Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.12.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 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 16644232 kB
MemAvailable 31489324 kB
Buffers 0 kB
Cached 15017088 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-03 13:37:08
DTS_END                         2021-12-03 13:37:08.9760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59622
TRN_ID                          228987
ATT_ID                          668
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: 9122.85 22428 1094742
customer order: creation 162.05 454 19446
customer order: refuse 13.47 338 1616
order to supplier: creation 42.36 1584 5083
order to supplier: removal 3.38 117073 405
invoice (draft): creation 43.66 3794 5239
invoice (draft): removal 53.93 889 6472
invoice accept: apply 39.79 18690 4775
invoice accept: cancel 48.92 828 5870
customer reserve: creation 3669.73 184 440368
customer reserve: removal 4764.88 65 571785
realization accept: apply 78.91 185 9469
realization accept: cancel 81.34 130 9761
payment from customer: creation 53.98 117 6478
payment from customer: removal 5.38 115 645
payment to supplier: creation 46.88 132 5625
payment to supplier: removal 3.35 67 402
service: total inventory turnovers 3.86 3314 463
service: total monetary turnovers 5.99 543 719
service: aggregate perf. data 0.98 15105 117
service: refresh index statistics 0.03 284957 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-12-2021 13:37:57 to 03-12-2021 13:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9721
TEST_TIME 2 6240
TEST_TIME 3 6803
TEST_TIME 4 6686
TEST_TIME 5 6372
TEST_TIME 6 6001
TEST_TIME 7 5763
TEST_TIME 8 5775
TEST_TIME 9 6202
TEST_TIME 10 6295
TEST_TIME 11 6616
TEST_TIME 12 6724
TEST_TIME 13 7167
TEST_TIME 14 7318
TEST_TIME 15 7471
TEST_TIME 16 7459
TEST_TIME 17 7553
TEST_TIME 18 7961
TEST_TIME 19 8317
TEST_TIME 20 8412
TEST_TIME 21 8803
TEST_TIME 22 9020
TEST_TIME 23 9468
TEST_TIME 24 9871
TEST_TIME 25 10421
TEST_TIME 26 11335
TEST_TIME 27 11561
TEST_TIME 28 11350
TEST_TIME 29 11055
TEST_TIME 30 11104
TEST_TIME 31 10971
TEST_TIME 32 10857
TEST_TIME 33 10666
TEST_TIME 34 10536
TEST_TIME 35 10389
TEST_TIME 36 10193
TEST_TIME 37 10039
TEST_TIME 38 10258
TEST_TIME 39 10131
TEST_TIME 40 10009
TEST_TIME 41 9944
TEST_TIME 42 9871
TEST_TIME 43 9791
TEST_TIME 44 9691
TEST_TIME 45 9603
TEST_TIME 46 9639
TEST_TIME 47 9572
TEST_TIME 48 9518
TEST_TIME 49 9533
TEST_TIME 50 9448
TEST_TIME 51 9544
TEST_TIME 52 9596
TEST_TIME 53 9477
TEST_TIME 54 9680
TEST_TIME 55 9764
TEST_TIME 56 9722
TEST_TIME 57 9719
TEST_TIME 58 9930
TEST_TIME 59 9833
TEST_TIME 60 9690
TEST_TIME 61 9659
TEST_TIME 62 9653
TEST_TIME 63 9612
TEST_TIME 64 9567
TEST_TIME 65 9549
TEST_TIME 66 9559
TEST_TIME 67 9565
TEST_TIME 68 9542
TEST_TIME 69 9514
TEST_TIME 70 9483
TEST_TIME 71 9600
TEST_TIME 72 9585
TEST_TIME 73 9573
TEST_TIME 74 9567
TEST_TIME 75 9557
TEST_TIME 76 9654
TEST_TIME 77 9612
TEST_TIME 78 9608
TEST_TIME 79 9491
TEST_TIME 80 9410
TEST_TIME 81 9474
TEST_TIME 82 9430
TEST_TIME 83 9418
TEST_TIME 84 9417
TEST_TIME 85 9354
TEST_TIME 86 9301
TEST_TIME 87 9285
TEST_TIME 88 9310
TEST_TIME 89 9287
TEST_TIME 90 9293
TEST_TIME 91 9346
TEST_TIME 92 9334
TEST_TIME 93 9363
TEST_TIME 94 9397
TEST_TIME 95 9380
TEST_TIME 96 9450
TEST_TIME 97 9491
TEST_TIME 98 9534
TEST_TIME 99 9540
TEST_TIME 100 9612
TEST_TIME 101 9568
TEST_TIME 102 9476
TEST_TIME 103 9427
TEST_TIME 104 9378
TEST_TIME 105 9320
TEST_TIME 106 9320
TEST_TIME 107 9295
TEST_TIME 108 9254
TEST_TIME 109 9215
TEST_TIME 110 9176
TEST_TIME 111 9158
TEST_TIME 112 9126
TEST_TIME 113 9118
TEST_TIME 114 9158
TEST_TIME 115 9150
TEST_TIME 116 9127
TEST_TIME 117 9148
TEST_TIME 118 9232
TEST_TIME 119 9199
TEST_TIME 120 9128

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-12-2021 13:38:01 to 03-12-2021 13:38:04.

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 1502310 1502310 0 0.00 0 44591 7 0 0
doc_list_biud 1502310 1502310 0 0.00 0 40877 31 0 0
sp_add_invoice_to_stock 4941 4775 166 3.35 25 218165 18690 0 166
sp_cancel_adding_invoice 5870 5870 0 0.00 5 91729 828 0 0
sp_cancel_client_order 1616 1616 0 0.00 4 14721 338 0 0
sp_cancel_customer_reserve 571785 571785 0 0.00 1 35400 66 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 10142 115 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 2278 68 0 0
sp_cancel_supplier_invoice 6476 6472 4 0.06 1 40887 890 0 4
sp_cancel_supplier_order 405 405 0 0.00 9 1165372 117073 0 0
sp_cancel_write_off 9761 9761 0 0.00 1 44594 130 0 0
sp_client_order 19446 19446 0 0.00 4 71470 455 0 0
sp_customer_reserve 440368 440368 0 0.00 0 58467 184 0 0
sp_fill_shopping_cart 29743 29743 0 0.00 1 10805 41 0 0
sp_fill_shopping_cart_clo_res 440353 440353 0 0.00 0 19659 15 0 0
sp_get_clo_for_invoice 4759 4759 0 0.00 0 32943 382 0 0
sp_kill_cost_storno 16678 16678 0 0.00 0 44589 65 0 0
sp_kill_qstorno_ret_qs2qd 1160208 1160208 0 0.00 0 40876 36 0 0
sp_kill_qty_storno 610134 610134 0 0.00 0 40877 76 0 0
sp_lock_selected_doc 19654 19654 0 0.00 0 9300 5 0 0
sp_make_cost_storno 26328 26328 0 0.00 0 14112 53 0 0
sp_multiply_rows_for_pdistr 26328 26328 0 0.00 0 8017 32 0 0
sp_multiply_rows_for_qdistr 19446 19446 0 0.00 0 71457 227 0 0
sp_pay_from_customer 6478 6478 0 0.00 4 10688 117 0 0
sp_pay_to_supplier 5625 5625 0 0.00 5 16588 133 0 0
sp_payment_common 12103 12103 0 0.00 1 9517 24 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 0 14714 304 0 0
sp_qd_handle_on_invoice_upd_sts 10626 10626 0 0.00 0 36340 363 0 0
sp_qd_handle_on_reserve_upd_sts 9469 9469 0 0.00 0 15741 61 0 0
sp_reserve_write_off 9469 9469 0 0.00 7 18439 185 0 0
sp_supplier_invoice 5239 5239 0 0.00 8 92578 3794 0 0
sp_supplier_order 5083 5083 0 0.00 5 49835 1584 0 0
srv_aggregate_perf_data 117 117 0 0.00 2440 332271 15105 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1020 109951 42669 0 0
srv_find_qd_qs_mism 1049666 1049666 0 0.00 0 11659 6 0 0
srv_make_invnt_saldo 463 463 0 0.00 20 111095 3315 0 0
srv_make_money_saldo 719 719 0 0.00 1 15436 543 0 0
srv_recalc_idx_stat 4 4 0 0.00 183420 401332 284957 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4775 4775 0 0.00 0 14524 164 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 6220 102 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 3563 16 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1907 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 1189 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2170 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 3655 29 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 17189 1510 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 4554 11 0 0
t$perf-norm:sp_client_order 19446 19446 0 0.00 0 13926 20 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 390 3 0 0
t$perf-norm:sp_pay_from_customer 6478 6478 0 0.00 0 4936 10 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 2615 8 0 0
t$perf-norm:sp_reserve_write_off 9469 9469 0 0.00 0 7340 11 0 0
t$perf-norm:sp_supplier_invoice 5239 5239 0 0.00 0 10949 25 0 0
t$perf-norm:sp_supplier_order 5083 5083 0 0.00 0 3201 13 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 358 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 334 7 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 2600 18 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 631 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9844 9512 332 3.37 0 7780 18 0 332
v_all_customers 34684 34684 0 0.00 0 32 1 0 0
v_all_suppliers 28944 28944 0 0.00 0 21 1 0 0
v_all_wares 38892 38892 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1105 10 0 0
v_cancel_client_order 3232 3232 0 0.00 0 5220 7 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 2697 9 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 610 3 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2032 7 0 0
v_cancel_supplier_invoice 774 766 8 1.03 0 600 14 0 8
v_cancel_supplier_order 810 810 0 0.00 0 718 6 0 0
v_cancel_write_off 1892 1892 0 0.00 0 4062 19 0 0
v_max_id_clo_ord 5083 5083 0 0.00 0 1093 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 3947 14 0 0
v_max_id_ord_sup 5213 5213 0 0.00 0 6826 4 0 0
v_max_non_paid_invoice 1449 1449 0 0.00 0 1482 4 0 0
v_max_non_paid_realizn 3638 3638 0 0.00 0 4300 5 0 0
v_min_id_clo_ord 5083 5083 0 0.00 0 2323 9 0 0
v_min_id_clo_res 967 967 0 0.00 0 2761 9 0 0
v_min_id_ord_sup 5213 5213 0 0.00 0 2248 3 0 0
v_min_non_paid_invoice 5625 5625 0 0.00 0 9514 16 0 0
v_min_non_paid_realizn 6478 6478 0 0.00 0 6622 17 0 0
v_our_firm 9888 9888 0 0.00 0 86 1 0 0
v_reserve_write_off 18938 18938 0 0.00 0 5220 6 0 0
x_lock_depdocs_on_canc_invoice 11959 11959 0 0.00 0 18451 101 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 16328 418 0 0
x_make_qstorno_1000_1200 5083 5083 0 0.00 2 49830 1506 0 0
x_make_qstorno_1200_2000 5239 5239 0 0.00 3 92552 3635 0 0
x_make_qstorno_2100_3300 440368 440368 0 0.00 0 58465 166 0 0

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

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:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 21523 8192000000 9706093984 10000461824 789062656 8.785 100 84 168 1 816603392 7462432 20078304
11:09:50 27422 8192000000 9765572400 10072039424 841004928 9.310 100 89 178 0 876122208 14187808 20929472
11:11:24 17965 8192000000 9813262352 10135875584 876122416 9.661 100 83 165 0 918734496 23984592 18627488
11:12:47 13345 8192000000 9882723584 10216505344 924205104 10.138 100 90 180 1 994668944 50916912 19546928
11:14:03 7750 8192000000 9940886016 10282831872 955077472 10.441 100 81 162 0 1049198112 76959568 17161072
11:15:13 4615 8192000000 9957750320 10315583488 992874112 10.809 100 74 146 1 1071878304 63765040 15239152
11:16:19 3303 8192000000 9984193200 10355056640 1026355680 11.133 100 73 146 0 1102749024 60266896 16126448
11:17:25 2248 8192000000 10043351200 10414579712 1053323696 11.393 100 91 180 0 1153804944 82499584 17981664
11:18:29 16879 8192000000 10028251952 10410065920 1069097360 11.543 100 66 133 2 1150419360 67903888 13418112
11:19:47 5004 8192000000 10006963968 10398089216 1084228240 11.688 100 58 117 3 1118012976 21790256 11994480
11:20:54 7267 8192000000 10016599552 10417516544 1090972672 11.752 100 28 56 1 1132262256 35395632 5893952
11:22:02 8520 8192000000 10025680432 10436718592 1096851936 11.808 100 25 50 0 1129952480 27950288 5150256
11:23:11 17479 8192000000 10024518848 10414022656 1102089312 11.857 100 70 143 3 1129627264 11484608 16053344
11:24:29 6608 8192000000 10046230352 10449334272 1102730752 11.864 100 51 103 0 1143722336 30344384 10647200
11:25:38 6041 8192000000 10045523296 10446737408 1106855296 11.903 100 51 101 2 1145399760 26253872 12290592
11:26:49 4538 8192000000 9991900800 10387214336 1063240832 11.487 97 42 82 2 1095762432 22729376 9792224
11:27:55 12754 8192000000 10005710160 10409291776 1078782272 11.636 99 49 96 1 1108589680 19170000 10637408
11:29:11 5320 8192000000 9978030368 10357202944 1021290336 11.084 97 45 90 0 1065458464 35152896 9015232
11:30:21 7795 8192000000 9945229088 10329636864 1028172624 11.151 99 45 82 4 1063812800 26283984 9356192
11:31:32 63994 8192000000 9934187344 10322264064 995507776 10.835 97 35 70 1 1023072032 20099632 7464624
11:33:36 3273 8192000000 9953216432 10336800768 1022055056 11.092 98 51 102 1 1057088448 24230560 10802832
11:34:40 7846 8192000000 9959722704 10358415360 1053923776 11.398 100 35 70 0 1073923984 12234224 7765984
11:35:49 6878 8192000000 9985932512 10385911808 1067180016 11.525 100 29 58 0 1103159712 30158144 5821552
11:36:57 43146 8192000000 10000981776 10390859776 1077805264 11.627 100 48 94 0 1119094880 30519232 10770384
11:38:46 3538 8192000000 10023347264 10422632448 1102692128 11.863 100 50 100 2 1139707456 26091136 10924192
11:39:53 5898 8192000000 10073994048 10472660992 1122204048 12.048 100 50 99 1 1186336064 53961904 10170112
11:41:07 5746 8192000000 10114502064 10515836928 1126312496 12.087 99 55 104 1 1217578544 80123760 11142288
11:42:15 6561 8192000000 10138273840 10543140864 1140870448 12.224 100 51 102 0 1254474336 102539088 11064800
11:43:25 29709 8192000000 10187455936 10596708352 1151913280 12.327 100 51 102 2 1282402640 119789792 10699568
11:45:07 11929 8192000000 10208094848 10631053312 1161683552 12.419 100 42 84 1 1329437440 157640160 10113728
11:46:21 8492 8192000000 10245736752 10669064192 1160430912 12.407 99 62 123 0 1355871184 181398720 14041552
11:47:34 2145 8192000000 10258661504 10674597888 1166357952 12.463 99 62 124 1 1365690432 185968752 13363728
11:48:39 3818 8192000000 10301794512 10711044096 1168201024 12.480 100 74 146 0 1406414560 222384512 15829024
11:49:46 1734 8192000000 10340935840 10742886400 1146378144 12.275 99 82 162 0 1434108080 269749472 17980464
11:50:50 2073 8192000000 10326570704 10751565824 1165213728 12.452 100 66 132 0 1425924560 245332080 15378752
11:51:56 2584 8192000000 10345166704 10746396672 1151532048 12.324 100 84 165 0 1446071744 275006464 19533232
11:53:03 1018 8192000000 10363086544 10776559616 1159509920 12.399 100 75 149 0 1464466576 287572800 17383856
11:54:08 2772 8192000000 10408466992 10808238080 1158723120 12.391 100 84 163 0 1511523136 333536480 19263536
11:55:15 1180 8192000000 10439349520 10864209920 1156150208 12.367 100 78 156 0 1539101984 364302976 18648800
11:56:20 6282 8192000000 10417779808 10856816640 1134658752 12.165 99 61 122 0 1503230160 353100000 15471408
11:57:27 9198 8192000000 10434234448 10873360384 1135731808 12.175 99 42 84 1 1537990512 391091616 11167088
11:58:46 13144 8192000000 10437481696 10868957184 1135906560 12.177 99 44 88 0 1550954800 402716176 12332064
12:00:12 10576 8192000000 10445409488 10892873728 1133108624 12.151 100 31 62 0 1552601952 410577504 8915824
12:01:31 20424 8192000000 10370202448 10901938176 1109396640 11.927 99 23 46 2 1491811152 375705040 6709472
12:03:10 26380 8192000000 10267431520 10699313152 1113409536 11.965 100 22 43 0 1365396448 246618672 5368240
12:04:37 109951 8192000000 10104420288 10548183040 1114144256 11.972 99 36 71 2 1190612208 67885760 8582192
12:07:35 56056 8192000000 10058618480 10501980160 1109716288 11.930 98 19 36 3 1152906256 39081984 4107984
12:09:36 53166 8192000000 10065022080 10508820480 1115951520 11.989 98 18 36 0 1143944976 24426416 3567040
12:11:33 23512 8192000000 10093006784 10556043264 1129202688 12.114 99 7 13 0 1187561216 57313328 1045200
12:13:25 41662 8192000000 10115082816 10556563456 1136827184 12.186 100 21 42 0 1221412416 79590272 4994960
12:15:36 68229 8192000000 10065192992 10578755584 1099751856 11.835 97 16 32 0 1150413984 47408368 3253760
12:17:47 26724 8192000000 10069680496 10504462336 1131836704 12.139 100 31 62 0 1177303184 38858656 6607824
12:19:19 38544 8192000000 10084710864 10537897984 1140783168 12.223 100 10 20 0 1186606624 43758112 2065344
12:21:13 99732 8192000000 10094659232 10562666496 1146296016 12.275 100 15 30 1 1209691024 60102000 3293008
12:23:53 64513 8192000000 10060879600 10509651968 1125130224 12.075 98 12 25 1 1150058976 22430864 2497888
12:26:03 40804 8192000000 10079900928 10523189248 1135176288 12.170 99 19 38 0 1189931344 49887760 4867296
12:28:00 43928 8192000000 10090649888 10529456128 1153959024 12.347 100 19 35 1 1227571424 69159312 4453088
12:29:44 102547 8192000000 10177203568 10661871616 1135922000 12.177 98 13 26 1 1245741248 107172128 2647120
12:32:34 66534 8192000000 10125006944 10573197312 1101771312 11.854 95 24 48 0 1152206720 45403952 5031456
12:34:43 88436 8192000000 10092435696 10575478784 1105936272 11.894 97 11 22 0 1232550352 124181728 2432352
12:37:16 77320 8192000000 10115551488 10559115264 1143807504 12.251 100 36 71 2 1191127712 40318480 7001728
12:39:34 25149 8192000000 10092847088 10523541504 1138583872 12.202 99 26 52 1 1196933840 53282544 5067424
12:41:03 71169 8192000000 10050950464 10516832256 1089529472 11.738 96 14 28 1 1162727856 70290432 2907952
12:43:25 70128 8192000000 10044278768 10502111232 1080443696 11.652 95 15 28 1 1104522272 21057376 3021200
12:45:37 28924 8192000000 10068421600 10500530176 1104117776 11.877 97 42 84 2 1147404272 34521808 8764688
12:47:08 43464 8192000000 10124628208 10577457152 1087196576 11.716 96 23 46 0 1166296336 74328256 4771504
12:48:52 58669 8192000000 10042498624 10477670400 1068152208 11.534 94 28 56 1 1107938848 33940112 5846528
12:51:09 53524 8192000000 10101444096 10565140480 1126002560 12.084 100 37 74 0 1210187936 76899056 7286320
12:53:05 74461 8192000000 10041911008 10477072384 1106892352 11.903 98 15 30 0 1172862208 63087920 2881936
12:55:20 36786 8192000000 10097186624 10554904576 1133353488 12.153 100 39 78 2 1199653216 57975968 8323760
12:56:59 85393 8192000000 10093730288 10542047232 1130145696 12.123 99 34 68 1 1232921232 95405312 7370224
12:59:26 73867 8192000000 10085875024 10566725632 1120566992 12.032 98 9 18 0 1178821872 56482928 1771952
13:01:40 52243 8192000000 10071579536 10525728768 1119256720 12.020 98 11 20 1 1162829472 41620608 1952144
13:03:42 60462 8192000000 10083424640 10542604288 1113124704 11.962 98 8 16 0 1166883024 51975376 1782944
13:05:43 46657 8192000000 10084270736 10517700608 1130358000 12.125 100 20 40 0 1179818896 45279136 4181760
13:07:41 46076 8192000000 10079099072 10555928576 1122576432 12.051 99 19 39 0 1171942336 45311120 4054784
13:09:29 20317 8192000000 10104597744 10531328000 1122095520 12.047 98 42 82 1 1170767712 40635824 8036368
13:10:49 44478 8192000000 10075051904 10508902400 1119659664 12.024 98 20 40 2 1184544192 61121520 3763008
13:12:34 35361 8192000000 10117807328 10533281792 1107470960 11.908 97 40 80 0 1180923008 64883072 8568976
13:14:11 62298 8192000000 10124971264 10619424768 1130243184 12.124 99 21 42 0 1229378848 94708208 4427456
13:16:20 77470 8192000000 10088970576 10607198208 1129919248 12.121 99 9 18 0 1213047760 81265680 1862832
13:19:29 91876 8192000000 10054475984 10503790592 1126847952 12.092 98 21 42 0 1150556800 19337984 4370864
13:22:19 78301 8192000000 10102021968 10573844480 1143776976 12.251 99 15 31 0 1200647168 53361440 3508752
13:24:38 52172 8192000000 10065380352 10520657920 1121511216 12.041 98 15 29 1 1153108400 28566144 3031040
13:26:31 57980 8192000000 10089301232 10558595072 1153108848 12.339 100 28 56 0 1197418464 38044288 6265328
13:29:01 49927 8192000000 10101712512 10545864704 1145303168 12.265 100 19 38 1 1178198288 28915776 3979344
13:30:56 71627 8192000000 10106318480 10617790464 1109786016 11.930 98 25 50 1 1206068624 90666192 5616416
13:33:19 67836 8192000000 10120793040 10628247552 1131736320 12.138 99 26 52 0 1229821312 92265280 5819712
13:35:30 59388 8192000000 10061525728 10489896960 1109217696 11.925 98 22 44 2 1156267712 42011552 5038464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-12-2021 13:38:11 to 03-12-2021 13:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-12-2021 13:38:27 to 03-12-2021 13:38:29.

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          227296
MON$OLDEST_ACTIVE               229114
MON$OLDEST_SNAPSHOT             229114
MON$NEXT_TRANSACTION            229114
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                       2042336
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2673 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2673 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2673 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2673 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Fri Dec  3 13:37:12 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Fri Dec  3 13:37:12 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Dec  3 13:37:13 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

03.12.21 13:38:30. 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-03 13:38:32 No severe PSQL-related problems occured
03.12.21 13:38:33. End of report.