Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2021 19: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 16885808 kB
MemAvailable 31406804 kB
Buffers 0 kB
Cached 14808100 kB
SwapCached 444 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-21 19:37:56
DTS_END                         2021-12-21 19:37:56.7380
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(57:17)
IP                              ::1/46110
TRN_ID                          235447
ATT_ID                          643
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: 9147.89 21923 1097747
customer order: creation 171.96 362 20635
customer order: refuse 14.83 335 1779
order to supplier: creation 45.46 1368 5455
order to supplier: removal 3.53 97931 423
invoice (draft): creation 44.27 3400 5312
invoice (draft): removal 51.26 800 6151
invoice accept: apply 41.06 17196 4927
invoice accept: cancel 46.24 843 5549
customer reserve: creation 3857.47 167 462896
customer reserve: removal 4584.48 59 550137
realization accept: apply 82.65 171 9918
realization accept: cancel 76.80 104 9216
payment from customer: creation 58.76 107 7051
payment from customer: removal 5.60 63 672
payment to supplier: creation 48.18 143 5781
payment to supplier: removal 3.68 65 442
service: total inventory turnovers 4.38 1232 525
service: total monetary turnovers 6.45 19 774
service: aggregate perf. data 0.83 18547 100
service: refresh index statistics 0.03 295556 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2021 19:39:23 to 21-12-2021 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3109
TEST_TIME 2 3852
TEST_TIME 3 4662
TEST_TIME 4 4705
TEST_TIME 5 5232
TEST_TIME 6 4703
TEST_TIME 7 4545
TEST_TIME 8 4657
TEST_TIME 9 4994
TEST_TIME 10 5256
TEST_TIME 11 5306
TEST_TIME 12 5443
TEST_TIME 13 5551
TEST_TIME 14 5959
TEST_TIME 15 6675
TEST_TIME 16 6781
TEST_TIME 17 7038
TEST_TIME 18 7470
TEST_TIME 19 7936
TEST_TIME 20 8482
TEST_TIME 21 8486
TEST_TIME 22 8900
TEST_TIME 23 9026
TEST_TIME 24 8882
TEST_TIME 25 8929
TEST_TIME 26 9400
TEST_TIME 27 9247
TEST_TIME 28 9850
TEST_TIME 29 10319
TEST_TIME 30 10557
TEST_TIME 31 10429
TEST_TIME 32 10188
TEST_TIME 33 10205
TEST_TIME 34 10105
TEST_TIME 35 10015
TEST_TIME 36 9820
TEST_TIME 37 9844
TEST_TIME 38 9641
TEST_TIME 39 9580
TEST_TIME 40 9681
TEST_TIME 41 9519
TEST_TIME 42 9405
TEST_TIME 43 9399
TEST_TIME 44 9290
TEST_TIME 45 9220
TEST_TIME 46 9332
TEST_TIME 47 9266
TEST_TIME 48 9185
TEST_TIME 49 9168
TEST_TIME 50 9264
TEST_TIME 51 9255
TEST_TIME 52 9331
TEST_TIME 53 9400
TEST_TIME 54 9364
TEST_TIME 55 9465
TEST_TIME 56 9403
TEST_TIME 57 9511
TEST_TIME 58 9504
TEST_TIME 59 9498
TEST_TIME 60 9487
TEST_TIME 61 9421
TEST_TIME 62 9508
TEST_TIME 63 9370
TEST_TIME 64 9330
TEST_TIME 65 9246
TEST_TIME 66 9222
TEST_TIME 67 9249
TEST_TIME 68 9172
TEST_TIME 69 9155
TEST_TIME 70 9096
TEST_TIME 71 9096
TEST_TIME 72 9087
TEST_TIME 73 8998
TEST_TIME 74 9018
TEST_TIME 75 9057
TEST_TIME 76 9129
TEST_TIME 77 9098
TEST_TIME 78 9126
TEST_TIME 79 9105
TEST_TIME 80 9116
TEST_TIME 81 9061
TEST_TIME 82 9162
TEST_TIME 83 9334
TEST_TIME 84 9328
TEST_TIME 85 9378
TEST_TIME 86 9392
TEST_TIME 87 9419
TEST_TIME 88 9334
TEST_TIME 89 9276
TEST_TIME 90 9242
TEST_TIME 91 9188
TEST_TIME 92 9133
TEST_TIME 93 9147
TEST_TIME 94 9096
TEST_TIME 95 9107
TEST_TIME 96 9118
TEST_TIME 97 9148
TEST_TIME 98 9140
TEST_TIME 99 9134
TEST_TIME 100 9131
TEST_TIME 101 9162
TEST_TIME 102 9217
TEST_TIME 103 9204
TEST_TIME 104 9226
TEST_TIME 105 9214
TEST_TIME 106 9267
TEST_TIME 107 9282
TEST_TIME 108 9288
TEST_TIME 109 9381
TEST_TIME 110 9335
TEST_TIME 111 9277
TEST_TIME 112 9232
TEST_TIME 113 9225
TEST_TIME 114 9205
TEST_TIME 115 9168
TEST_TIME 116 9131
TEST_TIME 117 9165
TEST_TIME 118 9161
TEST_TIME 119 9148
TEST_TIME 120 9154
TEST_TIME 121 9079

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2021 19:39:26 to 21-12-2021 19:39:29.

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 1525842 1525842 0 0.00 0 21450 6 0 0
doc_list_biud 1525842 1525842 0 0.00 0 22655 27 0 0
sp_add_invoice_to_stock 5041 4927 114 2.26 40 169877 17196 0 114
sp_cancel_adding_invoice 5549 5549 0 0.00 3 108209 843 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 10258 336 0 0
sp_cancel_customer_reserve 550137 550137 0 0.00 1 19479 60 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 2694 63 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 4129 66 0 0
sp_cancel_supplier_invoice 6162 6151 11 0.17 1 14814 801 0 11
sp_cancel_supplier_order 423 423 0 0.00 6 899820 97932 0 0
sp_cancel_write_off 9216 9216 0 0.00 1 8067 105 0 0
sp_client_order 20635 20635 0 0.00 3 27484 362 0 0
sp_customer_reserve 462896 462896 0 0.00 0 55438 168 0 0
sp_fill_shopping_cart 31402 31402 0 0.00 1 21403 41 0 0
sp_fill_shopping_cart_clo_res 462878 462878 0 0.00 0 16627 13 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 18704 310 0 0
sp_kill_cost_storno 15879 15879 0 0.00 0 8030 57 0 0
sp_kill_qstorno_ret_qs2qd 1116064 1116064 0 0.00 0 19092 32 0 0
sp_kill_qty_storno 588082 588082 0 0.00 0 22655 70 0 0
sp_lock_selected_doc 20498 20498 0 0.00 0 6613 5 0 0
sp_make_cost_storno 27659 27659 0 0.00 0 21445 54 0 0
sp_multiply_rows_for_pdistr 27659 27659 0 0.00 0 9441 27 0 0
sp_multiply_rows_for_qdistr 20635 20635 0 0.00 0 14531 175 0 0
sp_pay_from_customer 7051 7051 0 0.00 4 13565 107 0 0
sp_pay_to_supplier 5781 5781 0 0.00 5 16771 143 0 0
sp_payment_common 12832 12832 0 0.00 1 11266 29 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 10211 312 0 0
sp_qd_handle_on_invoice_upd_sts 10458 10458 0 0.00 0 16795 330 0 0
sp_qd_handle_on_reserve_upd_sts 9918 9918 0 0.00 0 22654 62 0 0
sp_reserve_write_off 9918 9918 0 0.00 6 22684 171 0 0
sp_supplier_invoice 5312 5312 0 0.00 6 103860 3400 0 0
sp_supplier_order 5455 5455 0 0.00 5 91920 1368 0 0
srv_aggregate_perf_data 100 100 0 0.00 2223 224034 18548 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2744 163289 49878 0 0
srv_find_qd_qs_mism 1049998 1049998 0 0.00 0 20688 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 33 75153 1232 0 0
srv_make_money_saldo 774 774 0 0.00 1 557 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 200366 373484 295556 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4927 4927 0 0.00 0 8578 167 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 0 7220 125 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 12302 16 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 4382 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 619 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 2935 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 965 15 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 22304 1422 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 1305 5 0 0
t$perf-norm:sp_client_order 20635 20635 0 0.00 0 9394 16 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 4723 14 0 0
t$perf-norm:sp_pay_from_customer 7051 7051 0 0.00 0 7247 10 0 0
t$perf-norm:sp_pay_to_supplier 5781 5781 0 0.00 0 4786 10 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 21612 13 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 6884 15 0 0
t$perf-norm:sp_supplier_order 5455 5455 0 0.00 0 9339 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 78 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 53 1 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 1928 20 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 6995 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10046 9818 228 2.26 0 5137 19 0 228
v_all_customers 38020 38020 0 0.00 0 27 0 0 0
v_all_suppliers 29980 29980 0 0.00 0 136 1 0 0
v_all_wares 41270 41270 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 3767 15 0 0
v_cancel_client_order 3558 3558 0 0.00 0 2834 6 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 560 4 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 565 3 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 475 3 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 467 14 0 22
v_cancel_supplier_order 846 846 0 0.00 0 1367 6 0 0
v_cancel_write_off 1926 1926 0 0.00 0 2479 16 0 0
v_max_id_clo_ord 5455 5455 0 0.00 0 777 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 2152 15 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 4733 6 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 839 3 0 0
v_max_non_paid_realizn 3584 3584 0 0.00 0 1862 4 0 0
v_min_id_clo_ord 5455 5455 0 0.00 0 3087 8 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 4254 11 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 4075 5 0 0
v_min_non_paid_invoice 5781 5781 0 0.00 0 11262 20 0 0
v_min_non_paid_realizn 7051 7051 0 0.00 0 7278 23 0 0
v_our_firm 10184 10184 0 0.00 0 16 1 0 0
v_reserve_write_off 19836 19836 0 0.00 0 6875 8 0 0
x_lock_depdocs_on_canc_invoice 11329 11329 0 0.00 0 14531 125 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 4350 285 0 0
x_make_qstorno_1000_1200 5455 5455 0 0.00 1 91910 1291 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 2 101482 3243 0 0
x_make_qstorno_2100_3300 462896 462896 0 0.00 0 55437 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-12-2021 19:39:30 to 21-12-2021 19:39:35.

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
17:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:01 42490 8192000000 9915711392 10234793984 1001261696 10.891 100 90 180 0 1031542064 6649488 23630880
17:10:58 23108 8192000000 9958761056 10286419968 1038867072 11.254 100 84 168 0 1069739456 9742416 21129968
17:12:23 20066 8192000000 10011729056 10351632384 1084601424 11.691 100 83 166 0 1116463632 11671584 20190624
17:13:50 13500 8192000000 10053250528 10404921344 1133388160 12.153 100 83 166 1 1165864944 12770880 19705904
17:15:07 6882 8192000000 10090691200 10451832832 1174017824 12.534 100 79 158 0 1204998624 12957936 18022864
17:16:19 4378 8192000000 10116184048 10487713792 1208987856 12.860 100 80 158 1 1240055424 13076016 17991552
17:17:26 9664 8192000000 10153964016 10533019648 1247928512 13.219 100 73 146 1 1278049264 14004688 16116064
17:18:37 2081 8192000000 10193065136 10578292736 1281773648 13.529 100 84 168 2 1313798976 13426240 18599088
17:19:42 25586 8192000000 10200575600 10596786176 1296098064 13.660 100 70 140 1 1320846336 8686192 16062080
17:21:09 5127 8192000000 10198675376 10608300032 1308265936 13.770 100 37 74 1 1324551472 8315072 7970464
17:22:15 64085 8192000000 10204285648 10627796992 1320353472 13.880 100 23 46 0 1331488704 5583360 5551872
17:24:20 8809 8192000000 10209243888 10634215424 1325217280 13.924 100 18 36 2 1338409968 9051216 4141472
17:25:30 4874 8192000000 10219344672 10642886656 1332247712 13.987 100 33 66 2 1345717360 5576400 7893248
17:26:35 65602 8192000000 10224191520 10642558976 1317812448 13.857 99 36 72 2 1332010880 5743328 8455104
17:28:41 9067 8192000000 10215971760 10635784192 1311144608 13.796 99 33 66 1 1325080576 5870896 8065072
17:29:52 7185 8192000000 10164869888 10569760768 1227423040 13.030 95 34 68 1 1240811984 5992208 7396736
17:31:00 8865 8192000000 10139818416 10536976384 1229644224 13.051 98 21 42 1 1240128288 5440832 5043232
17:32:12 21186 8192000000 10133498912 10531766272 1194607104 12.726 95 26 51 2 1206789088 6730928 5451056
17:33:38 6595 8192000000 10140498736 10536824832 1219343104 12.956 97 26 52 1 1232777248 7099216 6334928
17:34:45 7330 8192000000 10171872160 10578948096 1280700016 13.519 100 30 60 1 1294065008 6463648 6901344
17:35:54 20984 8192000000 10182287840 10594865152 1291399456 13.617 100 18 35 1 1299686384 4430832 3856096
17:37:41 11802 8192000000 10190063360 10604290048 1299114032 13.687 100 19 36 2 1308873088 5271408 4487648
17:39:02 21997 8192000000 10232923440 10636898304 1325562816 13.927 100 55 110 2 1347565568 9256800 12745952
17:40:30 17672 8192000000 10254592688 10666119168 1347302160 14.123 100 43 85 2 1366496800 10091248 9103392
17:42:10 20825 8192000000 10270433104 10690228224 1366217488 14.293 100 44 89 2 1387952256 10807040 10927728
17:43:43 40725 8192000000 10281172400 10706493440 1379696704 14.414 100 26 52 3 1397805184 11799088 6309392
17:45:26 12555 8192000000 10310565424 10731098112 1386857904 14.478 100 49 98 1 1418073008 19253680 11961424
17:46:52 20838 8192000000 10315248496 10739073024 1385454896 14.465 99 44 88 1 1413755520 17006288 11294336
17:48:18 5276 8192000000 10328872400 10753687552 1408944608 14.675 100 48 94 1 1439668816 18904560 11819648
17:49:28 4074 8192000000 10351496800 10774630400 1419403184 14.767 100 57 112 1 1457926976 24472336 14051456
17:50:37 2744 8192000000 10354497088 10770264064 1415181952 14.730 100 77 154 0 1464508448 30123616 19202880
17:51:42 2943 8192000000 10360276608 10771427328 1399767648 14.593 99 87 175 1 1458648112 36939264 21941200
17:52:50 4363 8192000000 10351562848 10762498048 1400340496 14.598 100 77 154 1 1453614688 34674032 18600160
17:54:02 3022 8192000000 10359205936 10773004288 1403159760 14.623 100 77 151 1 1463265856 41331120 18774976
17:55:18 3539 8192000000 10345757568 10759479296 1392755904 14.530 100 80 160 2 1451632192 39239536 19636752
17:56:27 7294 8192000000 10356866224 10780151808 1403805344 14.629 100 59 117 1 1463283632 44594400 14883888
17:57:41 13267 8192000000 10331966336 10768384000 1373113312 14.355 98 26 51 0 1423798736 43886816 6798608
17:58:56 12935 8192000000 10338676448 10777309184 1389075184 14.498 100 29 58 0 1449678864 52441264 8162416
18:00:14 14726 8192000000 10338276688 10773065728 1393614128 14.538 100 24 47 0 1446948928 46792352 6542448
18:01:44 20403 8192000000 10312073088 10745819136 1360112256 14.238 100 31 60 1 1418473328 50159296 8201776
18:03:13 24710 8192000000 10301776976 10733969408 1351495232 14.161 99 25 48 2 1418269136 60038768 6735136
18:05:00 43865 8192000000 10288318624 10731995136 1312668896 13.810 97 20 40 1 1371091136 52687552 5734688
18:07:00 50446 8192000000 10220885952 10670374912 1279593536 13.509 96 8 16 0 1299072816 17627456 1851824
18:08:51 97109 8192000000 10247213648 10690752512 1279175632 13.505 95 8 16 0 1295658368 15207504 1275232
18:11:29 134499 8192000000 10262063488 10695213056 1321104752 13.887 98 17 34 1 1338642688 13659936 3878000
18:14:50 61105 8192000000 10265454368 10698878976 1344548576 14.098 99 26 50 1 1360081168 9095328 6437264
18:16:54 106964 8192000000 10275095184 10714136576 1359322464 14.231 100 21 42 1 1379405280 14879664 5203152
18:19:42 35140 8192000000 10289504416 10737242112 1385063984 14.462 100 48 94 0 1412291408 15493888 11733536
18:21:25 58684 8192000000 10305516496 10751344640 1390429856 14.510 100 25 50 0 1415320128 18918576 5971696
18:23:37 72615 8192000000 10279332080 10745171968 1371188144 14.338 99 15 30 0 1399039168 24070016 3781008
18:25:56 62857 8192000000 10311969520 10762170368 1390354800 14.509 100 14 28 0 1410186320 16528000 3303520
18:28:35 55118 8192000000 10304702016 10760208384 1389323312 14.500 99 34 68 1 1412295488 14897664 8074512
18:30:39 90954 8192000000 10304140288 10755395584 1374213984 14.365 98 22 44 0 1395185968 16051200 4920784
18:33:27 19523 8192000000 10323759776 10762997760 1400879232 14.603 100 43 84 1 1417250992 7092368 9279392
18:34:51 66434 8192000000 10320661056 10770526208 1391489424 14.519 99 36 68 4 1412485312 13117664 7878224
18:37:00 70659 8192000000 10312114976 10762948608 1390396944 14.509 99 22 44 1 1405092272 9943600 4751728
18:39:17 163289 8192000000 10298262368 10740056064 1337075872 14.031 95 24 48 1 1355502832 13114912 5312048
18:43:09 39749 8192000000 10287127584 10744897536 1388161968 14.489 100 36 72 0 1407909280 11761264 7986048
18:44:56 72642 8192000000 10301102096 10768121856 1392950848 14.532 100 7 13 1 1412742144 18438624 1352672
18:48:15 57294 8192000000 10269369872 10731372544 1369839200 14.326 100 12 22 1 1387286336 14668640 2778496
18:50:13 38021 8192000000 10274548384 10726604800 1356667792 14.207 99 22 44 1 1375480624 13588624 5224208
18:52:11 78952 8192000000 10255395872 10710167552 1283672832 13.547 94 15 30 0 1304858896 17859776 3326288
18:54:36 28536 8192000000 10262017744 10692603904 1310583408 13.791 97 26 52 0 1323945648 7749600 5612640
18:56:05 52369 8192000000 10248304096 10686644224 1286488640 13.572 95 34 68 0 1306852960 12459856 7904464
18:57:58 90752 8192000000 10272056128 10717204480 1322559232 13.900 97 12 24 2 1346598576 21134896 2904448
19:00:54 67893 8192000000 10246158592 10689912832 1282082656 13.532 96 9 18 0 1294336256 9966480 2287120
19:03:03 69415 8192000000 10260220640 10700677120 1326474688 13.935 98 31 58 0 1351252240 18476304 6301248
19:05:18 126190 8192000000 10238630992 10692231168 1346250224 14.114 100 15 30 0 1356527440 7124416 3152800
19:08:26 74372 8192000000 10252747504 10704896000 1350538656 14.152 99 12 24 0 1365001584 11944704 2518224
19:10:44 40317 8192000000 10256213424 10728570880 1366577152 14.296 100 6 12 0 1375711456 8088112 1046192
19:12:24 61421 8192000000 10271507600 10735394816 1360862816 14.245 99 15 30 0 1374214080 10165328 3185936
19:14:26 39110 8192000000 10275677888 10725130240 1347934448 14.129 98 24 48 2 1361971360 7467696 6569216
19:16:05 17031 8192000000 10282827216 10729353216 1362939760 14.264 99 44 88 1 1385884864 13261904 9683200
19:17:25 28453 8192000000 10274106960 10720534528 1350661152 14.153 98 35 70 0 1369153760 10925760 7566848
19:19:06 77125 8192000000 10293311872 10752745472 1381492816 14.430 100 24 48 0 1400446048 13480512 5472720
19:21:30 80422 8192000000 10300514720 10754478080 1369321664 14.321 98 17 34 2 1386851136 13838480 3690992
19:23:51 73483 8192000000 10310929728 10772627456 1359557984 14.233 97 15 30 2 1375150320 11630000 3962336
19:26:06 76240 8192000000 10293486256 10754080768 1366259840 14.294 98 6 12 0 1381934192 14425632 1248720
19:28:28 97561 8192000000 10290445696 10745835520 1382002576 14.434 99 21 41 0 1395118016 8595936 4519504
19:31:07 72397 8192000000 10270629072 10728550400 1348607056 14.135 96 17 34 0 1363311600 9882528 4822016
19:33:36 56119 8192000000 10289881776 10748649472 1363047888 14.265 98 12 24 1 1381483248 15012704 3422656
19:35:42 59826 8192000000 10261082448 10716102656 1312712816 13.811 96 24 48 0 1333275440 15067552 5495072
19:37:42 23647 8192000000 9123606000 9364156416 285996224 3.373 21 6 9 1 299533024 11950960 1585840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2021 19:39:36 to 21-12-2021 19:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2021 19:39:52 to 21-12-2021 19:39:54.

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          232700
MON$OLDEST_ACTIVE               235996
MON$OLDEST_SNAPSHOT             235996
MON$NEXT_TRANSACTION            235996
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                       1996032
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 ---
:239: 
:240: mscs-test-161.budzdorov.ru    Tue Dec 21 19:38:08 2021
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:242: 
--- end of diff output ---

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

21.12.21 19:39:55. 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-21 19:39:57 No severe PSQL-related problems occured
21.12.21 19:39:58. End of report.