Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2023 16: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 15334936 kB
MemAvailable 30373276 kB
Buffers 0 kB
Cached 16348676 kB
SwapCached 3028 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.11
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 2023-03-08 16:38:46
DTS_END                         2023-03-08 16:38:46.1700
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/39224
TRN_ID                          247478
ATT_ID                          702
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: 9208.19 19095 1104983
customer order: creation 178.96 337 21475
customer order: refuse 14.61 360 1753
order to supplier: creation 48.57 1200 5828
order to supplier: removal 3.31 90876 397
invoice (draft): creation 47.46 3069 5695
invoice (draft): removal 51.46 717 6175
invoice accept: apply 43.18 16449 5182
invoice accept: cancel 46.13 713 5535
customer reserve: creation 3959.21 165 475105
customer reserve: removal 4508.09 52 540971
realization accept: apply 85.80 164 10296
realization accept: cancel 85.83 106 10300
payment from customer: creation 61.63 102 7396
payment from customer: removal 5.93 69 712
payment to supplier: creation 51.99 113 6239
payment to supplier: removal 3.77 79 452
service: total inventory turnovers 4.77 1038 572
service: total monetary turnovers 6.60 30 792
service: aggregate perf. data 0.87 21396 104
service: refresh index statistics 0.03 244866 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2023 16:40:00 to 08-03-2023 16:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5109
TEST_TIME 2 4496
TEST_TIME 3 4333
TEST_TIME 4 3913
TEST_TIME 5 3851
TEST_TIME 6 4104
TEST_TIME 7 4352
TEST_TIME 8 4872
TEST_TIME 9 5316
TEST_TIME 10 5697
TEST_TIME 11 5785
TEST_TIME 12 6093
TEST_TIME 13 6227
TEST_TIME 14 6099
TEST_TIME 15 6427
TEST_TIME 16 7064
TEST_TIME 17 7530
TEST_TIME 18 7730
TEST_TIME 19 7988
TEST_TIME 20 8529
TEST_TIME 21 8362
TEST_TIME 22 9053
TEST_TIME 23 9130
TEST_TIME 24 9079
TEST_TIME 25 9512
TEST_TIME 26 9718
TEST_TIME 27 10004
TEST_TIME 28 9891
TEST_TIME 29 10132
TEST_TIME 30 10412
TEST_TIME 31 10371
TEST_TIME 32 10202
TEST_TIME 33 9991
TEST_TIME 34 9885
TEST_TIME 35 9680
TEST_TIME 36 9659
TEST_TIME 37 9573
TEST_TIME 38 9575
TEST_TIME 39 9536
TEST_TIME 40 9488
TEST_TIME 41 9395
TEST_TIME 42 9278
TEST_TIME 43 9301
TEST_TIME 44 9228
TEST_TIME 45 9256
TEST_TIME 46 9247
TEST_TIME 47 9328
TEST_TIME 48 9280
TEST_TIME 49 9215
TEST_TIME 50 9186
TEST_TIME 51 9222
TEST_TIME 52 9225
TEST_TIME 53 9163
TEST_TIME 54 9245
TEST_TIME 55 9304
TEST_TIME 56 9296
TEST_TIME 57 9384
TEST_TIME 58 9364
TEST_TIME 59 9370
TEST_TIME 60 9456
TEST_TIME 61 9503
TEST_TIME 62 9573
TEST_TIME 63 9636
TEST_TIME 64 9497
TEST_TIME 65 9479
TEST_TIME 66 9488
TEST_TIME 67 9392
TEST_TIME 68 9358
TEST_TIME 69 9355
TEST_TIME 70 9302
TEST_TIME 71 9280
TEST_TIME 72 9265
TEST_TIME 73 9224
TEST_TIME 74 9169
TEST_TIME 75 9247
TEST_TIME 76 9174
TEST_TIME 77 9155
TEST_TIME 78 9108
TEST_TIME 79 9212
TEST_TIME 80 9215
TEST_TIME 81 9209
TEST_TIME 82 9330
TEST_TIME 83 9310
TEST_TIME 84 9331
TEST_TIME 85 9359
TEST_TIME 86 9390
TEST_TIME 87 9414
TEST_TIME 88 9454
TEST_TIME 89 9454
TEST_TIME 90 9354
TEST_TIME 91 9283
TEST_TIME 92 9246
TEST_TIME 93 9270
TEST_TIME 94 9202
TEST_TIME 95 9154
TEST_TIME 96 9208
TEST_TIME 97 9208
TEST_TIME 98 9160
TEST_TIME 99 9189
TEST_TIME 100 9178
TEST_TIME 101 9213
TEST_TIME 102 9283
TEST_TIME 103 9257
TEST_TIME 104 9271
TEST_TIME 105 9282
TEST_TIME 106 9334
TEST_TIME 107 9312
TEST_TIME 108 9401
TEST_TIME 109 9379
TEST_TIME 110 9313
TEST_TIME 111 9290
TEST_TIME 112 9250
TEST_TIME 113 9233
TEST_TIME 114 9224
TEST_TIME 115 9199
TEST_TIME 116 9203
TEST_TIME 117 9189
TEST_TIME 118 9233
TEST_TIME 119 9191
TEST_TIME 120 9210

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-03-2023 16:40:03 to 08-03-2023 16:40:07.

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 1545579 1545579 0 0.00 0 20652 6 0 0
doc_list_biud 1545577 1545577 0 0.00 0 24417 24 0 0
sp_add_invoice_to_stock 5278 5182 96 1.81 38 173407 16449 0 96
sp_cancel_adding_invoice 5535 5535 0 0.00 4 64875 714 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 13909 361 0 0
sp_cancel_customer_reserve 540971 540971 0 0.00 0 19913 53 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 5973 70 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 5493 79 0 0
sp_cancel_supplier_invoice 6181 6175 6 0.09 2 19578 718 0 6
sp_cancel_supplier_order 397 397 0 0.00 5 954037 90876 0 0
sp_cancel_write_off 10300 10300 0 0.00 1 20599 107 0 0
sp_client_order 21475 21475 0 0.00 3 13393 338 0 0
sp_customer_reserve 475105 475105 0 0.00 0 87449 166 0 0
sp_fill_shopping_cart 32997 32997 0 0.00 1 20293 37 0 0
sp_fill_shopping_cart_clo_res 475101 475101 0 0.00 0 11910 13 0 0
sp_get_clo_for_invoice 5177 5177 0 0.00 0 19022 294 0 0
sp_kill_cost_storno 16999 16999 0 0.00 0 7642 46 0 0
sp_kill_qstorno_ret_qs2qd 1098814 1098814 0 0.00 0 20580 29 0 0
sp_kill_qty_storno 580604 580604 0 0.00 0 24416 62 0 0
sp_lock_selected_doc 21424 21424 0 0.00 0 8242 6 0 0
sp_make_cost_storno 29108 29108 0 0.00 0 20640 46 0 0
sp_multiply_rows_for_pdistr 29108 29108 0 0.00 0 7604 26 0 0
sp_multiply_rows_for_qdistr 21475 21475 0 0.00 0 10892 156 0 0
sp_pay_from_customer 7396 7396 0 0.00 4 14943 103 0 0
sp_pay_to_supplier 6239 6239 0 0.00 4 20661 114 0 0
sp_payment_common 13635 13635 0 0.00 2 6558 22 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 13902 320 0 0
sp_qd_handle_on_invoice_upd_sts 10712 10712 0 0.00 0 24416 274 0 0
sp_qd_handle_on_reserve_upd_sts 10296 10296 0 0.00 0 15293 60 0 0
sp_reserve_write_off 10296 10296 0 0.00 6 15347 164 0 0
sp_supplier_invoice 5695 5695 0 0.00 6 71986 3070 0 0
sp_supplier_order 5828 5828 0 0.00 5 31394 1200 0 0
srv_aggregate_perf_data 104 104 0 0.00 2371 460318 21397 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2666 134185 43632 0 0
srv_find_qd_qs_mism 1055694 1055694 0 0.00 0 18524 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 62 51864 1039 0 0
srv_make_money_saldo 792 792 0 0.00 0 4062 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 139174 352819 244866 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 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 5182 5182 0 0.00 0 14854 158 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 9388 138 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 2162 12 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 6533 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 2096 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 583 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 697 10 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 31009 1576 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 2200 7 0 0
t$perf-norm:sp_client_order 21475 21475 0 0.00 0 7403 16 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 415 2 0 0
t$perf-norm:sp_pay_from_customer 7396 7396 0 0.00 0 6814 11 0 0
t$perf-norm:sp_pay_to_supplier 6239 6239 0 0.00 0 7918 12 0 0
t$perf-norm:sp_reserve_write_off 10296 10296 0 0.00 0 14528 17 0 0
t$perf-norm:sp_supplier_invoice 5695 5695 0 0.00 0 7463 19 0 0
t$perf-norm:sp_supplier_order 5828 5828 0 0.00 0 5163 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1088 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1383 19 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 5366 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10546 10354 192 1.82 0 5560 19 0 192
v_all_customers 39338 39338 0 0.00 0 133 0 0 0
v_all_suppliers 32074 32074 0 0.00 0 26 1 0 0
v_all_wares 42950 42950 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 5415 18 0 0
v_cancel_client_order 3506 3506 0 0.00 0 5572 9 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 5966 8 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 1354 5 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 2443 7 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 1361 15 0 12
v_cancel_supplier_order 794 794 0 0.00 0 486 3 0 0
v_cancel_write_off 2116 2116 0 0.00 0 5607 21 0 0
v_max_id_clo_ord 5828 5828 0 0.00 0 1601 2 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 3186 16 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 4953 5 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 899 3 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 5962 6 0 0
v_min_id_clo_ord 5828 5828 0 0.00 0 9015 10 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 4640 14 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 5667 10 0 0
v_min_non_paid_invoice 6239 6239 0 0.00 0 6073 14 0 0
v_min_non_paid_realizn 7396 7396 0 0.00 0 6053 17 0 0
v_our_firm 10634 10634 0 0.00 0 19 1 0 0
v_reserve_write_off 20592 20592 0 0.00 0 5566 7 0 0
x_lock_depdocs_on_canc_invoice 11283 11283 0 0.00 0 14944 117 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 10588 313 0 0
x_make_qstorno_1000_1200 5828 5828 0 0.00 1 31388 1125 0 0
x_make_qstorno_1200_2000 5695 5695 0 0.00 2 71978 2937 0 0
x_make_qstorno_2100_3300 475105 475105 0 0.00 0 87446 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2023 16:40:08 to 08-03-2023 16:40:12.

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
14:08:50 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:51 26762 8192000000 9896992368 10208169984 979409200 10.678 100 89 178 1 1009473296 6406624 23657472
14:11:28 11655 8192000000 9941370848 10261168128 1019855840 11.071 100 87 174 0 1050996768 8188528 22952400
14:12:48 23124 8192000000 9972300368 10302844928 1055588800 11.414 100 83 164 0 1086326880 10048048 20690032
14:14:25 9464 8192000000 10023579120 10369073152 1111763760 11.949 100 77 152 0 1141364992 11369376 18231856
14:15:38 6546 8192000000 10074068512 10431361024 1162168528 12.424 100 84 167 0 1193579136 12215664 19194944
14:16:48 4401 8192000000 10115067632 10483335168 1205421920 12.827 100 80 156 0 1236516464 13960768 17133776
14:17:54 2413 8192000000 10147598096 10522918912 1235975136 13.109 100 81 159 0 1266157728 13131328 17051264
14:18:58 2827 8192000000 10175950608 10555437056 1262685152 13.355 100 77 155 1 1292211456 12012192 17514112
14:20:01 1005 8192000000 10217368304 10603503616 1299901616 13.694 100 91 173 1 1337707984 18424752 19381616
14:21:03 32242 8192000000 10217027568 10614079488 1309274944 13.779 100 60 110 1 1327824432 5841504 12707984
14:22:36 15842 8192000000 10200570112 10617364480 1314689376 13.829 100 12 24 1 1322793488 5457744 2646368
14:23:56 7999 8192000000 10205706576 10626945024 1321642800 13.892 100 14 29 0 1329973584 4887504 3443280
14:25:07 18707 8192000000 10221068752 10640674816 1335656448 14.018 100 30 59 1 1349504016 6491776 7355792
14:27:10 6247 8192000000 10225851408 10647625728 1334511024 14.008 100 16 30 0 1343074192 4907120 3656048
14:28:18 7707 8192000000 10229358832 10648285184 1334309856 14.006 100 21 42 0 1345403184 6684128 4409200
14:29:26 12121 8192000000 10161205120 10564591616 1192289088 12.705 92 13 26 0 1200157088 4997616 2870384
14:30:39 53107 8192000000 10142768432 10548998144 1214634928 12.912 95 34 69 1 1228176032 6074432 7466672
14:32:32 8953 8192000000 10138850160 10544934912 1232798288 13.080 98 23 47 1 1243907104 6010320 5098496
14:33:42 7618 8192000000 10144697008 10544078848 1233451136 13.086 98 27 55 0 1245701840 5413968 6836736
14:34:50 13866 8192000000 10164171184 10575245312 1271036880 13.431 100 21 43 0 1285013264 9188048 4788336
14:36:06 31002 8192000000 10169136176 10585153536 1282202912 13.533 100 23 46 0 1293288784 5120000 5965872
14:37:37 7006 8192000000 10180461792 10594680832 1291960144 13.622 100 30 62 1 1305416560 6473200 6983216
14:38:44 6174 8192000000 10219066512 10628399104 1317808432 13.857 100 23 45 0 1326809008 4537168 4463408
14:39:56 10469 8192000000 10234011808 10648850432 1333409376 13.998 100 46 92 0 1352005456 7391872 11204208
14:41:13 23201 8192000000 10264874192 10683580416 1355420736 14.196 100 40 80 0 1375092384 10965280 8706368
14:42:42 28660 8192000000 10277140896 10697601024 1366312688 14.294 100 40 79 0 1392644672 16942832 9389152
14:44:26 14928 8192000000 10285142768 10707603456 1372900464 14.353 100 38 75 0 1399083664 17582544 8600656
14:45:46 5019 8192000000 10304261136 10718846976 1376888064 14.389 100 57 114 3 1412277792 21993824 13395904
14:46:56 17205 8192000000 10318265440 10736996352 1390668576 14.512 100 51 101 0 1425362080 22843632 11849872
14:48:19 8743 8192000000 10305014176 10723504128 1363448720 14.268 98 58 116 0 1403391232 26432048 13510464
14:49:32 5793 8192000000 10325293312 10744266752 1392712368 14.530 100 57 114 0 1435395536 28774912 13908256
14:50:42 20077 8192000000 10337470096 10755674112 1400935504 14.603 100 64 128 1 1448641040 32144464 15561072
14:52:09 10890 8192000000 10335357408 10757591040 1386517856 14.475 99 45 89 1 1433041696 35029984 11493856
14:53:23 5613 8192000000 10329206400 10748297216 1374888528 14.371 99 52 105 2 1424002384 35607680 13506176
14:54:33 2704 8192000000 10320458032 10727030784 1363663840 14.270 100 82 161 0 1422340288 37668016 21008432
14:55:43 7511 8192000000 10292132784 10703114240 1341430816 14.070 99 51 101 0 1384514832 29454160 13629856
14:56:59 11522 8192000000 10297267072 10720313344 1358606272 14.225 100 45 90 0 1410034576 38898400 12529904
14:58:14 6437 8192000000 10302695184 10721415168 1355043296 14.193 100 49 98 0 1402577552 34760208 12774048
14:59:25 2665 8192000000 10291095728 10697109504 1327434480 13.944 99 71 138 0 1377715216 33383248 16897488
15:00:34 17513 8192000000 10259852656 10678984704 1307771824 13.766 97 30 61 1 1341450976 25531488 8147664
15:02:07 7454 8192000000 10277421856 10704535552 1342803408 14.083 99 24 46 1 1370316448 21667856 5845184
15:03:15 18225 8192000000 10279819488 10716954624 1359692560 14.235 100 17 34 0 1385607152 21243664 4670928
15:04:51 13397 8192000000 10277580544 10702589952 1348508256 14.134 99 27 55 1 1373296272 17538384 7249632
15:06:05 51586 8192000000 10286878368 10712895488 1349184912 14.140 99 14 26 1 1370343568 17065216 4093440
15:07:59 63314 8192000000 10280378000 10731937792 1339089200 14.049 98 11 22 0 1360357504 19024320 2243984
15:10:03 25728 8192000000 10298147872 10730065920 1380543952 14.421 100 39 77 0 1395446352 5958768 8943632
15:11:34 42355 8192000000 10302852640 10729005056 1378636752 14.404 100 56 106 0 1396633072 5843104 12153216
15:13:25 37993 8192000000 10277513104 10716733440 1370240064 14.329 99 26 48 2 1382264000 6147616 5876320
15:15:08 17320 8192000000 10305169120 10743549952 1393472304 14.537 100 59 116 1 1415116992 7619904 14024784
15:16:27 39918 8192000000 10304812064 10756255744 1394691856 14.548 100 16 32 0 1407799072 9536816 3570400
15:18:11 47684 8192000000 10300490256 10750840832 1393491936 14.537 100 16 32 0 1409278400 11643072 4143392
15:20:04 53051 8192000000 10314059680 10768322560 1397795408 14.575 100 17 34 0 1422673104 20792400 4085296
15:22:03 72152 8192000000 10304941264 10768551936 1402216704 14.615 100 21 42 0 1423942368 16141040 5584624
15:24:59 92661 8192000000 10312549648 10786897920 1406472544 14.653 100 17 34 0 1431211120 20784864 3953712
15:27:33 10368 8192000000 10319275152 10765377536 1406922480 14.657 100 46 92 2 1424492512 7113408 10456624
15:28:59 17799 8192000000 10295125152 10741739520 1377074928 14.390 99 35 71 0 1393491888 8614880 7802080
15:30:22 46371 8192000000 10299353984 10751246336 1374325392 14.366 98 19 38 1 1386987552 8087200 4574960
15:32:19 48290 8192000000 10268249120 10730975232 1355146480 14.194 98 16 32 0 1373288560 14252784 3889296
15:34:25 77551 8192000000 10264209344 10730360832 1285070160 13.559 94 19 38 1 1309434656 20404896 3959600
15:37:00 79934 8192000000 10271674000 10743496704 1337280048 14.033 98 14 29 2 1359877920 18680288 3917584
15:39:20 32906 8192000000 10275028400 10712584192 1343523328 14.089 99 27 52 0 1361520752 11951632 6045792
15:40:54 111810 8192000000 10264853904 10725412864 1318710320 13.865 97 9 18 0 1343508400 22430512 2367568
15:43:57 46634 8192000000 10280212576 10727866368 1364612096 14.279 100 18 36 0 1382484768 13865520 4007152
15:45:52 24714 8192000000 10273502960 10702712832 1346375728 14.115 99 45 90 0 1366178080 9697488 10104864
15:47:17 27021 8192000000 10259145360 10704097280 1344403168 14.097 98 24 49 0 1356332720 7057488 4872064
15:48:45 68016 8192000000 10271172464 10723180544 1368918944 14.317 100 12 24 1 1379948544 8222048 2807552
15:50:57 70371 8192000000 10276684304 10740350976 1353208784 14.176 98 10 19 2 1367897664 12281952 2406928
15:54:00 46512 8192000000 10260508544 10726465536 1341856736 14.074 98 2 4 0 1361775760 19605376 313648
15:55:56 53625 8192000000 10278795136 10720088064 1355472224 14.197 99 26 52 0 1373167408 11856176 5839008
15:58:03 53738 8192000000 10291367664 10748805120 1378782688 14.406 100 13 26 0 1394637840 13037104 2818048
16:00:03 68030 8192000000 10279701872 10745483264 1361612768 14.252 98 14 28 0 1375682192 10780672 3288752
16:02:14 49930 8192000000 10291673856 10737004544 1371643008 14.342 99 33 66 0 1387728640 9327744 6757888
16:04:07 134185 8192000000 10307251984 10765262848 1393485104 14.537 100 19 38 0 1410340896 12758816 4096976
16:07:28 102059 8192000000 10313468944 10775552000 1401471856 14.608 100 17 34 0 1419161984 13765008 3925120
16:10:22 51416 8192000000 10299028336 10760396800 1402213088 14.615 100 8 16 0 1417799184 13794592 1791504
16:12:21 52186 8192000000 10294957216 10752516096 1379487568 14.412 98 17 32 0 1391091968 8060672 3543728
16:14:28 110339 8192000000 10307265440 10767622144 1395506048 14.555 99 11 22 0 1407442832 9209072 2727712
16:17:19 79638 8192000000 10315599792 10769993728 1400470480 14.599 99 20 40 0 1418887184 13593600 4823104
16:19:39 77771 8192000000 10257697104 10725961728 1294788720 13.648 93 13 26 0 1308020656 10157840 3074096
16:21:57 30528 8192000000 10274338384 10728333312 1347615712 14.126 99 20 40 0 1366053712 14191104 4246896
16:23:38 107078 8192000000 10273710272 10711511040 1286659696 13.574 94 20 40 0 1300843888 9765264 4418928
16:26:27 72490 8192000000 10247656512 10690457600 1274826448 13.466 94 17 34 0 1296608240 18408672 3373120
16:28:47 86369 8192000000 10249759376 10692292608 1317596064 13.855 97 33 66 0 1332328688 6909648 7822976
16:31:15 5208 8192000000 10267111888 10704846848 1354883296 14.191 100 39 78 1 1372113584 8559936 8670352
16:32:22 60363 8192000000 10263995920 10728534016 1368397024 14.313 100 11 22 0 1386053856 14784832 2872000
16:34:58 78859 8192000000 10245208304 10705088512 1309560176 13.782 96 12 24 0 1328615232 15300880 3754176
16:37:46 65898 8192000000 9055713712 9285255168 229756320 2.728 17 8 16 0 234822512 3421168 1645024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-03-2023 16:40:14 to 08-03-2023 16:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 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 08-03-2023 16:40:30 to 08-03-2023 16:40:32.

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          246075
MON$OLDEST_ACTIVE               247772
MON$OLDEST_SNAPSHOT             247772
MON$NEXT_TRANSACTION            247772
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                       2022336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1182: 
:1183: mscs-test-161.budzdorov.ru    Wed Mar  8 16:38:58 2023
:1184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1185: 
--- end of diff output ---

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

08.03.23 16:40:33. 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
2023-03-08 16:40:36 No severe PSQL-related problems occured
08.03.23 16:40:37. End of report.