Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 04: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 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 16596120 kB
MemAvailable 31518356 kB
Buffers 20 kB
Cached 15003856 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-11-23 04:37:10
DTS_END                         2021-11-23 04:37:10.1880
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/37786
TRN_ID                          223914
ATT_ID                          659
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: 8982.43 23278 1077892
customer order: creation 157.93 440 18951
customer order: refuse 13.82 352 1658
order to supplier: creation 42.41 1401 5089
order to supplier: removal 3.61 113288 433
invoice (draft): creation 42.06 3666 5047
invoice (draft): removal 53.15 890 6378
invoice accept: apply 38.53 19779 4623
invoice accept: cancel 47.62 903 5714
customer reserve: creation 3601.60 193 432192
customer reserve: removal 4702.34 70 564281
realization accept: apply 77.96 211 9355
realization accept: cancel 82.98 116 9957
payment from customer: creation 54.47 116 6536
payment from customer: removal 5.19 135 623
payment to supplier: creation 45.45 148 5454
payment to supplier: removal 3.50 60 420
service: total inventory turnovers 3.35 4191 402
service: total monetary turnovers 5.52 540 662
service: aggregate perf. data 0.94 14354 113
service: refresh index statistics 0.03 304701 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 04:38:21 to 23-11-2021 04:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7156
TEST_TIME 2 8443
TEST_TIME 3 7167
TEST_TIME 4 5839
TEST_TIME 5 5611
TEST_TIME 6 5326
TEST_TIME 7 5489
TEST_TIME 8 5409
TEST_TIME 9 5827
TEST_TIME 10 6283
TEST_TIME 11 6386
TEST_TIME 12 6749
TEST_TIME 13 7008
TEST_TIME 14 7167
TEST_TIME 15 7585
TEST_TIME 16 8095
TEST_TIME 17 7992
TEST_TIME 18 8139
TEST_TIME 19 8559
TEST_TIME 20 8884
TEST_TIME 21 8714
TEST_TIME 22 9116
TEST_TIME 23 9254
TEST_TIME 24 9590
TEST_TIME 25 9760
TEST_TIME 26 11205
TEST_TIME 27 10869
TEST_TIME 28 10582
TEST_TIME 29 10628
TEST_TIME 30 10581
TEST_TIME 31 10342
TEST_TIME 32 10306
TEST_TIME 33 10271
TEST_TIME 34 10202
TEST_TIME 35 10040
TEST_TIME 36 9840
TEST_TIME 37 9729
TEST_TIME 38 9629
TEST_TIME 39 9559
TEST_TIME 40 9433
TEST_TIME 41 9299
TEST_TIME 42 9243
TEST_TIME 43 9220
TEST_TIME 44 9263
TEST_TIME 45 9256
TEST_TIME 46 9205
TEST_TIME 47 9199
TEST_TIME 48 9305
TEST_TIME 49 9371
TEST_TIME 50 9289
TEST_TIME 51 9329
TEST_TIME 52 9258
TEST_TIME 53 9252
TEST_TIME 54 9414
TEST_TIME 55 9475
TEST_TIME 56 9532
TEST_TIME 57 9572
TEST_TIME 58 9575
TEST_TIME 59 9573
TEST_TIME 60 9537
TEST_TIME 61 9388
TEST_TIME 62 9299
TEST_TIME 63 9198
TEST_TIME 64 9250
TEST_TIME 65 9176
TEST_TIME 66 9178
TEST_TIME 67 9111
TEST_TIME 68 9127
TEST_TIME 69 9126
TEST_TIME 70 9103
TEST_TIME 71 9084
TEST_TIME 72 9162
TEST_TIME 73 9144
TEST_TIME 74 9170
TEST_TIME 75 9189
TEST_TIME 76 9250
TEST_TIME 77 9270
TEST_TIME 78 9354
TEST_TIME 79 9304
TEST_TIME 80 9270
TEST_TIME 81 9191
TEST_TIME 82 9125
TEST_TIME 83 9103
TEST_TIME 84 9036
TEST_TIME 85 9056
TEST_TIME 86 9017
TEST_TIME 87 9052
TEST_TIME 88 9016
TEST_TIME 89 8982
TEST_TIME 90 9001
TEST_TIME 91 8986
TEST_TIME 92 9061
TEST_TIME 93 9049
TEST_TIME 94 9114
TEST_TIME 95 9105
TEST_TIME 96 9216
TEST_TIME 97 9198
TEST_TIME 98 9258
TEST_TIME 99 9219
TEST_TIME 100 9151
TEST_TIME 101 9115
TEST_TIME 102 9121
TEST_TIME 103 9070
TEST_TIME 104 9041
TEST_TIME 105 8981
TEST_TIME 106 8986
TEST_TIME 107 8967
TEST_TIME 108 8942
TEST_TIME 109 8976
TEST_TIME 110 8952
TEST_TIME 111 8957
TEST_TIME 112 9014
TEST_TIME 113 9043
TEST_TIME 114 9012
TEST_TIME 115 9030
TEST_TIME 116 9121
TEST_TIME 117 9092
TEST_TIME 118 9098
TEST_TIME 119 9058
TEST_TIME 120 8983

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-11-2021 04:38:24 to 23-11-2021 04:38:28.

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 1477576 1477576 0 0.00 0 28950 7 0 0
doc_list_biud 1477575 1477575 0 0.00 0 29820 33 0 0
sp_add_invoice_to_stock 4787 4623 164 3.42 49 193729 19780 0 164
sp_cancel_adding_invoice 5714 5714 0 0.00 5 122477 903 0 0
sp_cancel_client_order 1658 1658 0 0.00 4 16630 352 0 0
sp_cancel_customer_reserve 564281 564281 0 0.00 1 23518 71 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 22177 135 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 1279 61 0 0
sp_cancel_supplier_invoice 6396 6378 18 0.28 2 21596 891 0 18
sp_cancel_supplier_order 433 433 0 0.00 11 902238 113288 0 0
sp_cancel_write_off 9957 9957 0 0.00 1 13581 116 0 0
sp_client_order 18951 18951 0 0.00 4 55339 440 0 0
sp_customer_reserve 432192 432192 0 0.00 0 41038 194 0 0
sp_fill_shopping_cart 29068 29068 0 0.00 1 13798 46 0 0
sp_fill_shopping_cart_clo_res 432189 432189 0 0.00 0 22114 19 0 0
sp_get_clo_for_invoice 4618 4618 0 0.00 0 30450 416 0 0
sp_kill_cost_storno 16714 16714 0 0.00 0 22155 54 0 0
sp_kill_qstorno_ret_qs2qd 1145330 1145330 0 0.00 0 22309 38 0 0
sp_kill_qty_storno 602393 602393 0 0.00 0 29819 81 0 0
sp_lock_selected_doc 19496 19496 0 0.00 0 9268 5 0 0
sp_make_cost_storno 25962 25962 0 0.00 0 22137 62 0 0
sp_multiply_rows_for_pdistr 25962 25962 0 0.00 0 21583 36 0 0
sp_multiply_rows_for_qdistr 18951 18951 0 0.00 0 55324 236 0 0
sp_pay_from_customer 6536 6536 0 0.00 4 17008 116 0 0
sp_pay_to_supplier 5454 5454 0 0.00 5 22218 148 0 0
sp_payment_common 11990 11990 0 0.00 1 22164 35 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 16615 312 0 0
sp_qd_handle_on_invoice_upd_sts 10331 10331 0 0.00 0 29819 360 0 0
sp_qd_handle_on_reserve_upd_sts 9355 9355 0 0.00 0 15196 71 0 0
sp_reserve_write_off 9355 9355 0 0.00 6 21745 211 0 0
sp_supplier_invoice 5047 5047 0 0.00 7 84681 3667 0 0
sp_supplier_order 5089 5089 0 0.00 5 43799 1402 0 0
srv_aggregate_perf_data 113 113 0 0.00 2679 180128 14355 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2395 130589 47709 0 0
srv_find_qd_qs_mism 1033478 1033478 0 0.00 0 28950 5 0 0
srv_make_invnt_saldo 402 402 0 0.00 53 128446 4192 0 0
srv_make_money_saldo 662 662 0 0.00 1 18391 541 0 0
srv_recalc_idx_stat 4 4 0 0.00 119717 490101 304701 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4623 4623 0 0.00 0 18434 164 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 8769 107 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 2561 10 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 1244 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 919 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 228 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1766 20 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 21047 1297 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 3553 17 0 0
t$perf-norm:sp_client_order 18951 18951 0 0.00 0 13276 15 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 20086 27 0 0
t$perf-norm:sp_pay_from_customer 6536 6536 0 0.00 0 6543 8 0 0
t$perf-norm:sp_pay_to_supplier 5454 5454 0 0.00 0 14033 13 0 0
t$perf-norm:sp_reserve_write_off 9355 9355 0 0.00 0 17368 13 0 0
t$perf-norm:sp_supplier_invoice 5047 5047 0 0.00 0 6948 17 0 0
t$perf-norm:sp_supplier_order 5089 5089 0 0.00 0 3606 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 305 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 96 3 0 0
t$perf-norm:srv_make_invnt_saldo 402 402 0 0.00 0 11586 36 0 0
t$perf-norm:srv_make_money_saldo 662 662 0 0.00 0 3703 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 9550 9222 328 3.43 0 6875 19 0 328
v_all_customers 34554 34554 0 0.00 0 16 1 0 0
v_all_suppliers 28080 28080 0 0.00 0 25 1 0 0
v_all_wares 37900 37900 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 1236 8 0 0
v_cancel_client_order 3316 3316 0 0.00 0 4576 10 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 3296 11 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 2451 5 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 193 3 0 0
v_cancel_supplier_invoice 826 790 36 4.35 0 3653 25 0 36
v_cancel_supplier_order 866 866 0 0.00 0 3018 12 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1451 12 0 0
v_max_id_clo_ord 5081 5081 0 0.00 0 1733 2 0 0
v_max_id_clo_res 910 910 0 0.00 0 3925 15 0 0
v_max_id_ord_sup 5034 5034 0 0.00 0 7226 6 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 2501 6 0 0
v_max_non_paid_realizn 3545 3545 0 0.00 0 5862 6 0 0
v_min_id_clo_ord 5081 5081 0 0.00 0 4972 12 0 0
v_min_id_clo_res 910 910 0 0.00 0 3081 10 0 0
v_min_id_ord_sup 5034 5034 0 0.00 0 5212 9 0 0
v_min_non_paid_invoice 5454 5454 0 0.00 0 22081 37 0 0
v_min_non_paid_realizn 6536 6536 0 0.00 0 7433 20 0 0
v_our_firm 9326 9326 0 0.00 0 15 1 0 0
v_reserve_write_off 18710 18710 0 0.00 0 4788 7 0 0
x_lock_depdocs_on_canc_invoice 11697 11697 0 0.00 0 26336 107 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 5435 295 0 0
x_make_qstorno_1000_1200 5089 5089 0 0.00 1 43756 1322 0 0
x_make_qstorno_1200_2000 5047 5047 0 0.00 4 83243 3490 0 0
x_make_qstorno_2100_3300 432192 432192 0 0.00 0 40087 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 04:38:29 to 23-11-2021 04:38:33.

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
02:07:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 33537 8192000000 9716136048 10016776192 798910512 8.885 100 87 174 0 828337824 7607984 21819328
02:09:54 27013 8192000000 9772844112 10083635200 844085600 9.341 100 89 178 1 878432688 13321904 21025184
02:11:26 20487 8192000000 9812727632 10134683648 869862880 9.599 100 78 156 0 911838848 24010800 17965168
02:12:55 15965 8192000000 9869326992 10207559680 925956336 10.155 100 70 140 0 982016032 40892400 15167296
02:14:19 8846 8192000000 9917755952 10269630464 971783760 10.604 100 70 139 3 1032036400 46032656 14219984
02:15:30 4300 8192000000 9973759152 10337652736 1008134688 10.957 100 74 144 1 1088709808 65681568 14893552
02:16:36 2475 8192000000 10000241104 10366279680 1036660832 11.233 100 81 160 0 1120525376 67552544 16312000
02:17:41 2413 8192000000 10017707488 10393735168 1051258672 11.373 100 71 142 1 1134427664 69114752 14054240
02:18:45 3255 8192000000 10019490192 10402463744 1059799680 11.455 100 64 128 0 1135501376 61523392 14178304
02:19:51 49229 8192000000 9992812912 10374320128 1069808880 11.550 100 47 94 2 1106405296 26129616 10466800
02:21:42 9436 8192000000 9987995504 10391822336 1081788784 11.665 100 38 76 1 1106588000 16196032 8603184
02:22:56 7859 8192000000 10009268928 10416906240 1085719792 11.702 100 52 102 2 1128619456 31650592 11249072
02:24:05 8905 8192000000 10027084528 10426232832 1094607776 11.786 100 38 77 1 1132016624 28367648 9041200
02:25:23 5638 8192000000 10023910672 10410938368 1092161552 11.763 100 70 143 3 1127998864 20811136 15026176
02:26:29 8987 8192000000 10009639360 10414161920 1094089120 11.782 100 28 56 1 1122013248 21482272 6441856
02:27:38 8227 8192000000 10000316208 10404241408 1057602752 11.434 97 41 83 1 1089588752 22254528 9731472
02:28:47 57100 8192000000 9948758176 10333917184 1006461056 10.941 95 29 55 0 1028762640 15504528 6797056
02:30:44 7421 8192000000 9953173152 10345529344 1000518640 10.884 96 47 92 2 1029288128 18735952 10033536
02:31:53 5209 8192000000 9936060160 10315853824 1005389888 10.931 98 54 108 1 1031661056 14199024 12072144
02:33:00 7761 8192000000 9962967136 10348425216 1017572096 11.049 98 29 58 2 1054876832 30496768 6807968
02:34:10 47446 8192000000 9958198400 10350129152 1046651968 11.329 100 38 76 0 1068371168 12971664 8747536
02:35:58 7496 8192000000 9975863984 10373193728 1058126112 11.439 100 47 94 3 1087381632 18481888 10773632
02:37:08 4944 8192000000 9984557296 10380029952 1074504800 11.595 100 47 92 1 1094096736 9020544 10571392
02:38:18 6032 8192000000 10052200112 10439925760 1101674432 11.854 100 62 123 0 1154041456 39940336 12426688
02:39:27 51330 8192000000 10094654208 10498588672 1118336576 12.011 100 50 101 0 1222354384 92885024 11132784
02:41:29 37091 8192000000 10132615728 10548523008 1132933840 12.149 100 37 74 1 1226708800 85521488 8253472
02:43:14 31502 8192000000 10169817456 10581331968 1146665568 12.278 100 36 72 1 1284333216 129591520 8076128
02:44:53 7128 8192000000 10202931440 10607185920 1151736304 12.326 100 62 123 1 1309225440 144514000 12975136
02:46:04 7247 8192000000 10214325504 10642870272 1155605680 12.362 100 58 116 1 1338170304 169349552 13215072
02:47:15 6166 8192000000 10235346944 10644135936 1169698368 12.494 100 73 144 1 1339581568 153932960 15950240
02:48:28 9897 8192000000 10300809920 10711113728 1173570688 12.530 99 72 143 2 1394914720 205678752 15665280
02:49:47 4068 8192000000 10293955616 10725736448 1177042928 12.563 100 65 128 2 1401708528 208879424 15786176
02:50:55 5944 8192000000 10321416256 10736758784 1168652208 12.484 99 57 110 1 1409731856 228648016 12431632
02:52:05 12015 8192000000 10348719968 10758754304 1168004048 12.478 100 75 150 2 1447088448 262729136 16355264
02:53:23 6436 8192000000 10288347936 10719109120 1175725520 12.550 99 64 127 2 1384168944 193632208 14811216
02:54:36 2393 8192000000 10316694688 10719154176 1144267968 12.256 98 80 159 0 1393578608 230195056 19115584
02:55:44 4843 8192000000 10371435488 10777513984 1149204592 12.302 100 73 145 0 1466940576 301162608 16573376
02:56:55 2889 8192000000 10307862736 10758508544 1151983680 12.328 100 48 96 1 1429184176 265607648 11592848
02:58:13 7199 8192000000 10363446368 10781753344 1172768832 12.523 100 50 99 0 1458855328 274073120 12013376
02:59:26 7823 8192000000 10357328144 10811510784 1148563936 12.296 99 37 74 1 1453954112 295148624 10241552
03:00:36 13018 8192000000 10386387744 10836127744 1160278192 12.406 100 32 64 0 1500918336 332581248 8058896
03:01:50 20303 8192000000 10354805104 10792873984 1133970256 12.159 100 29 58 3 1458084512 315785344 8328912
03:03:10 19893 8192000000 10267657328 10776018944 1105798752 11.893 98 24 48 0 1412251216 299951904 6500560
03:04:34 72750 8192000000 10096492816 10522152960 1091721632 11.759 97 37 73 0 1163647920 63483136 8443152
03:06:49 42573 8192000000 10048274000 10535018496 1073236768 11.583 97 10 20 0 1156361360 80726640 2397952
03:08:33 101595 8192000000 10038973616 10499874816 1053689632 11.396 95 18 36 0 1098692944 41484816 3518496
03:11:36 75534 8192000000 10019166864 10450051072 1068405264 11.537 96 18 36 0 1098369264 25992064 3971936
03:13:52 64431 8192000000 10056379920 10471669760 1098716304 11.825 98 53 106 1 1135285488 25598048 10971136
03:15:58 80925 8192000000 10072696096 10514702336 1118709280 12.015 99 23 46 0 1166074832 43017744 4347808
03:18:20 57367 8192000000 10146387216 10605203456 1144791152 12.261 100 30 59 1 1266729392 115610560 6327680
03:20:18 42666 8192000000 10180320176 10652733440 1140142800 12.217 99 21 42 0 1270053536 125065872 4844864
03:22:04 56886 8192000000 10120684480 10587369472 1147417120 12.285 100 15 30 0 1220914016 69988880 3508016
03:24:00 67275 8192000000 10144845392 10642079744 1140419040 12.219 99 14 28 0 1234431584 91028272 2984272
03:26:18 79644 8192000000 10077318096 10509824000 1123819536 12.063 98 20 41 1 1166739104 38399680 4519888
03:28:40 60563 8192000000 10112006320 10539749376 1160546288 12.408 100 35 70 0 1215389152 47433472 7409392
03:30:51 130588 8192000000 10117209824 10591567872 1157220192 12.377 99 14 28 0 1254569600 94074592 3274816
03:34:03 65744 8192000000 10106527440 10545934336 1154632080 12.353 99 13 25 0 1191573216 34268704 2672432
03:36:09 55625 8192000000 10115254448 10555609088 1163708192 12.438 100 21 42 0 1202827840 34483200 4636448
03:38:10 101564 8192000000 10123262576 10569437184 1139525360 12.211 98 30 60 0 1192195008 46267584 6402064
03:41:02 67151 8192000000 10112556656 10626916352 1130665248 12.128 98 34 68 0 1216298224 78641392 6991584
03:43:29 67823 8192000000 10071732848 10533191680 1121241968 12.039 98 9 18 0 1178249232 55105360 1901904
03:45:42 46228 8192000000 10098517408 10565226496 1149921248 12.309 100 14 27 1 1188993536 36269008 2803280
03:47:29 46375 8192000000 10094831584 10553585664 1110757552 11.940 96 23 46 0 1157153616 41780064 4616000
03:49:23 58943 8192000000 10092271392 10542084096 1107147120 11.905 97 16 32 0 1188332656 77534144 3651392
03:51:22 27371 8192000000 10102990272 10550915072 1127673968 12.099 99 26 52 0 1203037536 69894672 5468896
03:53:02 64077 8192000000 10111232352 10570985472 1101987568 11.856 97 14 28 0 1189748320 85061856 2698896
03:55:13 31622 8192000000 10094535184 10558406656 1103943648 11.875 98 17 33 1 1177739040 70393936 3401456
03:56:46 12751 8192000000 10085392992 10501713920 1117345920 12.002 99 50 100 3 1160328256 33163680 9818656
03:58:02 81568 8192000000 10072137408 10511728640 1137113632 12.188 100 31 62 0 1184768208 40961472 6693104
04:00:24 96580 8192000000 10125019568 10583937024 1134798720 12.167 99 18 37 0 1256062224 116985040 4278464
04:03:03 59290 8192000000 10069301648 10516742144 1113657392 11.967 98 21 43 1 1180476896 61757024 5062480
04:05:04 39754 8192000000 10069127152 10524114944 1123747792 12.062 99 10 20 1 1158177296 32490720 1938784
04:07:16 51432 8192000000 10096771776 10555412480 1105249088 11.887 97 27 54 0 1180313904 69386144 5678672
04:09:22 90476 8192000000 10094659824 10539077632 1106961648 11.904 97 17 34 0 1135589856 25145648 3482560
04:11:53 42504 8192000000 10117790496 10570076160 1136002368 12.178 99 25 50 1 1222760608 81909632 4848608
04:13:36 123966 8192000000 10118132080 10609176576 1088026016 11.724 95 19 38 0 1148532672 56474320 4032336
04:17:32 86172 8192000000 10118159440 10571841536 1148258816 12.293 100 55 110 2 1226186240 66921888 11005536
04:20:01 55403 8192000000 10075343664 10534965248 1138276384 12.199 99 5 10 1 1171334960 32239216 819360
04:21:56 49254 8192000000 10084556848 10553282560 1145291120 12.265 100 13 25 2 1181137168 33195424 2650624
04:24:04 67664 8192000000 10125527664 10590425088 1156439088 12.370 100 8 16 1 1236653232 77748416 2465728
04:26:12 63146 8192000000 10144948304 10613825536 1125766480 12.081 97 19 38 0 1220870448 90613216 4490752
04:28:29 97847 8192000000 10120882656 10592948224 1158617024 12.390 100 20 40 0 1233011760 69941792 4452944
04:31:10 26330 8192000000 10081499264 10514612224 1127429488 12.097 98 27 54 0 1167371120 34564048 5377584
04:32:46 48679 8192000000 10166510608 10644664320 1136595376 12.183 99 38 76 1 1245391296 101221872 7574048
04:35:06 79249 8192000000 10077742704 10543751168 1134765184 12.166 99 35 68 3 1167114464 25836912 6512368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2021 04:38:34 to 23-11-2021 04:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 04:38:50 to 23-11-2021 04:38:52.

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          223720
MON$OLDEST_ACTIVE               224463
MON$OLDEST_SNAPSHOT             224463
MON$NEXT_TRANSACTION            224463
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                       2047712
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.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:209: 
:210: mscs-test-161.budzdorov.ru    Tue Nov 23 04:37:22 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Nov 23 04:37:25 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Nov 23 04:37:25 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:220: 
--- end of diff output ---

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

23.11.21 04:38:53. 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-23 04:38:55 No severe PSQL-related problems occured
23.11.21 04:38:56. End of report.