Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.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 16846724 kB
MemAvailable 31468420 kB
Buffers 0 kB
Cached 14817900 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-07 13:37:06
DTS_END                         2021-12-07 13:37:06.4440
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/36852
TRN_ID                          223258
ATT_ID                          620
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: 8558.17 24379 1026981
customer order: creation 159.03 476 19084
customer order: refuse 13.83 402 1660
order to supplier: creation 42.92 1604 5150
order to supplier: removal 3.33 118209 400
invoice (draft): creation 42.02 4129 5042
invoice (draft): removal 49.62 999 5954
invoice accept: apply 37.85 20931 4542
invoice accept: cancel 44.99 975 5399
customer reserve: creation 3469.85 208 416382
customer reserve: removal 4419.09 70 530291
realization accept: apply 77.14 186 9257
realization accept: cancel 78.49 140 9419
payment from customer: creation 55.52 115 6662
payment from customer: removal 5.55 114 666
payment to supplier: creation 44.88 141 5385
payment to supplier: removal 3.42 93 410
service: total inventory turnovers 3.93 3728 471
service: total monetary turnovers 5.93 540 712
service: aggregate perf. data 0.75 17597 90
service: refresh index statistics 0.04 316929 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6669
TEST_TIME 2 5200
TEST_TIME 3 5409
TEST_TIME 4 5584
TEST_TIME 5 5603
TEST_TIME 6 5596
TEST_TIME 7 5313
TEST_TIME 8 5250
TEST_TIME 9 5062
TEST_TIME 10 5042
TEST_TIME 11 5432
TEST_TIME 12 5436
TEST_TIME 13 5440
TEST_TIME 14 5575
TEST_TIME 15 5765
TEST_TIME 16 6305
TEST_TIME 17 6492
TEST_TIME 18 6694
TEST_TIME 19 7171
TEST_TIME 20 7146
TEST_TIME 21 7604
TEST_TIME 22 7907
TEST_TIME 23 8178
TEST_TIME 24 8520
TEST_TIME 25 9466
TEST_TIME 26 9842
TEST_TIME 27 9731
TEST_TIME 28 9551
TEST_TIME 29 9481
TEST_TIME 30 9285
TEST_TIME 31 9322
TEST_TIME 32 9147
TEST_TIME 33 8983
TEST_TIME 34 9000
TEST_TIME 35 9034
TEST_TIME 36 8894
TEST_TIME 37 8776
TEST_TIME 38 8783
TEST_TIME 39 8702
TEST_TIME 40 8591
TEST_TIME 41 8625
TEST_TIME 42 8573
TEST_TIME 43 8482
TEST_TIME 44 8387
TEST_TIME 45 8353
TEST_TIME 46 8258
TEST_TIME 47 8218
TEST_TIME 48 8225
TEST_TIME 49 8277
TEST_TIME 50 8351
TEST_TIME 51 8309
TEST_TIME 52 8245
TEST_TIME 53 8427
TEST_TIME 54 8488
TEST_TIME 55 8512
TEST_TIME 56 8529
TEST_TIME 57 8629
TEST_TIME 58 8550
TEST_TIME 59 8722
TEST_TIME 60 8652
TEST_TIME 61 8585
TEST_TIME 62 8487
TEST_TIME 63 8439
TEST_TIME 64 8506
TEST_TIME 65 8407
TEST_TIME 66 8324
TEST_TIME 67 8330
TEST_TIME 68 8474
TEST_TIME 69 8492
TEST_TIME 70 8469
TEST_TIME 71 8452
TEST_TIME 72 8453
TEST_TIME 73 8394
TEST_TIME 74 8712
TEST_TIME 75 8683
TEST_TIME 76 8690
TEST_TIME 77 8741
TEST_TIME 78 8676
TEST_TIME 79 8728
TEST_TIME 80 8695
TEST_TIME 81 8630
TEST_TIME 82 8594
TEST_TIME 83 8607
TEST_TIME 84 8583
TEST_TIME 85 8514
TEST_TIME 86 8477
TEST_TIME 87 8446
TEST_TIME 88 8436
TEST_TIME 89 8514
TEST_TIME 90 8484
TEST_TIME 91 8520
TEST_TIME 92 8518
TEST_TIME 93 8476
TEST_TIME 94 8553
TEST_TIME 95 8564
TEST_TIME 96 8603
TEST_TIME 97 8589
TEST_TIME 98 8671
TEST_TIME 99 8688
TEST_TIME 100 8720
TEST_TIME 101 8711
TEST_TIME 102 8736
TEST_TIME 103 8729
TEST_TIME 104 8652
TEST_TIME 105 8599
TEST_TIME 106 8619
TEST_TIME 107 8586
TEST_TIME 108 8553
TEST_TIME 109 8564
TEST_TIME 110 8516
TEST_TIME 111 8467
TEST_TIME 112 8485
TEST_TIME 113 8478
TEST_TIME 114 8529
TEST_TIME 115 8544
TEST_TIME 116 8583
TEST_TIME 117 8553
TEST_TIME 118 8570
TEST_TIME 119 8631
TEST_TIME 120 8562

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1413696 1413696 0 0.00 0 27498 7 0 0
doc_list_biud 1413692 1413692 0 0.00 0 37904 34 0 0
sp_add_invoice_to_stock 4667 4542 125 2.67 42 204754 20932 0 125
sp_cancel_adding_invoice 5399 5399 0 0.00 5 76404 976 0 0
sp_cancel_client_order 1660 1660 0 0.00 5 14399 403 0 0
sp_cancel_customer_reserve 530291 530291 0 0.00 1 28108 71 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 13082 114 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3519 94 0 0
sp_cancel_supplier_invoice 5964 5954 10 0.16 3 24735 1000 0 10
sp_cancel_supplier_order 400 400 0 0.00 5 1068589 118210 0 0
sp_cancel_write_off 9419 9419 0 0.00 1 27500 140 0 0
sp_client_order 19084 19084 0 0.00 4 41503 477 0 0
sp_customer_reserve 416382 416382 0 0.00 0 59680 208 0 0
sp_fill_shopping_cart 29269 29269 0 0.00 1 32617 56 0 0
sp_fill_shopping_cart_clo_res 416375 416375 0 0.00 0 24107 19 0 0
sp_get_clo_for_invoice 4535 4535 0 0.00 0 43599 455 0 0
sp_kill_cost_storno 15894 15894 0 0.00 0 27497 69 0 0
sp_kill_qstorno_ret_qs2qd 1076355 1076355 0 0.00 0 28086 39 0 0
sp_kill_qty_storno 566911 566911 0 0.00 0 37903 83 0 0
sp_lock_selected_doc 19222 19222 0 0.00 0 6000 4 0 0
sp_make_cost_storno 25839 25839 0 0.00 0 20548 61 0 0
sp_multiply_rows_for_pdistr 25837 25837 0 0.00 0 8951 34 0 0
sp_multiply_rows_for_qdistr 19084 19084 0 0.00 0 41434 245 0 0
sp_pay_from_customer 6662 6662 0 0.00 4 8958 115 0 0
sp_pay_to_supplier 5385 5385 0 0.00 5 11290 142 0 0
sp_payment_common 12047 12047 0 0.00 1 7632 26 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 14388 371 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 37903 380 0 0
sp_qd_handle_on_reserve_upd_sts 9256 9256 0 0.00 0 11585 59 0 0
sp_reserve_write_off 9257 9257 0 0.00 6 12152 187 0 0
sp_supplier_invoice 5042 5042 0 0.00 7 90849 4130 0 0
sp_supplier_order 5150 5150 0 0.00 6 39520 1605 0 0
srv_aggregate_perf_data 90 90 0 0.00 3429 174693 17597 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1185 120006 46584 0 0
srv_find_qd_qs_mism 983747 983747 0 0.00 0 12356 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 50 114419 3729 0 0
srv_make_money_saldo 712 712 0 0.00 1 15211 540 0 0
srv_recalc_idx_stat 5 5 0 0.00 193462 513882 316929 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 4542 4542 0 0.00 0 23353 182 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 4169 167 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 8163 19 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1539 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 16577 45 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 934 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 2637 24 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 25848 1573 0 0
t$perf-norm:sp_cancel_write_off 910 910 0 0.00 0 6547 22 0 0
t$perf-norm:sp_client_order 19084 19084 0 0.00 0 11169 19 0 0
t$perf-norm:sp_customer_reserve 879 879 0 0.00 0 825 5 0 0
t$perf-norm:sp_pay_from_customer 6662 6662 0 0.00 0 11436 13 0 0
t$perf-norm:sp_pay_to_supplier 5385 5385 0 0.00 0 14705 20 0 0
t$perf-norm:sp_reserve_write_off 9257 9257 0 0.00 0 19075 17 0 0
t$perf-norm:sp_supplier_invoice 5042 5042 0 0.00 0 5685 18 0 0
t$perf-norm:sp_supplier_order 5150 5150 0 0.00 0 5910 16 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 197 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 60 2 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1211 10 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 1334 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 1553 315 0 0
v_add_invoice_to_stock 9314 9064 250 2.68 0 4618 21 0 250
v_all_customers 35016 35016 0 0.00 0 15 1 0 0
v_all_suppliers 28204 28204 0 0.00 0 18 1 0 0
v_all_wares 38168 38168 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 121 6 0 0
v_cancel_client_order 3320 3320 0 0.00 0 3342 9 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 5752 17 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 3912 8 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3463 8 0 0
v_cancel_supplier_invoice 736 716 20 2.71 0 3777 26 0 20
v_cancel_supplier_order 800 800 0 0.00 0 6971 17 0 0
v_cancel_write_off 1820 1820 0 0.00 0 1409 14 0 0
v_max_id_clo_ord 5149 5149 0 0.00 0 4565 4 0 0
v_max_id_clo_res 879 879 0 0.00 0 2596 15 0 0
v_max_id_ord_sup 5036 5036 0 0.00 0 3287 6 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 1206 2 0 0
v_max_non_paid_realizn 3528 3528 0 0.00 0 5199 7 0 0
v_min_id_clo_ord 5149 5149 0 0.00 0 3368 7 0 0
v_min_id_clo_res 879 879 0 0.00 0 7414 18 0 0
v_min_id_ord_sup 5036 5036 0 0.00 0 13483 10 0 0
v_min_non_paid_invoice 5385 5385 0 0.00 0 5285 16 0 0
v_min_non_paid_realizn 6662 6662 0 0.00 0 6286 20 0 0
v_our_firm 9420 9420 0 0.00 0 18 1 0 0
v_reserve_write_off 18512 18512 0 0.00 0 4007 6 0 0
x_lock_depdocs_on_canc_invoice 10995 10995 0 0.00 0 15881 113 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9022 391 0 0
x_make_qstorno_1000_1200 5150 5150 0 0.00 2 39516 1513 0 0
x_make_qstorno_1200_2000 5042 5042 0 0.00 3 90777 3900 0 0
x_make_qstorno_2100_3300 416382 416382 0 0.00 0 59679 187 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 07-12-2021 13:38:03 to 07-12-2021 13:38:06.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 21398 8192000000 9722795168 10021650432 805416496 8.951 100 84 168 0 832922464 7261760 20244208
11:09:36 24795 8192000000 9769248704 10076000256 840896304 9.309 100 89 176 2 873217776 11246496 21074976
11:11:06 10809 8192000000 9794449680 10108395520 860467520 9.505 100 84 168 0 901541296 21288592 19785184
11:12:22 17245 8192000000 9860006800 10191818752 916780608 10.064 100 78 156 0 967461088 33833808 16846672
11:13:42 11016 8192000000 9914024112 10255585280 956845184 10.458 100 77 154 0 1019990448 47094592 16050672
11:14:59 4910 8192000000 9971339280 10323546112 989481888 10.776 100 80 157 0 1084497440 79684304 15331248
11:16:14 3974 8192000000 9992164960 10352500736 1021595760 11.087 100 84 166 1 1106391536 67905488 16890288
11:17:20 2508 8192000000 9992810128 10362134528 1041022144 11.274 100 84 168 0 1109583824 51751504 16810176
11:18:24 2229 8192000000 10025285232 10400043008 1051250544 11.373 100 91 174 1 1138368720 69875920 17242256
11:19:30 6940 8192000000 10049049248 10422235136 1065490512 11.509 100 89 176 0 1163203040 80449360 17263168
11:20:38 5872 8192000000 9999443376 10392764416 1077821856 11.627 100 38 76 1 1114452128 28434160 8196112
11:21:46 6056 8192000000 10016409056 10413244416 1085721712 11.702 100 35 71 0 1136370400 42738064 7910624
11:22:54 58340 8192000000 10016343616 10411417600 1090354768 11.746 100 51 102 1 1121391152 20034256 11002128
11:24:53 6864 8192000000 10014839216 10416312320 1093552048 11.776 100 47 92 0 1122680224 19406368 9721808
11:26:01 11586 8192000000 10025680144 10436481024 1099510480 11.833 100 49 100 0 1127954896 17979200 10465216
11:27:17 7190 8192000000 10033314480 10434994176 1077493360 11.624 99 32 64 3 1113628016 29990912 6143744
11:28:32 28684 8192000000 9979148464 10389524480 1026991040 11.139 94 30 57 0 1059402688 26520272 5891376
11:30:02 6899 8192000000 9971620720 10363351040 1035983120 11.226 97 35 70 1 1061957728 18141872 7832736
11:31:13 5656 8192000000 9948834944 10332864512 1018410848 11.057 98 35 71 1 1045680656 19476160 7793648
11:32:20 67798 8192000000 9913707088 10298269696 963821824 10.526 94 37 72 2 991434592 19381360 8231408
11:34:30 8772 8192000000 9959590752 10345922560 1041533200 11.279 100 45 89 0 1073224896 21942896 9748800
11:35:42 7883 8192000000 9975911728 10365931520 1057313168 11.431 100 27 55 0 1083702752 19772720 6616864
11:36:53 7606 8192000000 9979740704 10364637184 1064748176 11.502 100 18 37 1 1099649200 30902720 3998304
11:38:04 116485 8192000000 10031590080 10430918656 1102314256 11.860 100 40 77 1 1153623008 43569760 7738992
11:41:07 10058 8192000000 10072089696 10481381376 1116849904 11.997 100 48 95 2 1191482992 63934032 10699056
11:42:32 5375 8192000000 10116520928 10516885504 1135226176 12.171 100 69 136 0 1224062880 75009984 13826720
11:43:39 34804 8192000000 10141362608 10557751296 1139991184 12.215 100 45 89 1 1258788864 108420688 10376992
11:45:19 10762 8192000000 10197372752 10612916224 1156832160 12.374 100 49 98 0 1311405632 143827920 10745552
11:46:36 8059 8192000000 10248443152 10653622272 1169024224 12.488 100 56 111 2 1349127616 167275744 12827648
11:47:52 12260 8192000000 10278168720 10702508032 1178226048 12.574 100 51 102 0 1389590848 199034464 12330336
11:49:10 6340 8192000000 10296248688 10718183424 1190181312 12.685 100 59 117 0 1401291040 197934096 13175632
11:50:25 2763 8192000000 10345329968 10752909312 1184553600 12.633 100 82 164 0 1451450400 247207552 19689248
11:51:34 1689 8192000000 10378671696 10780860416 1182615296 12.615 100 84 168 1 1473122928 270817536 19690096
11:52:41 2344 8192000000 10413682304 10839556096 1198974768 12.767 100 78 155 1 1525435952 308254400 18206784
11:53:48 2325 8192000000 10407787856 10834427904 1186909584 12.655 100 79 158 0 1512084128 306386672 18787872
11:54:58 1324 8192000000 10417030320 10820919296 1162463792 12.426 100 81 161 0 1518705200 337038768 19202640
11:56:05 2378 8192000000 10443052944 10844549120 1164436448 12.445 100 87 170 1 1539553008 355326848 19789712
11:57:14 1182 8192000000 10417679216 10820898816 1170053376 12.497 100 83 167 1 1515860192 326623392 19183424
11:58:20 9656 8192000000 10370310736 10817187840 1151049184 12.319 100 46 92 0 1472749296 309798080 11902032
11:59:40 28943 8192000000 10347597920 10777960448 1116365152 11.993 99 30 60 0 1439163728 314484112 8314464
12:01:15 16601 8192000000 10263942064 10696339456 1106811104 11.902 99 21 42 0 1360250416 247646944 5792368
12:02:34 19679 8192000000 10147443952 10614337536 1108894032 11.922 99 15 28 2 1247398112 135032576 3471504
12:03:55 36613 8192000000 10078031488 10525491200 1106291616 11.897 99 33 66 2 1193984336 80596000 7096720
12:05:36 33539 8192000000 10046884624 10490875904 1078705488 11.635 97 22 45 0 1155003568 71661200 4636880
12:07:43 29454 8192000000 10085472704 10510536704 1122105984 12.047 100 47 88 0 1183450688 52858496 8486208
12:09:18 62284 8192000000 10111548800 10546012160 1124136000 12.066 100 16 32 1 1203549808 75837856 3575952
12:11:27 52017 8192000000 10076939072 10526662656 1108284896 11.916 98 16 32 2 1170293168 57871024 4137248
12:13:20 117724 8192000000 10085970032 10525810688 1118554720 12.013 99 36 73 2 1177221008 50359184 8307104
12:16:39 76229 8192000000 10072868816 10514264064 1109327936 11.926 98 27 51 1 1164349584 49140160 5881488
12:18:56 73316 8192000000 10055180672 10519261184 1119889792 12.026 99 19 38 3 1168543216 44163120 4490304
12:21:11 41628 8192000000 10039535264 10493018112 1116403856 11.993 99 21 42 0 1140046688 19171664 4471168
12:22:58 66131 8192000000 10076594816 10558857216 1128157424 12.104 99 7 14 0 1186118656 56534832 1426400
12:25:18 62049 8192000000 10072264960 10532483072 1118768288 12.015 98 18 36 0 1188175120 65103552 4303280
12:27:28 64070 8192000000 10077738656 10520936448 1102670352 11.863 97 22 44 1 1138811712 31439184 4702176
12:29:33 53086 8192000000 10138261168 10613891072 1128863264 12.111 99 30 59 0 1247203936 111954000 6386672
12:31:27 72600 8192000000 10096149376 10544656384 1123737152 12.062 98 16 32 0 1177599168 50495936 3366080
12:33:41 70584 8192000000 10065929504 10544308224 1136618064 12.184 99 27 53 1 1209623904 67515152 5490688
12:35:53 89734 8192000000 10092949632 10565292032 1125905872 12.083 98 26 52 1 1187829312 56652224 5271216
12:38:23 65161 8192000000 10060693504 10491043840 1138094912 12.198 100 23 46 0 1171973312 28854560 5023840
12:40:34 59178 8192000000 10125331456 10587897856 1147849856 12.289 100 33 67 1 1220623696 66281120 6492720
12:45:49 58520 8192000000 10089402864 10553835520 1130260112 12.124 98 20 40 0 1186907104 52736624 3910368
12:47:54 48924 8192000000 10171270544 10609786880 1145065520 12.263 99 24 48 0 1240841760 90378912 5397328
12:49:43 50586 8192000000 10211970096 10656899072 1116786512 11.997 97 18 36 1 1283564240 162921696 3856032
12:51:35 41340 8192000000 10067551616 10535895040 1113973184 11.970 99 4 8 0 1150531312 36021168 536960
12:53:29 15747 8192000000 10097983584 10510102528 1102547360 11.862 97 57 111 1 1150100336 36311632 11241344
12:54:52 40429 8192000000 10084637072 10508443648 1093989568 11.781 96 20 40 0 1149705904 52115072 3601264
12:56:34 25465 8192000000 10083677760 10513805312 1120177504 12.029 100 38 72 1 1172407328 44925888 7303936
12:58:05 101135 8192000000 10103171744 10552123392 1131358128 12.134 100 35 71 0 1193361968 54177376 7826464
13:00:48 38562 8192000000 10083003808 10569011200 1132864832 12.148 100 16 32 0 1182756560 46565168 3326560
13:02:28 37371 8192000000 10074460016 10514460672 1122171136 12.047 99 7 14 0 1157759504 34046224 1542144
13:04:09 105183 8192000000 10091382976 10552692736 1112190240 11.953 98 23 46 0 1203326928 85760400 5376288
13:07:11 119932 8192000000 10052121056 10491838464 1058494400 11.442 94 15 30 0 1110912912 49591536 2826976
13:10:14 36967 8192000000 10112284544 10525179904 1098533680 11.824 97 39 78 0 1172396368 66173216 7689472
13:11:52 74937 8192000000 10099443696 10536460288 1116613504 11.995 99 22 44 0 1179782000 59171984 3996512
13:14:08 50205 8192000000 10148595600 10599972864 1112730896 11.958 98 23 46 3 1179754176 62481232 4542048
13:16:11 51000 8192000000 10074657712 10503491584 1118618912 12.014 99 30 60 0 1186324368 61896384 5809072
13:18:03 57337 8192000000 10113849520 10555248640 1142823536 12.242 100 26 52 0 1210052080 62015392 5213152
13:20:45 89195 8192000000 10099109680 10545930240 1130006240 12.121 99 16 32 0 1189110720 55660176 3444304
13:23:31 38293 8192000000 10069820816 10518192128 1122647152 12.052 98 18 36 0 1169487120 43190960 3649008
13:25:23 78570 8192000000 10132758656 10577317888 1142662800 12.241 100 14 28 1 1217705536 72001664 3041072
13:27:41 64677 8192000000 10153421040 10604302336 1154089504 12.348 100 19 38 0 1257217120 98581504 4546112
13:29:46 76084 8192000000 10129039056 10580586496 1110807872 11.940 96 16 32 0 1199674544 85373504 3493168
13:32:03 35982 8192000000 10096819312 10570788864 1134350240 12.162 99 19 38 0 1183624400 45073024 4201136
13:33:42 53702 8192000000 10099071152 10541346816 1129015056 12.112 98 21 43 0 1165483040 32319952 4148032
13:35:39 79552 8192000000 10095505552 10585231360 1132514400 12.145 99 20 40 0 1204931792 68463040 3954352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2021 13:38:08 to 07-12-2021 13:38:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 07-12-2021 13:38:24 to 07-12-2021 13:38:26.

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          221443
MON$OLDEST_ACTIVE               223460
MON$OLDEST_SNAPSHOT             223460
MON$NEXT_TRANSACTION            223460
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                       2014976
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Dec  7 13:37:13 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Dec  7 13:37:13 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

07.12.21 13:38:27. 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-07 13:38:29 No severe PSQL-related problems occured
07.12.21 13:38:30. End of report.