Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.05.2022 10:39
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 16151180 kB
MemAvailable 31475812 kB
Buffers 0 kB
Cached 15577684 kB
SwapCached 2628 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.2
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 2022-05-16 10:37:17
DTS_END                         2022-05-16 10:37:17.4480
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/41298
TRN_ID                          227121
ATT_ID                          597
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: 9197.91 26134 1103749
customer order: creation 175.29 450 21035
customer order: refuse 14.48 404 1738
order to supplier: creation 47.60 1292 5712
order to supplier: removal 3.38 165211 406
invoice (draft): creation 45.82 3476 5498
invoice (draft): removal 51.29 1007 6155
invoice accept: apply 42.43 25782 5092
invoice accept: cancel 46.40 1087 5568
customer reserve: creation 3910.18 247 469222
customer reserve: removal 4563.43 96 547611
realization accept: apply 84.18 263 10102
realization accept: cancel 82.13 164 9855
payment from customer: creation 59.79 170 7175
payment from customer: removal 5.99 122 719
payment to supplier: creation 49.35 200 5922
payment to supplier: removal 3.67 124 440
service: total inventory turnovers 3.82 4260 458
service: total monetary turnovers 7.68 373 922
service: aggregate perf. data 0.95 16327 114
service: refresh index statistics 0.04 301629 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-05-2022 10:39:26 to 16-05-2022 10:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9569
TEST_TIME 2 7912
TEST_TIME 3 6771
TEST_TIME 4 6791
TEST_TIME 5 6483
TEST_TIME 6 6165
TEST_TIME 7 6374
TEST_TIME 8 6305
TEST_TIME 9 6410
TEST_TIME 10 6668
TEST_TIME 11 6744
TEST_TIME 12 6897
TEST_TIME 13 7046
TEST_TIME 14 7187
TEST_TIME 15 7328
TEST_TIME 16 7353
TEST_TIME 17 7577
TEST_TIME 18 7792
TEST_TIME 19 8263
TEST_TIME 20 8235
TEST_TIME 21 8366
TEST_TIME 22 8316
TEST_TIME 23 8532
TEST_TIME 24 8392
TEST_TIME 25 8519
TEST_TIME 26 8525
TEST_TIME 27 8653
TEST_TIME 28 8878
TEST_TIME 29 9257
TEST_TIME 30 9726
TEST_TIME 31 9884
TEST_TIME 32 10187
TEST_TIME 33 10452
TEST_TIME 34 11217
TEST_TIME 35 11330
TEST_TIME 36 11256
TEST_TIME 37 11160
TEST_TIME 38 11205
TEST_TIME 39 11012
TEST_TIME 40 10811
TEST_TIME 41 10740
TEST_TIME 42 10745
TEST_TIME 43 10635
TEST_TIME 44 10605
TEST_TIME 45 10483
TEST_TIME 46 10376
TEST_TIME 47 10305
TEST_TIME 48 10249
TEST_TIME 49 10292
TEST_TIME 50 10300
TEST_TIME 51 10239
TEST_TIME 52 10188
TEST_TIME 53 10126
TEST_TIME 54 10126
TEST_TIME 55 10122
TEST_TIME 56 10054
TEST_TIME 57 9953
TEST_TIME 58 9937
TEST_TIME 59 10006
TEST_TIME 60 9988
TEST_TIME 61 10025
TEST_TIME 62 10044
TEST_TIME 63 10032
TEST_TIME 64 10035
TEST_TIME 65 10026
TEST_TIME 66 10038
TEST_TIME 67 10035
TEST_TIME 68 9977
TEST_TIME 69 9908
TEST_TIME 70 9886
TEST_TIME 71 9849
TEST_TIME 72 9778
TEST_TIME 73 9775
TEST_TIME 74 9697
TEST_TIME 75 9642
TEST_TIME 76 9634
TEST_TIME 77 9589
TEST_TIME 78 9533
TEST_TIME 79 9521
TEST_TIME 80 9548
TEST_TIME 81 9511
TEST_TIME 82 9538
TEST_TIME 83 9514
TEST_TIME 84 9467
TEST_TIME 85 9567
TEST_TIME 86 9604
TEST_TIME 87 9600
TEST_TIME 88 9750
TEST_TIME 89 9742
TEST_TIME 90 9689
TEST_TIME 91 9625
TEST_TIME 92 9644
TEST_TIME 93 9606
TEST_TIME 94 9558
TEST_TIME 95 9549
TEST_TIME 96 9483
TEST_TIME 97 9415
TEST_TIME 98 9394
TEST_TIME 99 9335
TEST_TIME 100 9401
TEST_TIME 101 9366
TEST_TIME 102 9355
TEST_TIME 103 9376
TEST_TIME 104 9386
TEST_TIME 105 9385
TEST_TIME 106 9360
TEST_TIME 107 9355
TEST_TIME 108 9328
TEST_TIME 109 9451
TEST_TIME 110 9501
TEST_TIME 111 9468
TEST_TIME 112 9478
TEST_TIME 113 9471
TEST_TIME 114 9502
TEST_TIME 115 9459
TEST_TIME 116 9397
TEST_TIME 117 9355
TEST_TIME 118 9348
TEST_TIME 119 9295
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-05-2022 10:39:30 to 16-05-2022 10:39:33.

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 1536152 1536152 0 0.00 0 20294 13 0 0
doc_list_biud 1536141 1536141 0 0.00 0 39088 40 0 0
sp_add_invoice_to_stock 5174 5092 82 1.58 48 184841 25783 0 82
sp_cancel_adding_invoice 5568 5568 0 0.00 5 55529 1087 0 0
sp_cancel_client_order 1738 1738 0 0.00 6 8965 405 0 0
sp_cancel_customer_reserve 547611 547611 0 0.00 1 45804 96 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 5730 122 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 4 5605 125 0 0
sp_cancel_supplier_invoice 6161 6155 6 0.09 3 19853 1008 0 6
sp_cancel_supplier_order 406 406 0 0.00 9 1549476 165211 0 0
sp_cancel_write_off 9855 9855 0 0.00 2 9387 165 0 0
sp_client_order 21035 21035 0 0.00 6 18882 451 0 0
sp_customer_reserve 469222 469222 0 0.00 0 37105 248 0 0
sp_fill_shopping_cart 32241 32241 0 0.00 2 24923 84 0 0
sp_fill_shopping_cart_clo_res 469182 469182 0 0.00 0 18967 18 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 21770 281 0 0
sp_kill_cost_storno 16582 16582 0 0.00 0 10261 68 0 0
sp_kill_qstorno_ret_qs2qd 1111638 1111638 0 0.00 0 39078 42 0 0
sp_kill_qty_storno 586482 586482 0 0.00 0 39087 97 0 0
sp_lock_selected_doc 21025 21025 0 0.00 0 9650 6 0 0
sp_make_cost_storno 28246 28246 0 0.00 0 20283 77 0 0
sp_multiply_rows_for_pdistr 28246 28246 0 0.00 0 13508 36 0 0
sp_multiply_rows_for_qdistr 21035 21035 0 0.00 0 14145 174 0 0
sp_pay_from_customer 7175 7175 0 0.00 6 16616 170 0 0
sp_pay_to_supplier 5922 5922 0 0.00 7 19755 200 0 0
sp_payment_common 13097 13097 0 0.00 2 12187 49 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 8953 329 0 0
sp_qd_handle_on_invoice_upd_sts 10615 10615 0 0.00 0 17552 495 0 0
sp_qd_handle_on_reserve_upd_sts 10102 10102 0 0.00 0 11258 60 0 0
sp_reserve_write_off 10102 10102 0 0.00 9 24301 264 0 0
sp_supplier_invoice 5498 5498 0 0.00 10 133828 3476 0 0
sp_supplier_order 5712 5712 0 0.00 8 41974 1293 0 0
srv_aggregate_perf_data 114 114 0 0.00 2798 221253 16328 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 890 129812 37406 0 0
srv_find_qd_qs_mism 1054200 1054200 0 0.00 0 13481 11 0 0
srv_make_invnt_saldo 458 458 0 0.00 77 67762 4261 0 0
srv_make_money_saldo 922 922 0 0.00 2 12792 373 0 0
srv_recalc_idx_stat 5 5 0 0.00 180449 401553 301630 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 1 20741 870 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 6537 309 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 2631 31 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 3267 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1334 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1464 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 924 31 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 103888 6831 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 3035 22 0 0
t$perf-norm:sp_client_order 21035 21035 0 0.00 0 7409 36 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 2788 18 0 0
t$perf-norm:sp_pay_from_customer 7175 7175 0 0.00 0 1605 16 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 8679 21 0 0
t$perf-norm:sp_reserve_write_off 10102 10102 0 0.00 0 8101 22 0 0
t$perf-norm:sp_supplier_invoice 5498 5498 0 0.00 0 7389 32 0 0
t$perf-norm:sp_supplier_order 5712 5712 0 0.00 0 13312 34 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 669 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1662 33 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 645 10 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 406 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 187 49 0 0
v_add_invoice_to_stock 10254 10090 164 1.59 0 8029 25 0 164
v_all_customers 38032 38032 0 0.00 0 68 4 0 0
v_all_suppliers 31056 31056 0 0.00 0 114 4 0 0
v_all_wares 42066 42066 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 687 13 0 0
v_cancel_client_order 3476 3476 0 0.00 0 2705 11 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 5679 14 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 792 10 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 2325 12 0 0
v_cancel_supplier_invoice 810 798 12 1.48 0 859 21 0 12
v_cancel_supplier_order 812 812 0 0.00 0 663 11 0 0
v_cancel_write_off 1998 1998 0 0.00 0 1478 23 0 0
v_max_id_clo_ord 5712 5712 0 0.00 0 3892 5 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2291 16 0 0
v_max_id_ord_sup 5496 5496 0 0.00 0 4132 7 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 159 7 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 502 7 0 0
v_min_id_clo_ord 5712 5712 0 0.00 0 2220 8 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 1952 10 0 0
v_min_id_ord_sup 5496 5496 0 0.00 0 9002 11 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 7827 22 0 0
v_min_non_paid_realizn 7175 7175 0 0.00 0 12181 21 0 0
v_our_firm 10552 10552 0 0.00 0 61 4 0 0
v_reserve_write_off 20202 20202 0 0.00 0 3193 10 0 0
x_lock_depdocs_on_canc_invoice 11324 11324 0 0.00 0 17761 120 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 17400 367 0 0
x_make_qstorno_1000_1200 5712 5712 0 0.00 2 41960 1116 0 0
x_make_qstorno_1200_2000 5498 5498 0 0.00 3 133431 3249 0 0
x_make_qstorno_2100_3300 469221 469221 0 0.00 0 36625 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-05-2022 10:39:34 to 16-05-2022 10:39:38.

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:21 4 8192000000 8799923200 8811696128 5700128 0.069 2 2 3 0 5844784 83296 61360
08:08:22 33744 8192000000 9718302080 10019471360 798714704 8.883 100 85 170 0 827119168 7796304 20608160
08:10:00 24577 8192000000 9766188064 10075140096 834435184 9.244 100 83 166 0 866688064 12847056 19405824
08:11:28 9152 8192000000 9805220560 10126495744 872500736 9.625 100 86 172 2 913827040 22133392 19192912
08:12:57 5794 8192000000 9870264912 10205986816 921069792 10.107 100 76 151 0 986865152 49799776 15995584
08:14:08 4583 8192000000 9936270816 10282950656 967701312 10.564 100 88 176 0 1048018016 61501520 18815184
08:15:16 2274 8192000000 9992583328 10346876928 1001847120 10.896 100 93 186 0 1104470704 83306544 19317040
08:16:20 1225 8192000000 9996297168 10358755328 1029640176 11.165 100 91 171 0 1118889216 71703904 17545136
08:17:24 901 8192000000 10017166144 10388942848 1050465472 11.365 100 98 187 1 1140655680 71422304 18767904
08:18:26 988 8192000000 10033135344 10405400576 1058915072 11.446 100 95 185 0 1154405264 77345008 18145184
08:19:28 1040 8192000000 10053899776 10433368064 1068670736 11.539 100 96 187 1 1170792800 82949024 19173040
08:20:30 1410 8192000000 10016200704 10403950592 1072512608 11.576 100 73 140 0 1140079552 53884096 13682848
08:21:34 1097 8192000000 10039152544 10424090624 1076504160 11.614 100 89 172 6 1158032704 64707504 16821040
08:22:36 1202 8192000000 10037361328 10425540608 1081692816 11.664 100 90 172 1 1160327296 62323456 16311024
08:23:38 3199 8192000000 10038606288 10428944384 1087155040 11.716 100 79 146 2 1159700848 58351568 14194240
08:24:42 1051 8192000000 10041724528 10431082496 1090647376 11.749 100 93 181 1 1162545376 52038640 19859360
08:25:44 1234 8192000000 10057164336 10444279808 1091540080 11.757 100 93 186 0 1177812016 67594128 18677808
08:26:46 1276 8192000000 10064074880 10451034112 1085076288 11.696 100 96 184 1 1183717472 79420832 19220352
08:27:48 2506 8192000000 10050550288 10446647296 1090103856 11.744 100 74 127 2 1165134720 61465136 13565728
08:28:51 1131 8192000000 10053934528 10451001344 1101797712 11.855 100 80 153 1 1184800256 66262704 16739840
08:29:53 4534 8192000000 10040967728 10434650112 1086921248 11.713 100 74 146 2 1168339488 67096016 14322224
08:30:58 1240 8192000000 10035405312 10415292416 1065425488 11.508 100 91 175 0 1156161312 72794288 17941536
08:32:00 981 8192000000 10008612896 10393591808 1055280944 11.411 100 87 158 5 1130959792 57165200 18513648
08:33:02 1922 8192000000 9999899472 10391015424 1051450896 11.375 99 62 122 2 1118434720 54085552 12898272
08:34:05 1146 8192000000 9975468656 10347495424 1037136592 11.237 100 94 177 0 1101401328 46410976 17853760
08:35:07 2045 8192000000 9966784400 10337464320 1011618064 10.991 99 77 149 0 1089291776 62429312 15244400
08:36:10 5344 8192000000 10010745872 10393772032 1045002432 11.313 100 71 142 0 1131034144 71256304 14775408
08:37:16 5298 8192000000 10010048224 10389893120 1042978368 11.293 100 77 144 0 1128671344 71753424 13939552
08:38:26 10953 8192000000 10027725216 10410450944 1070436496 11.556 100 82 153 1 1146907744 61981328 14489920
08:39:43 1670 8192000000 10034240256 10414669824 1072178320 11.573 100 84 153 1 1151330816 64954176 14198320
08:40:52 5305 8192000000 10068275360 10453962752 1085380800 11.699 100 69 132 0 1179710432 80921088 13408544
08:42:03 5777 8192000000 10088560256 10474885120 1101244432 11.849 100 77 142 5 1206770496 90311936 15214128
08:43:18 3235 8192000000 10132442704 10523234304 1112261200 11.954 100 77 150 0 1246156624 117688368 16207056
08:44:25 2343 8192000000 10155925632 10560221184 1123131232 12.057 100 91 176 0 1267741648 123535488 21074928
08:45:29 5031 8192000000 10194716832 10598096896 1138702752 12.203 100 80 161 0 1299537728 143609024 17225952
08:46:40 3216 8192000000 10211414592 10620530688 1138561104 12.202 100 81 158 0 1320984992 164704208 17719680
08:47:48 1038 8192000000 10243109984 10648666112 1158287472 12.387 100 95 184 1 1356552016 178096928 20167616
08:48:52 1033 8192000000 10285827184 10688970752 1170378000 12.500 100 88 174 0 1395482688 206396064 18708624
08:49:55 1519 8192000000 10320238240 10738946048 1177368704 12.566 100 90 174 0 1430573008 234142064 19062240
08:51:02 1308 8192000000 10345582064 10744803328 1184431920 12.632 100 94 179 2 1450382480 246472176 19478384
08:52:06 1045 8192000000 10353511152 10757337088 1185813568 12.644 100 98 187 0 1461624048 255429904 20380576
08:53:10 1168 8192000000 10376742992 10780102656 1174472432 12.539 99 90 176 0 1487444144 292692128 20279584
08:54:15 883 8192000000 10385558496 10824290304 1176084464 12.554 100 96 183 1 1490820624 294111104 20625056
08:55:20 1596 8192000000 10365561280 10801205248 1176247072 12.555 100 81 156 0 1475670064 280174960 19248032
08:56:25 7742 8192000000 10387808304 10836721664 1182417552 12.613 100 57 113 2 1501037408 304263520 14356336
08:57:41 1721 8192000000 10415130416 10838900736 1187916352 12.664 100 84 163 0 1521045760 313538320 19591088
08:58:47 912 8192000000 10421941520 10856353792 1189356192 12.677 100 84 164 0 1523790560 315463088 18971280
08:59:52 951 8192000000 10483980944 10911027200 1202094976 12.796 100 100 193 1 1583908464 358705280 23108208
09:00:54 7943 8192000000 10496159824 10921861120 1191222064 12.695 100 60 117 1 1588778272 382958960 14597248
09:02:15 1485 8192000000 10554537280 10978918400 1205731072 12.830 100 85 168 0 1663381040 436795008 20854960
09:03:19 5585 8192000000 10577135232 11030196224 1204928128 12.822 100 74 143 1 1702756688 480164016 17664544
09:04:27 8770 8192000000 10572818624 11013857280 1182422960 12.613 100 46 90 0 1680710480 485711984 12575536
09:05:41 7820 8192000000 10546203184 10994896896 1178717456 12.578 100 45 88 2 1655297536 464702224 11877856
09:07:00 12253 8192000000 10453436256 10965471232 1177769120 12.569 100 46 90 1 1562212736 373634880 10808736
09:08:19 36789 8192000000 10430750624 10891010048 1159032048 12.394 99 32 62 1 1500338384 333457232 7849104
09:10:19 35692 8192000000 10305914992 10825232384 1133371568 12.153 99 18 36 0 1423193520 284958288 4863664
09:12:37 101067 8192000000 10040037584 10500608000 1060911744 11.465 93 13 26 0 1091910896 28391088 2608064
09:15:23 110747 8192000000 10088000544 10513317888 1087432576 11.718 96 17 35 0 1123415952 32060624 3922752
09:18:21 95930 8192000000 10100416080 10588274688 1133083840 12.150 100 34 67 3 1228286192 87245728 7956624
09:20:58 33856 8192000000 10118136864 10555641856 1146701472 12.279 100 52 96 0 1195846832 40972944 8172416
09:22:34 21470 8192000000 10085083664 10530107392 1135389296 12.172 100 38 76 0 1193658256 50301376 7967584
09:24:01 15125 8192000000 10116236560 10550407168 1141871376 12.233 100 49 95 1 1223480112 71479088 10129648
09:25:18 14275 8192000000 10161896432 10583695360 1138854448 12.205 100 66 129 1 1265427200 112519376 14053376
09:26:33 108697 8192000000 10098195952 10573045760 1107598096 11.910 97 12 24 0 1193812240 83452352 2761792
09:29:55 35864 8192000000 10100799232 10564489216 1130786592 12.129 99 20 40 1 1197995392 62693568 4515232
09:31:34 62639 8192000000 10077880976 10544816128 1113322368 11.964 97 11 22 1 1168770688 53273104 2175216
09:34:15 13522 8192000000 10088120944 10513149952 1110717424 11.939 97 64 114 2 1164233776 41169088 12347264
09:35:32 44214 8192000000 10136816544 10591326208 1142460864 12.239 100 35 69 0 1238716832 89048224 7207744
09:37:31 79461 8192000000 10102727520 10583044096 1113483408 11.965 97 19 38 0 1187430496 70108720 3838368
09:39:57 59610 8192000000 10088309408 10526502912 1130118352 12.122 99 27 54 1 1172549488 37443136 4988000
09:41:57 12339 8192000000 10082557280 10518675456 1117615472 12.004 100 42 84 0 1178197552 51974800 8607280
09:43:10 22217 8192000000 10096902656 10527367168 1121858000 12.045 100 45 86 0 1196231088 65364496 9008592
09:44:33 60529 8192000000 10093431680 10556067840 1114966576 11.979 99 35 68 3 1188397184 65939584 7491024
09:46:42 53627 8192000000 10064269360 10514219008 1122115920 12.047 99 33 56 1 1159939984 30936656 6887408
09:48:37 27845 8192000000 10078575392 10528948224 1143548752 12.249 100 28 52 1 1188744448 40595696 4600000
09:50:15 76940 8192000000 10069800256 10536087552 1133419328 12.154 99 15 30 1 1194421840 58082784 2919728
09:52:32 66569 8192000000 10045985536 10514563072 1106174464 11.896 97 11 22 0 1143403136 35003056 2225616
09:54:42 56238 8192000000 10122221680 10555596800 1110503984 11.937 97 30 58 3 1197899840 80937840 6458016
09:56:41 15908 8192000000 10148088128 10592313344 1138628560 12.203 99 35 70 2 1233174544 86851808 7694176
09:57:57 16384 8192000000 10156461840 10595311616 1160087552 12.404 100 35 68 1 1252539232 84425376 8026304
09:59:15 47330 8192000000 10178104800 10641399808 1133755344 12.157 98 27 54 1 1262830656 122698160 6377152
10:01:02 14229 8192000000 10209844400 10653024256 1148372720 12.294 99 41 82 1 1289046736 131904528 8769488
10:02:19 57321 8192000000 10187981712 10683707392 1139424224 12.210 99 23 45 1 1351702464 206911568 5366672
10:04:16 117146 8192000000 10139722176 10610327552 1098626848 11.825 96 26 52 1 1219815472 115734096 5454528
10:07:14 125060 8192000000 10111053808 10563837952 1126175712 12.085 99 22 44 1 1204295152 73535632 4583808
10:10:20 87475 8192000000 10060058928 10537435136 1127968320 12.102 100 13 27 0 1187366352 56822736 2575296
10:12:55 54448 8192000000 10081160496 10534842368 1121065056 12.037 99 11 22 0 1159651104 36263168 2322880
10:15:05 121359 8192000000 10119965520 10580025344 1126986432 12.093 99 12 24 1 1208493424 78835328 2671664
10:18:07 23472 8192000000 10087045376 10527023104 1137448304 12.192 99 30 58 1 1186662208 43275232 5938672
10:19:37 64291 8192000000 10105636848 10570113024 1066339008 11.517 93 31 62 0 1142208512 69683232 6186272
10:21:44 51277 8192000000 10112777728 10559340544 1126066368 12.084 99 38 75 0 1212311920 78889424 7356128
10:23:35 43569 8192000000 10170555600 10603491328 1153248912 12.340 100 46 90 1 1256314080 93934432 9130736
10:25:21 22352 8192000000 10193615664 10628296704 1145271280 12.265 99 52 100 3 1278198976 123092592 9835104
10:26:44 114227 8192000000 10099067296 10569216000 1098793488 11.826 96 16 32 1 1164367680 62442848 3131344
10:29:40 88238 8192000000 10087946672 10554429440 1121769856 12.044 99 26 52 0 1213556928 85694016 6093056
10:32:11 129811 8192000000 10059536576 10504351744 1118957168 12.017 99 17 32 1 1168238464 46025936 3255360
10:35:41 71354 8192000000 10069827888 10534465536 1124313184 12.068 100 9 18 1 1176365776 50138144 1914448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-05-2022 10:39:40 to 16-05-2022 10:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-05-2022 10:39:57 to 16-05-2022 10:39:59.

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          224628
MON$OLDEST_ACTIVE               227236
MON$OLDEST_SNAPSHOT             227236
MON$NEXT_TRANSACTION            227236
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                       2079200
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:223: 
:224: mscs-test-161.budzdorov.ru    Mon May 16 10:37:33 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
:227: 
:228: mscs-test-161.budzdorov.ru    Mon May 16 10:37:34 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:230: 
--- end of diff output ---

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

16.05.22 10:40:00. 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
2022-05-16 10:40:02 No severe PSQL-related problems occured
16.05.22 10:40:04. End of report.