Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2021 07:50
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 15924764 kB
MemAvailable 30669752 kB
Buffers 0 kB
Cached 15755160 kB
SwapCached 3876 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-12 07:49:44
DTS_END                         2021-10-12 07:49:44.2020
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/36756
TRN_ID                          238897
ATT_ID                          666
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: 9138.19 20692 1096583
customer order: creation 176.39 372 21167
customer order: refuse 15.38 333 1846
order to supplier: creation 47.13 1399 5655
order to supplier: removal 3.27 120902 392
invoice (draft): creation 48.37 3638 5804
invoice (draft): removal 49.60 1052 5952
invoice accept: apply 43.78 18228 5253
invoice accept: cancel 45.23 913 5428
customer reserve: creation 4010.25 180 481230
customer reserve: removal 4401.60 70 528192
realization accept: apply 85.78 189 10293
realization accept: cancel 77.49 139 9299
payment from customer: creation 61.97 127 7436
payment from customer: removal 6.10 84 732
payment to supplier: creation 50.22 165 6026
payment to supplier: removal 3.76 79 451
service: total inventory turnovers 4.08 3214 489
service: total monetary turnovers 6.96 478 835
service: aggregate perf. data 0.82 18662 98
service: refresh index statistics 0.04 243618 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 07:50:56 to 12-10-2021 07:50:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3155
TEST_TIME 2 4428
TEST_TIME 3 4587
TEST_TIME 4 4542
TEST_TIME 5 4886
TEST_TIME 6 4893
TEST_TIME 7 5194
TEST_TIME 8 5199
TEST_TIME 9 5457
TEST_TIME 10 5557
TEST_TIME 11 5797
TEST_TIME 12 5970
TEST_TIME 13 6011
TEST_TIME 14 6299
TEST_TIME 15 6518
TEST_TIME 16 6807
TEST_TIME 17 6874
TEST_TIME 18 6870
TEST_TIME 19 6973
TEST_TIME 20 7699
TEST_TIME 21 8535
TEST_TIME 22 8933
TEST_TIME 23 9540
TEST_TIME 24 10717
TEST_TIME 25 11506
TEST_TIME 26 11456
TEST_TIME 27 11392
TEST_TIME 28 11170
TEST_TIME 29 10852
TEST_TIME 30 10832
TEST_TIME 31 10660
TEST_TIME 32 10469
TEST_TIME 33 10344
TEST_TIME 34 10233
TEST_TIME 35 10256
TEST_TIME 36 10380
TEST_TIME 37 10202
TEST_TIME 38 10222
TEST_TIME 39 10136
TEST_TIME 40 10066
TEST_TIME 41 10032
TEST_TIME 42 10018
TEST_TIME 43 10141
TEST_TIME 44 10125
TEST_TIME 45 10255
TEST_TIME 46 10230
TEST_TIME 47 10266
TEST_TIME 48 10245
TEST_TIME 49 10177
TEST_TIME 50 10389
TEST_TIME 51 10357
TEST_TIME 52 10348
TEST_TIME 53 10199
TEST_TIME 54 10052
TEST_TIME 55 9997
TEST_TIME 56 9908
TEST_TIME 57 9832
TEST_TIME 58 9809
TEST_TIME 59 9711
TEST_TIME 60 9708
TEST_TIME 61 9698
TEST_TIME 62 9685
TEST_TIME 63 9698
TEST_TIME 64 9657
TEST_TIME 65 9693
TEST_TIME 66 9681
TEST_TIME 67 9708
TEST_TIME 68 9723
TEST_TIME 69 9802
TEST_TIME 70 9745
TEST_TIME 71 9777
TEST_TIME 72 9837
TEST_TIME 73 9827
TEST_TIME 74 9749
TEST_TIME 75 9643
TEST_TIME 76 9618
TEST_TIME 77 9553
TEST_TIME 78 9520
TEST_TIME 79 9473
TEST_TIME 80 9507
TEST_TIME 81 9512
TEST_TIME 82 9501
TEST_TIME 83 9482
TEST_TIME 84 9461
TEST_TIME 85 9466
TEST_TIME 86 9470
TEST_TIME 87 9492
TEST_TIME 88 9531
TEST_TIME 89 9511
TEST_TIME 90 9509
TEST_TIME 91 9520
TEST_TIME 92 9426
TEST_TIME 93 9451
TEST_TIME 94 9409
TEST_TIME 95 9365
TEST_TIME 96 9373
TEST_TIME 97 9339
TEST_TIME 98 9355
TEST_TIME 99 9348
TEST_TIME 100 9327
TEST_TIME 101 9324
TEST_TIME 102 9357
TEST_TIME 103 9344
TEST_TIME 104 9348
TEST_TIME 105 9397
TEST_TIME 106 9416
TEST_TIME 107 9384
TEST_TIME 108 9427
TEST_TIME 109 9453
TEST_TIME 110 9447
TEST_TIME 111 9364
TEST_TIME 112 9315
TEST_TIME 113 9304
TEST_TIME 114 9273
TEST_TIME 115 9216
TEST_TIME 116 9214
TEST_TIME 117 9180
TEST_TIME 118 9167
TEST_TIME 119 9176
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2021 07:50:59 to 12-10-2021 07:51:03.

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 1541111 1541111 0 0.00 0 47915 7 0 0
doc_list_biud 1541111 1541111 0 0.00 0 41965 31 0 0
sp_add_invoice_to_stock 5399 5253 146 2.70 17 220097 18229 0 146
sp_cancel_adding_invoice 5428 5428 0 0.00 5 81649 914 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 12704 334 0 0
sp_cancel_customer_reserve 528192 528192 0 0.00 1 41972 70 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 7438 85 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 3271 80 0 0
sp_cancel_supplier_invoice 5965 5952 13 0.21 1 19878 1052 0 13
sp_cancel_supplier_order 392 392 0 0.00 9 1299402 120903 0 0
sp_cancel_write_off 9299 9299 0 0.00 1 18672 140 0 0
sp_client_order 21167 21167 0 0.00 4 19057 373 0 0
sp_customer_reserve 481230 481230 0 0.00 0 94253 181 0 0
sp_fill_shopping_cart 32612 32612 0 0.00 1 30945 50 0 0
sp_fill_shopping_cart_clo_res 481222 481222 0 0.00 0 21108 17 0 0
sp_get_clo_for_invoice 5244 5244 0 0.00 0 30101 344 0 0
sp_kill_cost_storno 15910 15910 0 0.00 0 7840 63 0 0
sp_kill_qstorno_ret_qs2qd 1072027 1072027 0 0.00 0 41954 38 0 0
sp_kill_qty_storno 566646 566646 0 0.00 0 41964 82 0 0
sp_lock_selected_doc 21540 21540 0 0.00 0 9807 7 0 0
sp_make_cost_storno 28999 28999 0 0.00 0 47912 59 0 0
sp_multiply_rows_for_pdistr 28999 28999 0 0.00 0 8561 32 0 0
sp_multiply_rows_for_qdistr 21167 21167 0 0.00 0 17553 178 0 0
sp_pay_from_customer 7436 7436 0 0.00 4 19963 128 0 0
sp_pay_to_supplier 6026 6026 0 0.00 5 47926 166 0 0
sp_payment_common 13462 13462 0 0.00 1 19956 26 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 12698 292 0 0
sp_qd_handle_on_invoice_upd_sts 10672 10672 0 0.00 0 29200 374 0 0
sp_qd_handle_on_reserve_upd_sts 10293 10293 0 0.00 0 14488 59 0 0
sp_reserve_write_off 10293 10293 0 0.00 7 14900 189 0 0
sp_supplier_invoice 5804 5804 0 0.00 6 109827 3639 0 0
sp_supplier_order 5655 5655 0 0.00 5 30105 1400 0 0
srv_aggregate_perf_data 98 98 0 0.00 3336 180948 18662 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1169 173040 48728 0 0
srv_find_qd_qs_mism 1046564 1046564 0 0.00 0 38711 6 0 0
srv_make_invnt_saldo 489 489 0 0.00 57 89353 3214 0 0
srv_make_money_saldo 835 835 0 0.00 1 15022 479 0 0
srv_recalc_idx_stat 5 5 0 0.00 151282 481396 243618 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 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 5254 5254 0 0.00 0 10624 150 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 2980 71 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 3564 14 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 3695 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 2310 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 378 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 3697 40 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 23401 1338 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 699 4 0 0
t$perf-norm:sp_client_order 21167 21167 0 0.00 0 17631 15 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 7108 10 0 0
t$perf-norm:sp_pay_from_customer 7436 7436 0 0.00 0 14500 10 0 0
t$perf-norm:sp_pay_to_supplier 6026 6026 0 0.00 0 4729 9 0 0
t$perf-norm:sp_reserve_write_off 10293 10293 0 0.00 0 10820 10 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 4257 13 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 18854 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 332 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 105 3 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 916 12 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 6576 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10778 10486 292 2.70 0 7215 18 0 292
v_all_customers 38634 38634 0 0.00 0 22 1 0 0
v_all_suppliers 31822 31822 0 0.00 0 18 1 0 0
v_all_wares 42334 42334 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 384 6 0 0
v_cancel_client_order 3692 3692 0 0.00 0 6805 10 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 1762 7 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 291 3 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 3142 15 0 0
v_cancel_supplier_invoice 804 778 26 3.23 0 6815 22 0 26
v_cancel_supplier_order 784 784 0 0.00 0 3211 10 0 0
v_cancel_write_off 2108 2108 0 0.00 0 3825 16 0 0
v_max_id_clo_ord 5653 5653 0 0.00 0 2610 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 3384 16 0 0
v_max_id_ord_sup 5792 5792 0 0.00 0 13574 10 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 3676 4 0 0
v_max_non_paid_realizn 3930 3930 0 0.00 0 6507 5 0 0
v_min_id_clo_ord 5653 5653 0 0.00 0 1148 4 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 4379 11 0 0
v_min_id_ord_sup 5792 5792 0 0.00 0 7603 13 0 0
v_min_non_paid_invoice 6026 6026 0 0.00 0 7548 17 0 0
v_min_non_paid_realizn 7436 7436 0 0.00 0 19951 18 0 0
v_our_firm 10712 10712 0 0.00 0 18 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 6641 8 0 0
x_lock_depdocs_on_canc_invoice 10991 10991 0 0.00 0 14107 117 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 8155 288 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 2 30091 1322 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 3 109813 3441 0 0
x_make_qstorno_2100_3300 481230 481230 0 0.00 0 94245 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 07:51:04 to 12-10-2021 07:51:08.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:19:47 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:48 35049 8192000000 9738977296 10040750080 813983904 9.038 100 87 172 1 842779952 7538048 21258000
05:22:31 24627 8192000000 9782628928 10090897408 852799904 9.428 100 94 188 0 887710160 12562704 22347552
05:24:04 19899 8192000000 9816557040 10138992640 885874112 9.758 100 85 168 1 928657824 23761264 19022448
05:25:31 16211 8192000000 9885740304 10220302336 922610800 10.122 100 82 162 0 995417776 55587552 17219424
05:26:51 7982 8192000000 9935258512 10278150144 956104320 10.451 100 79 158 0 1039784160 67378240 16301600
05:28:02 5471 8192000000 9970792832 10325155840 996815376 10.848 100 82 164 0 1083515056 69799472 16900208
05:29:12 1751 8192000000 10005207472 10367504384 1028194656 11.151 100 92 182 1 1121209360 74829712 18184992
05:30:16 1548 8192000000 10017296336 10385170432 1057817136 11.436 100 88 177 1 1121655952 44790320 19048496
05:31:18 14867 8192000000 9999384832 10383749120 1069942992 11.552 100 66 131 1 1117582432 32826032 14813408
05:32:34 5837 8192000000 10003355648 10394976256 1072892624 11.580 100 47 92 1 1119919904 36852416 10174864
05:33:40 6377 8192000000 10011835728 10410229760 1079301120 11.641 100 46 93 0 1116237824 27074528 9862176
05:34:51 8993 8192000000 9992392240 10394882048 1084955216 11.695 100 22 45 1 1107926032 17819328 5151488
05:36:07 65431 8192000000 10000553600 10410881024 1093618144 11.777 100 27 54 2 1127192208 27948880 5625184
05:38:15 7926 8192000000 10022329488 10431938560 1102811456 11.864 100 30 59 2 1122428464 12717504 6899504
05:39:26 6833 8192000000 10038227072 10439716864 1103305712 11.869 100 42 84 1 1141970320 29989024 8675584
05:40:34 4456 8192000000 9980663584 10382024704 1055139824 11.410 97 28 56 0 1081052416 19363024 6549568
05:41:40 53793 8192000000 9941704752 10341609472 963608224 10.524 89 26 49 2 987308336 17964848 5735264
05:43:35 6437 8192000000 9949600752 10334392320 998339376 10.862 96 42 83 1 1026929440 19600368 8989696
05:44:43 9646 8192000000 9947397248 10335031296 1004465600 10.922 97 39 76 0 1037370880 24307840 8597440
05:45:53 3889 8192000000 9980758160 10364174336 1054874544 11.407 100 64 126 1 1100279520 30821392 14583584
05:46:59 44486 8192000000 9967608784 10377400320 1044778416 11.311 99 21 42 0 1074276112 24991728 4505968
05:48:47 4449 8192000000 10002819968 10389528576 1068124128 11.534 100 44 86 2 1118489456 40734896 9630432
05:49:52 47520 8192000000 10022254608 10418479104 1094331984 11.784 100 50 97 1 1119417104 14274560 10810560
05:51:46 69039 8192000000 10077923696 10481836032 1117790256 12.006 100 36 72 0 1190837536 65894192 7153088
05:54:01 12762 8192000000 10139826672 10541359104 1135392480 12.172 100 50 98 1 1243586080 98155744 10037856
05:55:19 18872 8192000000 10156062160 10566025216 1151777888 12.326 100 36 72 1 1260468864 100719328 7971648
05:56:42 6078 8192000000 10209792864 10612469760 1159651808 12.400 100 54 108 0 1308551296 137118416 11781072
05:57:59 16274 8192000000 10254599888 10680672256 1182179568 12.611 100 55 108 0 1350023824 155158768 12685488
05:59:19 2615 8192000000 10316246720 10727006208 1198180288 12.759 100 73 146 1 1419185712 204442576 16562848
06:00:25 5545 8192000000 10353230752 10767384576 1193652992 12.717 100 64 128 1 1456375280 247486016 15236272
06:01:33 2871 8192000000 10401304544 10813620224 1205114272 12.824 100 82 167 0 1506275552 282262240 18899040
06:02:41 3807 8192000000 10438647552 10853654528 1207550608 12.846 100 72 141 0 1545265184 321527808 16186768
06:03:50 3431 8192000000 10447937216 10851856384 1189443824 12.678 99 83 167 1 1546700672 337381232 19875616
06:04:57 3576 8192000000 10489503104 10895142912 1196882048 12.747 100 82 164 0 1587108416 370192432 20033936
06:06:06 1841 8192000000 10523168688 10921451520 1169946896 12.496 99 91 182 1 1606028480 414088896 21992688
06:07:13 1168 8192000000 10540922512 10943488000 1164540288 12.446 100 88 174 2 1634226016 447878288 21807440
06:08:20 1694 8192000000 10600388416 11009355776 1172607312 12.521 100 71 139 0 1699460000 508953232 17899456
06:09:24 7116 8192000000 10543321888 10995912704 1163344000 12.435 100 43 85 0 1637522112 462462832 11715280
06:10:36 4314 8192000000 10524821424 11010117632 1167143488 12.470 100 42 84 0 1646252416 467941616 11167312
06:11:52 24619 8192000000 10472109904 10963750912 1160955152 12.412 100 31 62 0 1588364576 418408256 9001168
06:13:23 35448 8192000000 10242630160 10805719040 1134513776 12.164 99 15 30 1 1400938640 262524720 3900144
06:15:00 76993 8192000000 10067582848 10535956480 1073888256 11.589 95 7 14 1 1135535744 60084368 1563120
06:17:22 97493 8192000000 10063572672 10482151424 1111181232 11.944 99 34 67 0 1156939776 39279232 6479312
06:20:23 125917 8192000000 10112334144 10525519872 1097201456 11.811 97 37 72 3 1138954944 34002256 7751232
06:23:30 4656 8192000000 10145166832 10563387392 1151967008 12.328 99 66 128 1 1235845744 70719072 13159664
06:24:37 114474 8192000000 10127058464 10600402944 1148950272 12.300 99 19 38 1 1257940608 104758112 4232224
06:27:32 16138 8192000000 10126423648 10569822208 1152383360 12.332 100 25 50 0 1228023552 69812096 5828096
06:28:55 40113 8192000000 10119901536 10595356672 1151587600 12.324 99 15 30 0 1227470576 72566848 3316128
06:30:36 50971 8192000000 10158387504 10628231168 1125165600 12.076 97 15 30 0 1234876624 106211520 3499504
06:32:31 30976 8192000000 10117273984 10558644224 1149891200 12.308 100 38 74 1 1205470208 47991184 7587824
06:34:36 61573 8192000000 10109346992 10588999680 1124710480 12.071 97 13 26 0 1210551632 82882752 2958400
06:36:38 33037 8192000000 10107618720 10551934976 1141089328 12.226 99 28 56 0 1178076064 31526656 5460080
06:38:12 64235 8192000000 10151006848 10627538944 1138097600 12.198 98 18 36 2 1203514224 61566240 3850384
06:40:35 63830 8192000000 10138225568 10578382848 1140712880 12.222 99 35 70 0 1213771680 65190544 7868256
06:42:44 116286 8192000000 10108449040 10545106944 1133463296 12.154 98 26 52 0 1179284208 40364640 5456272
06:45:40 56160 8192000000 10103259296 10572267520 1162448144 12.426 100 20 40 2 1234322416 66606480 5267792
06:47:37 57537 8192000000 10109629568 10568957952 1123662352 12.062 97 9 18 0 1195759648 70105808 1991488
06:49:41 53249 8192000000 10104676320 10582204416 1105734208 11.892 96 10 20 0 1183227120 75416576 2076336
06:51:39 37729 8192000000 10078504208 10530758656 1142742784 12.241 100 30 59 2 1184723344 35999824 5980736
06:53:19 70372 8192000000 10082453872 10554904576 1071082624 11.562 94 29 58 0 1131532720 54194448 6255648
06:55:34 56651 8192000000 10050623152 10506194944 1062115056 11.477 94 26 52 1 1125346784 58041904 5189824
06:57:34 95557 8192000000 10064042976 10560278528 1088528128 11.729 96 16 32 1 1151103024 59032688 3542208
07:00:10 68829 8192000000 10097847184 10574372864 1103880336 11.874 98 35 70 0 1186209840 75299696 7029808
07:02:21 105169 8192000000 10031340848 10477023232 1084816448 11.693 97 26 52 0 1118309088 28282272 5210368
07:05:29 55952 8192000000 10076337856 10539823104 1111783424 11.949 99 22 44 2 1198193184 81446656 4963104
07:07:36 38789 8192000000 10087976464 10555609088 1118956528 12.017 100 9 18 0 1204982464 84087872 1938064
07:09:15 55481 8192000000 10048300976 10530496512 1096564064 11.805 98 13 27 0 1169952448 70274992 3113392
07:11:12 56521 8192000000 10071606688 10510872576 1104441280 11.880 99 28 57 0 1157786992 47945232 5400480
07:13:09 58097 8192000000 10083877712 10515451904 1112931056 11.960 99 28 56 0 1172542448 53763600 5847792
07:15:15 37458 8192000000 10095674896 10530316288 1112910144 11.960 99 30 59 0 1169264704 50198528 6156032
07:16:52 51290 8192000000 10076294624 10539474944 1099044848 11.829 97 30 60 1 1161395536 56565008 5785680
07:18:49 14304 8192000000 10048788016 10471669760 1094096736 11.782 98 51 102 3 1125088720 19783776 11208208
07:20:10 133109 8192000000 10120335536 10561126400 1101973616 11.856 98 25 50 1 1210156160 102892304 5290240
07:23:37 46659 8192000000 10094191248 10598948864 1123283088 12.058 100 7 14 0 1201301552 76572672 1445792
07:25:24 65122 8192000000 10057644912 10521808896 1127366096 12.097 99 12 23 1 1166398352 36956528 2075728
07:27:29 71202 8192000000 10080843648 10526539776 1131195632 12.133 99 10 20 0 1162660976 29638576 1826768
07:29:42 50607 8192000000 10131932144 10575388672 1134523920 12.164 99 38 76 1 1225277760 82610512 8143328
07:31:33 65846 8192000000 10116483712 10541600768 1130380672 12.125 99 32 64 0 1194535104 57066320 7088112
07:33:43 97882 8192000000 10092683440 10568413184 1095718800 11.797 96 6 12 0 1166942832 69911600 1312432
07:36:21 22917 8192000000 10083185280 10496339968 1082092128 11.667 96 53 105 2 1135421280 43143008 10186144
07:38:19 173039 8192000000 10055991024 10517057536 1087563920 11.719 97 22 44 0 1119545520 27636832 4344768
07:43:06 40853 8192000000 10045852992 10507431936 1125190912 12.076 100 7 15 0 1156143280 29808032 1144336
07:44:47 79833 8192000000 10036475184 10519347200 1086090896 11.705 96 12 24 1 1125418368 36892784 2434688
07:47:08 50303 8192000000 10052363984 10504155136 1073268496 11.583 96 22 45 0 1114543584 36375584 4899504
07:49:05 38021 8192000000 10055962224 10490466304 1102802768 11.864 99 24 48 0 1156715968 49111856 4801344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-10-2021 07:51:10 to 12-10-2021 07:51:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 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 12-10-2021 07:51:25 to 12-10-2021 07:51: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          236335
MON$OLDEST_ACTIVE               239442
MON$OLDEST_SNAPSHOT             239442
MON$NEXT_TRANSACTION            239442
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                       2032416
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2628 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2628 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2628 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2628 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Oct 12 07:49:48 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

12.10.21 07:51: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-12 07:51:31 No severe PSQL-related problems occured
12.10.21 07:51:32. End of report.