Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2021 01: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 16693008 kB
MemAvailable 31448760 kB
Buffers 0 kB
Cached 15011212 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 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-19 01:37:55
DTS_END                         2021-12-19 01:37:55.5470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60456
TRN_ID                          236270
ATT_ID                          633
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: 9103.08 22661 1092369
customer order: creation 167.13 374 20056
customer order: refuse 13.54 379 1625
order to supplier: creation 45.59 1324 5471
order to supplier: removal 3.38 105412 406
invoice (draft): creation 44.50 3696 5340
invoice (draft): removal 53.10 835 6372
invoice accept: apply 40.70 17514 4884
invoice accept: cancel 47.47 781 5696
customer reserve: creation 3761.94 171 451433
customer reserve: removal 4635.99 59 556319
realization accept: apply 79.41 195 9529
realization accept: cancel 84.84 114 10181
payment from customer: creation 57.76 130 6931
payment from customer: removal 5.38 65 646
payment to supplier: creation 47.43 132 5691
payment to supplier: removal 3.12 84 374
service: total inventory turnovers 4.64 1246 557
service: total monetary turnovers 6.45 30 774
service: aggregate perf. data 0.67 26985 80
service: refresh index statistics 0.03 293697 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-12-2021 01:40:10 to 19-12-2021 01:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2622
TEST_TIME 2 2271
TEST_TIME 3 3321
TEST_TIME 4 3444
TEST_TIME 5 3566
TEST_TIME 6 3752
TEST_TIME 7 3990
TEST_TIME 8 4548
TEST_TIME 9 5004
TEST_TIME 10 4981
TEST_TIME 11 4855
TEST_TIME 12 5176
TEST_TIME 13 5508
TEST_TIME 14 5894
TEST_TIME 15 6208
TEST_TIME 16 6458
TEST_TIME 17 6809
TEST_TIME 18 7006
TEST_TIME 19 7052
TEST_TIME 20 8061
TEST_TIME 21 8010
TEST_TIME 22 8090
TEST_TIME 23 8449
TEST_TIME 24 8438
TEST_TIME 25 8920
TEST_TIME 26 8980
TEST_TIME 27 10101
TEST_TIME 28 10399
TEST_TIME 29 10531
TEST_TIME 30 10283
TEST_TIME 31 10183
TEST_TIME 32 9956
TEST_TIME 33 9867
TEST_TIME 34 9966
TEST_TIME 35 9950
TEST_TIME 36 9843
TEST_TIME 37 9835
TEST_TIME 38 9746
TEST_TIME 39 9710
TEST_TIME 40 9629
TEST_TIME 41 9635
TEST_TIME 42 9472
TEST_TIME 43 9406
TEST_TIME 44 9401
TEST_TIME 45 9474
TEST_TIME 46 9469
TEST_TIME 47 9486
TEST_TIME 48 9341
TEST_TIME 49 9268
TEST_TIME 50 9222
TEST_TIME 51 9191
TEST_TIME 52 9267
TEST_TIME 53 9311
TEST_TIME 54 9338
TEST_TIME 55 9250
TEST_TIME 56 9322
TEST_TIME 57 9377
TEST_TIME 58 9341
TEST_TIME 59 9320
TEST_TIME 60 9353
TEST_TIME 61 9379
TEST_TIME 62 9435
TEST_TIME 63 9417
TEST_TIME 64 9454
TEST_TIME 65 9357
TEST_TIME 66 9358
TEST_TIME 67 9405
TEST_TIME 68 9381
TEST_TIME 69 9326
TEST_TIME 70 9296
TEST_TIME 71 9269
TEST_TIME 72 9249
TEST_TIME 73 9248
TEST_TIME 74 9171
TEST_TIME 75 9178
TEST_TIME 76 9221
TEST_TIME 77 9175
TEST_TIME 78 9190
TEST_TIME 79 9193
TEST_TIME 80 9162
TEST_TIME 81 9133
TEST_TIME 82 9202
TEST_TIME 83 9157
TEST_TIME 84 9201
TEST_TIME 85 9219
TEST_TIME 86 9251
TEST_TIME 87 9274
TEST_TIME 88 9332
TEST_TIME 89 9362
TEST_TIME 90 9414
TEST_TIME 91 9465
TEST_TIME 92 9387
TEST_TIME 93 9313
TEST_TIME 94 9257
TEST_TIME 95 9247
TEST_TIME 96 9231
TEST_TIME 97 9189
TEST_TIME 98 9213
TEST_TIME 99 9220
TEST_TIME 100 9193
TEST_TIME 101 9161
TEST_TIME 102 9185
TEST_TIME 103 9193
TEST_TIME 104 9221
TEST_TIME 105 9230
TEST_TIME 106 9184
TEST_TIME 107 9218
TEST_TIME 108 9254
TEST_TIME 109 9340
TEST_TIME 110 9325
TEST_TIME 111 9279
TEST_TIME 112 9250
TEST_TIME 113 9230
TEST_TIME 114 9217
TEST_TIME 115 9224
TEST_TIME 116 9189
TEST_TIME 117 9166
TEST_TIME 118 9144
TEST_TIME 119 9148
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2021 01:40:14 to 19-12-2021 01:40:17.

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 1508919 1508919 0 0.00 0 21958 6 0 0
doc_list_biud 1508908 1508908 0 0.00 0 38503 28 0 0
sp_add_invoice_to_stock 5012 4884 128 2.55 30 159974 17515 0 128
sp_cancel_adding_invoice 5696 5696 0 0.00 5 74803 781 0 0
sp_cancel_client_order 1625 1625 0 0.00 4 13435 379 0 0
sp_cancel_customer_reserve 556319 556319 0 0.00 1 22483 60 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 954 65 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 2 6944 84 0 0
sp_cancel_supplier_invoice 6386 6372 14 0.21 2 25071 835 0 14
sp_cancel_supplier_order 406 406 0 0.00 6 983994 105413 0 0
sp_cancel_write_off 10181 10181 0 0.00 1 9068 114 0 0
sp_client_order 20056 20056 0 0.00 4 60270 374 0 0
sp_customer_reserve 451433 451433 0 0.00 0 42320 172 0 0
sp_fill_shopping_cart 30858 30858 0 0.00 1 21658 43 0 0
sp_fill_shopping_cart_clo_res 451423 451423 0 0.00 0 16651 13 0 0
sp_get_clo_for_invoice 4873 4873 0 0.00 0 39870 362 0 0
sp_kill_cost_storno 16897 16897 0 0.00 0 6599 60 0 0
sp_kill_qstorno_ret_qs2qd 1129597 1129597 0 0.00 0 22480 33 0 0
sp_kill_qty_storno 594993 594993 0 0.00 0 38502 71 0 0
sp_lock_selected_doc 19829 19829 0 0.00 0 7484 6 0 0
sp_make_cost_storno 27024 27024 0 0.00 0 21945 59 0 0
sp_multiply_rows_for_pdistr 27013 27013 0 0.00 0 11669 34 0 0
sp_multiply_rows_for_qdistr 20056 20056 0 0.00 0 58653 179 0 0
sp_pay_from_customer 6931 6931 0 0.00 4 12976 131 0 0
sp_pay_to_supplier 5691 5691 0 0.00 5 33723 133 0 0
sp_payment_common 12619 12619 0 0.00 1 33709 32 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 13414 351 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 38501 340 0 0
sp_qd_handle_on_reserve_upd_sts 9526 9526 0 0.00 0 14207 72 0 0
sp_reserve_write_off 9529 9529 0 0.00 6 17077 195 0 0
sp_supplier_invoice 5340 5340 0 0.00 7 72278 3696 0 0
sp_supplier_order 5471 5471 0 0.00 5 34667 1325 0 0
srv_aggregate_perf_data 80 80 0 0.00 2313 444870 26985 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1138 150603 50889 0 0
srv_find_qd_qs_mism 1045134 1045134 0 0.00 0 21917 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 38 133743 1247 0 0
srv_make_money_saldo 774 774 0 0.00 1 5677 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 100061 525869 293698 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 0 8380 171 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 3833 113 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 3175 15 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 834 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 2135 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 2920 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 727 11 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 23262 1642 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 3485 13 0 0
t$perf-norm:sp_client_order 20056 20056 0 0.00 0 7978 18 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 2221 11 0 0
t$perf-norm:sp_pay_from_customer 6931 6931 0 0.00 0 5054 8 0 0
t$perf-norm:sp_pay_to_supplier 5691 5691 0 0.00 0 6351 12 0 0
t$perf-norm:sp_reserve_write_off 9529 9529 0 0.00 0 6527 12 0 0
t$perf-norm:sp_supplier_invoice 5340 5340 0 0.00 0 5965 18 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 17010 23 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 6579 85 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 4007 33 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 2190 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 7 0 0
v_add_invoice_to_stock 9992 9736 256 2.56 0 7489 21 0 256
v_all_customers 36692 36692 0 0.00 0 28 1 0 0
v_all_suppliers 29964 29964 0 0.00 0 30 1 0 0
v_all_wares 40112 40112 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 273 6 0 0
v_cancel_client_order 3250 3250 0 0.00 0 1007 4 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 529 4 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3818 9 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 1347 5 0 0
v_cancel_supplier_invoice 802 774 28 3.49 0 2068 15 0 28
v_cancel_supplier_order 812 812 0 0.00 0 3795 9 0 0
v_cancel_write_off 1886 1886 0 0.00 0 3823 17 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 11148 4 0 0
v_max_id_clo_res 957 957 0 0.00 0 2821 20 0 0
v_max_id_ord_sup 5334 5334 0 0.00 0 6420 5 0 0
v_max_non_paid_invoice 1504 1504 0 0.00 0 600 3 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 4806 6 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 1662 5 0 0
v_min_id_clo_res 957 957 0 0.00 0 4346 10 0 0
v_min_id_ord_sup 5334 5334 0 0.00 0 9904 11 0 0
v_min_non_paid_invoice 5689 5689 0 0.00 0 33703 24 0 0
v_min_non_paid_realizn 6930 6930 0 0.00 0 12967 25 0 0
v_our_firm 9946 9946 0 0.00 0 34 1 0 0
v_reserve_write_off 19052 19052 0 0.00 0 6702 6 0 0
x_lock_depdocs_on_canc_invoice 11681 11681 0 0.00 0 11314 108 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 2649 300 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 1 34658 1241 0 0
x_make_qstorno_1200_2000 5340 5340 0 0.00 3 72270 3533 0 0
x_make_qstorno_2100_3300 451433 451433 0 0.00 0 37951 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 01:40:18 to 19-12-2021 01:40:22.

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
23:07:59 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 17919 8192000000 9886323632 10194497536 975111856 10.637 100 89 178 0 1004662832 5744768 23806208
23:10:36 16869 8192000000 9947209360 10271207424 1026028304 11.130 100 90 180 0 1057320496 7865056 23427136
23:11:57 11533 8192000000 9971362208 10302525440 1055063424 11.409 100 90 180 0 1087362640 9657744 22641472
23:13:27 5185 8192000000 10043245888 10391281664 1128361904 12.106 100 84 168 0 1159626608 11101920 20162784
23:14:37 10012 8192000000 10084353792 10447265792 1173338256 12.528 100 82 164 0 1205451200 12810352 19302592
23:15:49 5762 8192000000 10131990144 10500698112 1216819904 12.932 100 82 162 1 1248067584 13624592 17623088
23:16:57 4814 8192000000 10152271536 10528428032 1241289840 13.158 100 74 149 0 1271350720 14133264 15927616
23:18:04 2381 8192000000 10195792416 10573869056 1279918048 13.512 100 93 186 1 1314726736 14095808 20712880
23:19:08 38369 8192000000 10188713904 10593026048 1293834000 13.639 100 50 99 2 1313536128 7554096 12148032
23:20:47 8044 8192000000 10199052576 10609848320 1306732016 13.756 100 28 56 0 1323880688 10934496 6214176
23:21:57 7952 8192000000 10197404864 10615795712 1315748224 13.838 100 22 43 0 1326243920 5424576 5071120
23:23:08 69505 8192000000 10211231328 10637877248 1323509408 13.908 100 26 52 0 1334974528 5165552 6299568
23:25:18 7271 8192000000 10228750000 10648154112 1334535616 14.008 100 23 44 1 1345470976 5570000 5365360
23:26:26 15565 8192000000 10216509600 10634989568 1309145408 13.778 98 16 32 1 1318677056 5725904 3805744
23:27:43 8177 8192000000 10219844864 10641948672 1304630624 13.737 97 14 28 1 1312506144 4800752 3074768
23:28:51 32375 8192000000 10199513184 10585415680 1240876240 13.154 94 62 125 2 1262539328 6869680 14793408
23:30:24 8290 8192000000 10158979200 10557513728 1216802176 12.932 95 22 44 0 1231434160 10083968 4548016
23:31:32 7451 8192000000 10140863968 10542120960 1238711856 13.134 99 27 54 1 1250701136 5503632 6485648
23:32:40 29791 8192000000 10163494912 10570665984 1272703888 13.446 100 30 61 2 1285173056 5005296 7463872
23:34:11 8113 8192000000 10172522480 10579906560 1268717280 13.410 99 34 67 1 1281607520 5329472 7560768
23:35:21 10773 8192000000 10187214288 10607120384 1302895216 13.722 100 20 40 1 1313383552 5836816 4651520
23:36:32 13040 8192000000 10202658352 10618245120 1310647056 13.792 100 18 37 0 1320860512 5369008 4844448
23:37:45 68486 8192000000 10244409008 10655404032 1335651648 14.018 100 36 73 0 1349694240 4970080 9072512
23:40:06 24885 8192000000 10254263088 10678243328 1357528208 14.215 100 52 104 2 1381114304 11338528 12247568
23:41:33 27359 8192000000 10287103424 10712367104 1375164336 14.373 100 39 78 0 1404017264 19800656 9052272
23:43:11 18789 8192000000 10300236432 10727583744 1384540880 14.457 100 36 70 0 1410162736 17618160 8003696
23:44:33 10741 8192000000 10310000768 10734985216 1382549808 14.439 99 51 102 0 1416892432 22412528 11930096
23:45:46 7543 8192000000 10328591248 10750414848 1403003600 14.622 100 54 108 1 1439087840 23005120 13079120
23:46:56 8119 8192000000 10317605152 10750345216 1401931408 14.612 100 34 66 2 1432638192 22525744 8181040
23:48:08 11356 8192000000 10339630112 10765533184 1412851920 14.709 100 52 104 0 1451632224 26478528 12301776
23:49:38 2555 8192000000 10356129584 10763862016 1402306048 14.616 99 77 153 2 1457990976 37554784 18130144
23:50:45 3342 8192000000 10344522016 10755956736 1380614800 14.422 99 71 140 3 1438867152 41595472 16656880
23:51:54 2195 8192000000 10348251472 10759421952 1388262736 14.490 100 77 156 1 1454630880 47367728 19000416
23:53:04 5323 8192000000 10360574208 10777796608 1403566624 14.627 100 63 126 2 1470590544 50438880 16585040
23:54:28 1134 8192000000 10364625584 10772254720 1382782064 14.441 99 83 165 1 1456554768 52536048 21236656
23:55:34 3627 8192000000 10357910960 10764840960 1380049344 14.417 100 79 158 0 1456479840 55598672 20831824
23:56:48 4243 8192000000 10315870304 10723004416 1330215264 13.969 97 61 123 0 1406597600 60708272 15674064
23:57:56 7776 8192000000 10305448336 10726035456 1356267104 14.204 99 41 82 1 1408346224 41243152 10835968
23:59:10 13299 8192000000 10337848144 10752499712 1379065024 14.408 100 54 107 0 1441950128 48212192 14672912
00:00:28 7647 8192000000 10325373856 10747977728 1367658240 14.306 99 41 80 1 1417998848 39607504 10733104
00:01:45 10161 8192000000 10331724640 10763264000 1380080576 14.417 100 29 57 1 1434825216 45875456 8869184
00:03:00 27178 8192000000 10321965136 10765746176 1373405408 14.358 100 22 44 1 1425899456 45795104 6698944
00:04:31 32565 8192000000 10285647424 10733084672 1339207952 14.050 98 20 40 1 1395054816 49782976 6063888
00:06:04 16045 8192000000 10289812016 10731757568 1369760736 14.325 100 33 66 2 1393496624 15664304 8071584
00:07:27 23596 8192000000 10297682928 10735652864 1378704880 14.405 100 47 97 0 1403969680 12965392 12299408
00:08:57 35099 8192000000 10296960768 10749304832 1390593760 14.511 100 20 37 1 1410615104 16058896 3962448
00:11:22 124584 8192000000 10297456752 10738745344 1372317744 14.348 99 27 52 0 1387520784 9550576 5652464
00:14:29 120778 8192000000 10290929424 10742075392 1311701408 13.802 94 14 29 0 1327928704 13344384 2882912
00:17:30 62745 8192000000 10296973472 10751254528 1382272992 14.437 100 9 18 0 1399682912 15319232 2090688
00:19:38 22546 8192000000 10314737936 10761797632 1391671872 14.521 100 39 79 0 1416342448 15579040 9091536
00:21:03 50728 8192000000 10321039744 10760101888 1394850944 14.549 100 41 82 0 1422960848 18046784 10063120
00:22:56 56174 8192000000 10286954336 10745446400 1364119200 14.274 98 18 36 1 1384492256 16068992 4304064
00:24:53 72310 8192000000 10282924496 10744430592 1365411536 14.286 99 7 14 0 1384589888 17177760 2000592
00:27:36 54965 8192000000 10286030352 10737512448 1371160112 14.337 99 13 26 3 1384346016 9872640 3313264
00:29:35 117591 8192000000 10294164368 10760699904 1347307168 14.123 97 10 20 1 1364392000 15074448 2010384
00:32:33 129685 8192000000 10303733168 10771271680 1400727472 14.601 100 15 31 0 1414845104 10931792 3185840
00:35:46 59377 8192000000 10311029200 10758684672 1368542192 14.314 98 28 56 1 1385719184 11304592 5872400
00:37:49 47881 8192000000 10292797312 10741506048 1348031536 14.130 97 21 42 0 1362730384 10250960 4447888
00:39:37 75071 8192000000 10298138336 10746081280 1361536848 14.251 98 26 52 0 1378404144 11225696 5641600
00:41:53 109153 8192000000 10302390240 10768949248 1371162432 14.337 98 9 18 1 1393161152 19963632 2035088
00:45:05 55161 8192000000 10263092752 10728030208 1332061072 13.986 96 6 12 0 1346732064 13408064 1262928
00:47:22 75779 8192000000 10250263440 10707423232 1345657696 14.108 100 12 24 0 1359560304 10625840 3276768
00:49:39 65668 8192000000 10258476112 10711355392 1331961072 13.985 98 19 38 0 1347836896 11022928 4852896
00:51:50 81303 8192000000 10222670416 10682150912 1253468016 13.270 92 13 26 0 1271284224 14290544 3525664
00:54:19 71269 8192000000 10246699008 10697064448 1341607968 14.072 100 16 32 1 1353596720 8457168 3531584
00:58:10 27699 8192000000 10291664784 10722308096 1362668400 14.261 100 41 83 0 1382611520 10560720 9382400
00:59:50 23956 8192000000 10273997136 10717556736 1341372752 14.070 98 14 28 1 1353533920 9003376 3157792
01:01:16 101600 8192000000 10302399296 10759172096 1369197424 14.320 99 20 40 0 1394555920 20877152 4481344
01:03:58 46821 8192000000 10299384544 10741866496 1356629888 14.207 98 23 46 1 1378411648 16759872 5021888
01:05:48 91551 8192000000 10295720880 10754854912 1368392432 14.313 99 21 42 2 1387039552 14307920 4339200
01:08:25 150603 8192000000 10293302144 10745315328 1375215984 14.374 99 27 54 0 1392314096 10861328 6236784
01:12:00 76853 8192000000 10276143888 10739503104 1383402752 14.447 100 14 28 0 1395780448 9286256 3091440
01:14:30 141905 8192000000 10288116928 10748575744 1337593792 14.036 96 11 22 1 1349423952 9241344 2588816
01:17:52 41192 8192000000 10311985632 10749493248 1371753248 14.343 98 36 72 0 1389498464 9773200 7972016
01:19:33 65748 8192000000 10309195712 10769895424 1393091056 14.533 100 19 38 1 1408921168 11506800 4323312
01:23:55 58149 8192000000 10319440880 10769936384 1366960384 14.300 97 25 50 0 1390958928 18424368 5574176
01:26:12 97025 8192000000 10283205888 10725388288 1274004464 13.458 91 17 32 1 1289342080 11856096 3481520
01:29:00 104532 8192000000 10300082640 10738544640 1347362304 14.124 97 30 60 1 1362617968 8729488 6526176
01:31:48 31115 8192000000 10296442000 10757709824 1392284624 14.526 100 32 64 1 1410024016 11027824 6711568
01:33:28 57642 8192000000 10257044848 10711117824 1331970336 13.985 97 13 27 0 1346286736 11057168 3259232
01:35:35 60168 8192000000 10265698512 10724536320 1339853136 14.056 98 21 43 0 1365649744 21028368 4768240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2021 01:40:24 to 19-12-2021 01:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2021 01:40:39 to 19-12-2021 01:40:41.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232027
MON$OLDEST_ACTIVE               236589
MON$OLDEST_SNAPSHOT             236589
MON$NEXT_TRANSACTION            236589
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-03 16:41:55.3470
MON$PAGES                       2026528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:109: 
:110: mscs-test-161.budzdorov.ru    Sun Dec 19 01:38:23 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Sun Dec 19 01:38:24 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Sun Dec 19 01:38:24 2021
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
--- end of diff output ---

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

19.12.21 01:40:42. There are 3 logs in /var/tmp/logs-oltp30 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-19 01:40:45 No severe PSQL-related problems occured
19.12.21 01:40:46. End of report.