Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2021 10:38
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 15843856 kB
MemAvailable 30837804 kB
Buffers 0 kB
Cached 15897312 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-08 10:37:10
DTS_END                         2021-11-08 10:37:10.1270
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/36020
TRN_ID                          237259
ATT_ID                          608
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: 9187.07 18311 1102448
customer order: creation 176.13 434 21135
customer order: refuse 14.74 327 1769
order to supplier: creation 45.04 1447 5405
order to supplier: removal 3.59 110986 431
invoice (draft): creation 46.68 3328 5601
invoice (draft): removal 53.64 997 6437
invoice accept: apply 41.53 16878 4984
invoice accept: cancel 48.28 776 5794
customer reserve: creation 3772.08 169 452649
customer reserve: removal 4692.21 67 563065
realization accept: apply 85.64 189 10277
realization accept: cancel 77.79 127 9335
payment from customer: creation 60.87 100 7304
payment from customer: removal 5.78 103 694
payment to supplier: creation 48.37 120 5804
payment to supplier: removal 3.49 69 419
service: total inventory turnovers 4.08 3322 490
service: total monetary turnovers 6.31 471 757
service: aggregate perf. data 0.78 17283 94
service: refresh index statistics 0.03 209024 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2021 10:38:56 to 08-11-2021 10:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6689
TEST_TIME 2 5475
TEST_TIME 3 5378
TEST_TIME 4 5024
TEST_TIME 5 4978
TEST_TIME 6 4937
TEST_TIME 7 4839
TEST_TIME 8 5428
TEST_TIME 9 5694
TEST_TIME 10 5791
TEST_TIME 11 6263
TEST_TIME 12 6657
TEST_TIME 13 6732
TEST_TIME 14 7225
TEST_TIME 15 7663
TEST_TIME 16 7804
TEST_TIME 17 7846
TEST_TIME 18 7764
TEST_TIME 19 8239
TEST_TIME 20 8287
TEST_TIME 21 8423
TEST_TIME 22 9048
TEST_TIME 23 10013
TEST_TIME 24 11265
TEST_TIME 25 11513
TEST_TIME 26 11757
TEST_TIME 27 11683
TEST_TIME 28 11520
TEST_TIME 29 11257
TEST_TIME 30 10964
TEST_TIME 31 10901
TEST_TIME 32 10967
TEST_TIME 33 10754
TEST_TIME 34 10697
TEST_TIME 35 10575
TEST_TIME 36 10611
TEST_TIME 37 10527
TEST_TIME 38 10494
TEST_TIME 39 10514
TEST_TIME 40 10382
TEST_TIME 41 10221
TEST_TIME 42 10367
TEST_TIME 43 10328
TEST_TIME 44 10364
TEST_TIME 45 10277
TEST_TIME 46 10437
TEST_TIME 47 10356
TEST_TIME 48 10460
TEST_TIME 49 10545
TEST_TIME 50 10500
TEST_TIME 51 10506
TEST_TIME 52 10536
TEST_TIME 53 10506
TEST_TIME 54 10454
TEST_TIME 55 10299
TEST_TIME 56 10184
TEST_TIME 57 10157
TEST_TIME 58 10056
TEST_TIME 59 10025
TEST_TIME 60 9929
TEST_TIME 61 9802
TEST_TIME 62 9794
TEST_TIME 63 9907
TEST_TIME 64 9955
TEST_TIME 65 9941
TEST_TIME 66 9957
TEST_TIME 67 10007
TEST_TIME 68 9929
TEST_TIME 69 9942
TEST_TIME 70 9978
TEST_TIME 71 9948
TEST_TIME 72 9941
TEST_TIME 73 10064
TEST_TIME 74 10107
TEST_TIME 75 9993
TEST_TIME 76 9911
TEST_TIME 77 9857
TEST_TIME 78 9885
TEST_TIME 79 9823
TEST_TIME 80 9772
TEST_TIME 81 9704
TEST_TIME 82 9617
TEST_TIME 83 9599
TEST_TIME 84 9571
TEST_TIME 85 9568
TEST_TIME 86 9576
TEST_TIME 87 9610
TEST_TIME 88 9619
TEST_TIME 89 9623
TEST_TIME 90 9581
TEST_TIME 91 9596
TEST_TIME 92 9647
TEST_TIME 93 9651
TEST_TIME 94 9671
TEST_TIME 95 9677
TEST_TIME 96 9629
TEST_TIME 97 9589
TEST_TIME 98 9528
TEST_TIME 99 9519
TEST_TIME 100 9476
TEST_TIME 101 9443
TEST_TIME 102 9388
TEST_TIME 103 9375
TEST_TIME 104 9363
TEST_TIME 105 9339
TEST_TIME 106 9331
TEST_TIME 107 9335
TEST_TIME 108 9322
TEST_TIME 109 9297
TEST_TIME 110 9349
TEST_TIME 111 9438
TEST_TIME 112 9477
TEST_TIME 113 9464
TEST_TIME 114 9467
TEST_TIME 115 9399
TEST_TIME 116 9357
TEST_TIME 117 9343
TEST_TIME 118 9296
TEST_TIME 119 9261
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-11-2021 10:38:59 to 08-11-2021 10:39: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 1523354 1523354 0 0.00 0 27108 6 0 0
doc_list_biud 1523354 1523354 0 0.00 0 108662 31 0 0
sp_add_invoice_to_stock 5057 4984 73 1.44 38 198941 16878 0 73
sp_cancel_adding_invoice 5794 5794 0 0.00 6 62034 777 0 0
sp_cancel_client_order 1769 1769 0 0.00 5 9258 327 0 0
sp_cancel_customer_reserve 563065 563065 0 0.00 1 108666 67 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 11426 104 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 2605 70 0 0
sp_cancel_supplier_invoice 6447 6437 10 0.15 2 25248 998 0 10
sp_cancel_supplier_order 431 431 0 0.00 8 1314981 110986 0 0
sp_cancel_write_off 9335 9335 0 0.00 1 16313 128 0 0
sp_client_order 21135 21135 0 0.00 4 22982 434 0 0
sp_customer_reserve 452649 452649 0 0.00 0 71862 170 0 0
sp_fill_shopping_cart 32114 32114 0 0.00 1 14051 40 0 0
sp_fill_shopping_cart_clo_res 452634 452634 0 0.00 0 20316 17 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 31064 365 0 0
sp_kill_cost_storno 16242 16242 0 0.00 0 11058 58 0 0
sp_kill_qstorno_ret_qs2qd 1142333 1142333 0 0.00 0 108641 37 0 0
sp_kill_qty_storno 602077 602077 0 0.00 0 108662 78 0 0
sp_lock_selected_doc 21061 21061 0 0.00 0 6896 6 0 0
sp_make_cost_storno 28354 28354 0 0.00 0 17876 51 0 0
sp_multiply_rows_for_pdistr 28354 28354 0 0.00 0 9991 31 0 0
sp_multiply_rows_for_qdistr 21135 21135 0 0.00 0 13761 238 0 0
sp_pay_from_customer 7304 7304 0 0.00 4 10775 100 0 0
sp_pay_to_supplier 5804 5804 0 0.00 5 12644 121 0 0
sp_payment_common 13108 13108 0 0.00 1 9694 22 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 9250 290 0 0
sp_qd_handle_on_invoice_upd_sts 10763 10763 0 0.00 0 19642 317 0 0
sp_qd_handle_on_reserve_upd_sts 10277 10277 0 0.00 0 16934 60 0 0
sp_reserve_write_off 10277 10277 0 0.00 7 16965 190 0 0
sp_supplier_invoice 5601 5601 0 0.00 7 82108 3328 0 0
sp_supplier_order 5405 5405 0 0.00 6 43180 1448 0 0
srv_aggregate_perf_data 94 94 0 0.00 3150 152764 17284 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 3670 173406 45744 0 0
srv_find_qd_qs_mism 1055623 1055623 0 0.00 0 27107 5 0 0
srv_make_invnt_saldo 490 490 0 0.00 61 88771 3322 0 0
srv_make_money_saldo 757 757 0 0.00 1 15974 471 0 0
srv_recalc_idx_stat 4 4 0 0.00 111965 263303 209025 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 16893 137 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 13466 141 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 1826 9 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 2409 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 2061 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2233 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1490 20 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 18577 1429 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2761 10 0 0
t$perf-norm:sp_client_order 21135 21135 0 0.00 0 13417 19 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 4044 12 0 0
t$perf-norm:sp_pay_from_customer 7304 7304 0 0.00 0 7896 12 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 4345 9 0 0
t$perf-norm:sp_reserve_write_off 10277 10277 0 0.00 0 6336 8 0 0
t$perf-norm:sp_supplier_invoice 5601 5601 0 0.00 0 6875 15 0 0
t$perf-norm:sp_supplier_order 5405 5405 0 0.00 0 7650 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 186 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 431 9 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 1968 9 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3816 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 6 0 0
v_add_invoice_to_stock 10080 9934 146 1.44 0 10560 17 0 146
v_all_customers 38398 38398 0 0.00 0 14 1 0 0
v_all_suppliers 30112 30112 0 0.00 0 20 1 0 0
v_all_wares 42270 42270 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 4666 15 0 0
v_cancel_client_order 3538 3538 0 0.00 0 6100 7 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2285 7 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 6100 9 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 863 4 0 0
v_cancel_supplier_invoice 794 774 20 2.51 0 817 11 0 20
v_cancel_supplier_order 862 862 0 0.00 0 333 3 0 0
v_cancel_write_off 2002 2002 0 0.00 0 7402 21 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 1279 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 2638 21 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 5347 6 0 0
v_max_non_paid_invoice 1727 1727 0 0.00 0 884 3 0 0
v_max_non_paid_realizn 3963 3963 0 0.00 0 1297 3 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 2260 6 0 0
v_min_id_clo_res 942 942 0 0.00 0 6605 12 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 4960 7 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 6507 15 0 0
v_min_non_paid_realizn 7304 7304 0 0.00 0 9190 18 0 0
v_our_firm 10554 10554 0 0.00 0 12 1 0 0
v_reserve_write_off 20554 20554 0 0.00 0 3909 5 0 0
x_lock_depdocs_on_canc_invoice 11844 11844 0 0.00 0 17822 110 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 8422 290 0 0
x_make_qstorno_1000_1200 5405 5405 0 0.00 2 43069 1378 0 0
x_make_qstorno_1200_2000 5601 5601 0 0.00 4 81683 3173 0 0
x_make_qstorno_2100_3300 452649 452649 0 0.00 0 71826 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2021 10:39:04 to 08-11-2021 10:39: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
08:07:13 4 8192000000 8798084560 8809140224 4179744 0.050 2 1 2 0 4279136 63616 35776
08:08:14 19295 8192000000 9721307216 10014466048 790659920 8.802 100 87 175 0 819356784 7454768 21242096
08:09:38 14637 8192000000 9751393088 10055917568 828769600 9.187 100 86 173 0 860688688 11278576 20640512
08:11:03 23329 8192000000 9795872800 10111959040 860409824 9.504 100 84 168 0 902231616 21807584 20014208
08:12:33 16192 8192000000 9869626848 10205646848 920678992 10.103 100 75 150 1 981530736 44262720 16589024
08:13:58 11641 8192000000 9929512064 10275819520 973948912 10.625 100 85 170 0 1043362656 51168944 18244800
08:15:15 4351 8192000000 9980029808 10337189888 999804656 10.877 100 83 166 1 1096179456 79450400 16924400
08:16:21 1830 8192000000 9997492880 10360569856 1026920752 11.139 100 91 182 0 1107450336 61894400 18635184
08:17:24 1467 8192000000 10036963584 10402922496 1049165680 11.353 100 96 190 0 1142361072 74604288 18591104
08:18:27 9232 8192000000 9973326864 10370277376 1060779280 11.464 100 49 98 2 1098718992 27229184 10710528
08:19:37 6843 8192000000 9978475104 10373746688 1071047264 11.562 100 29 58 0 1104844336 27125520 6671552
08:20:45 6958 8192000000 9988900240 10386337792 1075839072 11.608 100 34 68 0 1118920240 36013088 7068080
08:21:54 13744 8192000000 10028085360 10434514944 1079778880 11.645 100 38 76 1 1121577888 34673232 7125776
08:23:09 6128 8192000000 10008051616 10409312256 1086253936 11.707 100 51 101 4 1121408480 23761120 11393424
08:24:19 10073 8192000000 10001971280 10417422336 1091297952 11.755 100 40 78 1 1116358144 15584640 9475552
08:25:29 6874 8192000000 10008749872 10413514752 1096231152 11.802 100 23 45 1 1125854144 25222672 4400320
08:26:37 27282 8192000000 9991591008 10392600576 1059153072 11.448 97 35 71 3 1083810960 16774736 7883152
08:28:04 5039 8192000000 9986017728 10375561216 1053092128 11.390 99 54 108 2 1079798256 14101472 12604656
08:29:11 4451 8192000000 9980343232 10358755328 1040719648 11.272 100 41 84 2 1093703952 43933408 9050896
08:30:16 7035 8192000000 9934751968 10320101376 990441248 10.786 96 35 70 1 1031151120 32675056 8034816
08:31:27 36445 8192000000 9922092880 10310287360 964955744 10.537 95 22 44 0 990981264 21470592 4554928
08:33:07 8444 8192000000 9973391600 10374807552 1035480736 11.221 100 51 102 1 1074462128 27229600 11751792
08:34:16 8894 8192000000 9985547168 10372325376 1040133504 11.266 99 43 85 0 1068599888 18657568 9808816
08:35:26 82989 8192000000 9991735680 10387845120 1064474400 11.499 100 44 85 0 1087574080 12212416 10887264
08:37:55 23664 8192000000 10024255440 10418212864 1093614544 11.777 100 54 106 1 1132441984 27294816 11532624
08:39:32 19983 8192000000 10085550528 10484637696 1119705440 12.024 100 56 110 2 1179992512 47751664 12535408
08:40:59 7547 8192000000 10118046256 10514157568 1131576368 12.136 100 45 89 3 1223730608 80169088 11985152
08:42:14 16809 8192000000 10191872528 10600148992 1146865936 12.280 100 49 98 0 1306039376 147461168 11712272
08:43:46 5332 8192000000 10208951072 10613895168 1152548176 12.333 100 54 107 1 1316806704 152390960 11867568
08:44:57 6728 8192000000 10245427312 10665529344 1158136480 12.386 100 55 110 1 1356097232 185801728 12159024
08:46:08 5261 8192000000 10261139696 10674028544 1165971472 12.459 100 54 108 0 1366351760 187190912 13189376
08:47:17 3669 8192000000 10298719504 10706780160 1175700320 12.550 100 63 123 0 1400337472 211522560 13114592
08:48:24 4230 8192000000 10365304640 10774482944 1186965344 12.655 100 73 144 2 1462967776 260334928 15667504
08:49:31 4971 8192000000 10375822896 10788618240 1172581984 12.521 100 62 118 3 1482647536 295529520 14536032
08:50:39 6206 8192000000 10392977856 10814074880 1166689280 12.466 100 62 121 1 1492666080 311240304 14736496
08:51:50 7046 8192000000 10417490512 10834116608 1177799360 12.570 100 63 125 1 1514109072 320488848 15820864
08:53:02 8758 8192000000 10414284000 10830675968 1152894160 12.337 99 52 102 0 1518764768 353158736 12711872
08:54:17 14371 8192000000 10453428032 10886651904 1167526224 12.474 99 40 80 0 1548147424 370141888 10479312
08:55:36 16222 8192000000 10497778992 10927411200 1140833424 12.223 98 41 82 0 1574934144 423437520 10663200
08:56:58 18493 8192000000 10466227696 10899918848 1130958400 12.130 99 33 66 1 1556737728 416731904 9047424
08:58:24 18101 8192000000 10482075872 10923466752 1115886080 11.988 100 32 62 1 1574679568 450271840 8521648
08:59:47 11889 8192000000 10388325840 10854572032 1104707264 11.882 100 31 62 0 1510569760 396965856 8896640
09:01:03 17702 8192000000 10275880624 10770878464 1109574112 11.928 100 30 58 0 1402698960 285709680 7415168
09:02:43 51584 8192000000 10052586688 10605293568 1068196160 11.535 97 7 14 1 1155347936 85738512 1413264
09:04:37 57166 8192000000 10028733056 10479493120 1080261376 11.650 98 35 71 3 1126053568 38189472 7602720
09:06:36 81027 8192000000 10081636272 10508124160 1108105696 11.914 99 41 82 1 1162948656 46701472 8141488
09:09:00 80868 8192000000 10074878304 10518175744 1121403504 12.040 99 19 38 1 1168679536 43917168 3358864
09:11:27 5711 8192000000 10104395520 10526670848 1127102720 12.094 100 56 110 1 1196919552 58067920 11748912
09:12:43 16589 8192000000 10124448288 10540941312 1123886944 12.064 99 31 61 1 1199983904 69106064 6990896
09:14:02 26913 8192000000 10102795888 10543353856 1120473440 12.031 100 27 52 0 1220633536 94499328 5660768
09:15:29 47317 8192000000 10105697152 10609008640 1133087920 12.150 100 18 36 0 1236969376 99504416 4377040
09:17:17 41064 8192000000 10119727264 10569060352 1127703680 12.100 99 10 20 1 1193883872 63805504 2374688
09:19:15 45180 8192000000 10077657392 10516819968 1124025312 12.065 99 12 24 0 1175628368 48962896 2640160
09:21:05 31772 8192000000 10131303472 10603417600 1143141792 12.245 100 44 88 0 1229563776 77518704 8903280
09:22:55 49271 8192000000 10087272048 10532761600 1135124112 12.170 99 26 51 2 1236473536 95163360 6186064
09:24:44 68086 8192000000 10114987600 10569125888 1137008624 12.187 99 18 36 0 1192220832 51123328 4088880
09:26:55 48828 8192000000 10090350336 10515525632 1112338912 11.955 97 40 77 0 1155168544 34060144 8769488
09:28:46 50049 8192000000 10102314656 10556317696 1128834928 12.110 99 20 40 0 1189104640 56252528 4017184
09:30:42 173405 8192000000 10042752800 10485329920 1106145968 11.896 97 27 53 0 1133698672 22221280 5331424
09:34:42 74061 8192000000 10090015984 10549452800 1119969776 12.027 98 19 38 0 1180738160 56908096 3860288
09:36:56 33025 8192000000 10091421872 10567503872 1115159616 11.981 97 10 20 0 1172713472 55543936 2009920
09:38:30 63681 8192000000 10162033840 10620616704 1131864784 12.139 99 16 32 0 1229379184 94222448 3291952
09:40:34 67103 8192000000 10058133824 10499166208 1061750832 11.473 93 15 30 0 1100305312 35631616 2922864
09:42:41 52938 8192000000 10084201600 10519461888 1116346528 11.992 99 38 76 1 1182811888 58071120 8394240
09:44:37 43798 8192000000 10044999680 10471780352 1078120208 11.630 96 15 30 0 1112819952 31712976 2986768
09:46:25 55248 8192000000 10067883760 10508951552 1083394912 11.680 97 29 57 0 1146230624 56917504 5918208
09:48:23 42661 8192000000 10079885344 10523111424 1094717056 11.787 98 32 64 0 1156433152 55371424 6344672
09:50:06 12214 8192000000 10128493104 10557304832 1116535296 11.994 100 45 90 0 1212129904 86760000 8834608
09:51:23 157278 8192000000 10014511056 10462433280 1063218992 11.487 95 15 30 1 1120633920 54230880 3184048
09:55:21 35534 8192000000 10056717888 10522132480 1104749440 11.883 99 36 72 1 1173111392 60984624 7377328
09:56:58 58064 8192000000 10034298944 10488438784 1050110704 11.362 95 15 30 1 1077282528 24094304 3077520
09:59:01 74318 8192000000 10059208304 10522677248 1107205216 11.906 100 14 28 0 1165569904 54416800 3947888
10:01:15 43641 8192000000 10076021936 10521059328 1106199408 11.896 99 15 30 0 1167628464 58157120 3271936
10:03:03 45283 8192000000 10059168896 10519797760 1095159936 11.792 98 22 44 0 1161158896 60807616 5191344
10:04:58 66884 8192000000 10039065568 10527543296 1109667968 11.929 100 20 40 0 1194281920 79989952 4624000
10:07:06 17436 8192000000 10065281584 10487750656 1083562320 11.681 97 37 71 0 1125107872 33596256 7949296
10:08:25 30813 8192000000 10081591600 10510823424 1126164624 12.085 100 27 54 2 1161880848 30655152 5061072
10:10:05 95389 8192000000 10068238688 10555736064 1092694448 11.768 96 10 20 0 1148536864 53927728 1914688
10:12:41 68073 8192000000 10110795648 10550136832 1101402352 11.851 97 19 38 0 1144986624 39849696 3734576
10:14:52 123867 8192000000 10047403648 10518786048 1137537504 12.192 100 17 33 2 1180298256 39114448 3646304
10:17:59 90585 8192000000 10046875232 10510774272 1092893120 11.770 96 8 16 0 1133640816 39088832 1658864
10:20:37 59234 8192000000 10069701840 10548645888 1126285824 12.086 100 10 20 0 1177720720 49242624 2192272
10:22:57 63288 8192000000 10116938560 10557726720 1129829664 12.120 99 21 42 0 1213528512 78717552 4981296
10:25:02 28112 8192000000 10142100496 10584387584 1118561824 12.013 98 25 50 0 1225087360 101075632 5449904
10:26:42 75398 8192000000 10140036656 10592595968 1126015760 12.084 99 23 46 0 1258520192 127128624 5375808
10:28:58 130215 8192000000 10076467120 10547523584 1102219360 11.859 97 14 28 0 1188496144 83170992 3105792
10:32:14 101199 8192000000 10081135136 10536517632 1118598784 12.014 98 41 81 1 1172073456 44319024 9155648
10:34:55 29574 8192000000 10078917664 10555367424 1136121328 12.179 100 5 10 0 1180416784 43441744 853712
10:36:29 42539 8192000000 9676263488 10065039360 768839824 8.579 68 5 10 0 803555312 33805760 909728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-11-2021 10:39:10 to 08-11-2021 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2021 10:39:25 to 08-11-2021 10:39: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          234568
MON$OLDEST_ACTIVE               237435
MON$OLDEST_SNAPSHOT             237435
MON$NEXT_TRANSACTION            237435
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                       2056032
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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 ---
:171: 
:172: mscs-test-161.budzdorov.ru    Mon Nov  8 10:37:17 2021
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
--- end of diff output ---

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

08.11.21 10:39: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-11-08 10:39:31 No severe PSQL-related problems occured
08.11.21 10:39:32. End of report.