Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2023 19:40
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 15271084 kB
MemAvailable 30449684 kB
Buffers 0 kB
Cached 16460032 kB
SwapCached 3736 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-01-31 19:38:41
DTS_END                         2023-01-31 19:38:41.2310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51938
TRN_ID                          237284
ATT_ID                          652
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: 9207.14 22982 1104857
customer order: creation 166.21 408 19945
customer order: refuse 14.41 410 1729
order to supplier: creation 44.60 1254 5352
order to supplier: removal 3.47 93411 416
invoice (draft): creation 44.57 3497 5348
invoice (draft): removal 52.25 668 6270
invoice accept: apply 41.11 16906 4933
invoice accept: cancel 47.79 824 5735
customer reserve: creation 3793.63 166 455235
customer reserve: removal 4710.66 55 565279
realization accept: apply 80.70 177 9684
realization accept: cancel 82.38 106 9886
payment from customer: creation 58.14 95 6977
payment from customer: removal 5.41 97 649
payment to supplier: creation 46.83 121 5619
payment to supplier: removal 3.48 64 417
service: total inventory turnovers 4.23 1251 508
service: total monetary turnovers 6.38 27 765
service: aggregate perf. data 0.88 15818 106
service: refresh index statistics 0.03 324276 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-01-2023 19:41:06 to 31-01-2023 19:41:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1059
TEST_TIME 2 2512
TEST_TIME 3 4186
TEST_TIME 4 4107
TEST_TIME 5 4860
TEST_TIME 6 4856
TEST_TIME 7 5178
TEST_TIME 8 5092
TEST_TIME 9 5272
TEST_TIME 10 5667
TEST_TIME 11 5694
TEST_TIME 12 5786
TEST_TIME 13 6100
TEST_TIME 14 6446
TEST_TIME 15 6914
TEST_TIME 16 7042
TEST_TIME 17 7210
TEST_TIME 18 7752
TEST_TIME 19 7813
TEST_TIME 20 8146
TEST_TIME 21 8559
TEST_TIME 22 8812
TEST_TIME 23 8569
TEST_TIME 24 8604
TEST_TIME 25 8909
TEST_TIME 26 9142
TEST_TIME 27 9887
TEST_TIME 28 10270
TEST_TIME 29 10127
TEST_TIME 30 10203
TEST_TIME 31 10364
TEST_TIME 32 10286
TEST_TIME 33 10508
TEST_TIME 34 10290
TEST_TIME 35 10041
TEST_TIME 36 9949
TEST_TIME 37 9911
TEST_TIME 38 9745
TEST_TIME 39 9996
TEST_TIME 40 9903
TEST_TIME 41 9790
TEST_TIME 42 9720
TEST_TIME 43 9622
TEST_TIME 44 9527
TEST_TIME 45 9605
TEST_TIME 46 9547
TEST_TIME 47 9601
TEST_TIME 48 9609
TEST_TIME 49 9563
TEST_TIME 50 9508
TEST_TIME 51 9449
TEST_TIME 52 9415
TEST_TIME 53 9334
TEST_TIME 54 9247
TEST_TIME 55 9242
TEST_TIME 56 9161
TEST_TIME 57 9191
TEST_TIME 58 9212
TEST_TIME 59 9255
TEST_TIME 60 9213
TEST_TIME 61 9350
TEST_TIME 62 9367
TEST_TIME 63 9463
TEST_TIME 64 9444
TEST_TIME 65 9583
TEST_TIME 66 9526
TEST_TIME 67 9579
TEST_TIME 68 9530
TEST_TIME 69 9578
TEST_TIME 70 9682
TEST_TIME 71 9573
TEST_TIME 72 9546
TEST_TIME 73 9506
TEST_TIME 74 9478
TEST_TIME 75 9468
TEST_TIME 76 9404
TEST_TIME 77 9345
TEST_TIME 78 9268
TEST_TIME 79 9291
TEST_TIME 80 9277
TEST_TIME 81 9251
TEST_TIME 82 9261
TEST_TIME 83 9314
TEST_TIME 84 9321
TEST_TIME 85 9357
TEST_TIME 86 9372
TEST_TIME 87 9354
TEST_TIME 88 9356
TEST_TIME 89 9315
TEST_TIME 90 9435
TEST_TIME 91 9448
TEST_TIME 92 9490
TEST_TIME 93 9483
TEST_TIME 94 9388
TEST_TIME 95 9329
TEST_TIME 96 9347
TEST_TIME 97 9306
TEST_TIME 98 9267
TEST_TIME 99 9253
TEST_TIME 100 9241
TEST_TIME 101 9307
TEST_TIME 102 9302
TEST_TIME 103 9307
TEST_TIME 104 9267
TEST_TIME 105 9267
TEST_TIME 106 9382
TEST_TIME 107 9402
TEST_TIME 108 9423
TEST_TIME 109 9421
TEST_TIME 110 9465
TEST_TIME 111 9469
TEST_TIME 112 9460
TEST_TIME 113 9409
TEST_TIME 114 9353
TEST_TIME 115 9360
TEST_TIME 116 9317
TEST_TIME 117 9293
TEST_TIME 118 9299
TEST_TIME 119 9256
TEST_TIME 120 9209

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-01-2023 19:41:10 to 31-01-2023 19:41:13.

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 1527780 1527780 0 0.00 0 22634 5 0 0
doc_list_biud 1527780 1527780 0 0.00 0 51502 26 0 0
sp_add_invoice_to_stock 5077 4933 144 2.83 20 185889 16906 0 144
sp_cancel_adding_invoice 5735 5735 0 0.00 3 86640 825 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 13643 410 0 0
sp_cancel_customer_reserve 565279 565279 0 0.00 0 51504 55 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 2 7807 98 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 1603 64 0 0
sp_cancel_supplier_invoice 6280 6270 10 0.15 1 22739 669 0 10
sp_cancel_supplier_order 416 416 0 0.00 10 999360 93411 0 0
sp_cancel_write_off 9886 9886 0 0.00 1 22184 106 0 0
sp_client_order 19945 19945 0 0.00 3 43845 409 0 0
sp_customer_reserve 455235 455235 0 0.00 0 85454 167 0 0
sp_fill_shopping_cart 30627 30627 0 0.00 1 18484 38 0 0
sp_fill_shopping_cart_clo_res 455234 455234 0 0.00 0 18023 14 0 0
sp_get_clo_for_invoice 4931 4931 0 0.00 0 31288 329 0 0
sp_kill_cost_storno 16687 16687 0 0.00 0 8026 49 0 0
sp_kill_qstorno_ret_qs2qd 1147130 1147130 0 0.00 0 51500 29 0 0
sp_kill_qty_storno 603930 603930 0 0.00 0 51502 64 0 0
sp_lock_selected_doc 20260 20260 0 0.00 0 7975 7 0 0
sp_make_cost_storno 27211 27211 0 0.00 0 22624 46 0 0
sp_multiply_rows_for_pdistr 27211 27211 0 0.00 0 12831 25 0 0
sp_multiply_rows_for_qdistr 19945 19945 0 0.00 0 18556 173 0 0
sp_pay_from_customer 6977 6977 0 0.00 4 14024 95 0 0
sp_pay_to_supplier 5619 5619 0 0.00 4 22639 122 0 0
sp_payment_common 12596 12596 0 0.00 1 12705 24 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 13636 377 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 29756 321 0 0
sp_qd_handle_on_reserve_upd_sts 9684 9684 0 0.00 0 12168 64 0 0
sp_reserve_write_off 9684 9684 0 0.00 5 17713 177 0 0
sp_supplier_invoice 5348 5348 0 0.00 6 108327 3498 0 0
sp_supplier_order 5352 5352 0 0.00 5 45825 1255 0 0
srv_aggregate_perf_data 106 106 0 0.00 2667 98857 15818 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1431 141579 51213 0 0
srv_find_qd_qs_mism 1059031 1059031 0 0.00 0 21667 5 0 0
srv_make_invnt_saldo 508 508 0 0.00 41 68976 1251 0 0
srv_make_money_saldo 765 765 0 0.00 1 929 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 240281 503143 324276 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4935 4935 0 0.00 0 25021 192 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 20316 169 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 3694 16 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1511 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 728 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 434 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 5794 28 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 15888 1428 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 1322 4 0 0
t$perf-norm:sp_client_order 19945 19945 0 0.00 0 11097 20 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 2218 8 0 0
t$perf-norm:sp_pay_from_customer 6977 6977 0 0.00 0 12622 13 0 0
t$perf-norm:sp_pay_to_supplier 5619 5619 0 0.00 0 9588 13 0 0
t$perf-norm:sp_reserve_write_off 9684 9684 0 0.00 0 4753 11 0 0
t$perf-norm:sp_supplier_invoice 5348 5348 0 0.00 0 4389 19 0 0
t$perf-norm:sp_supplier_order 5352 5352 0 0.00 0 6473 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 365 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 654 16 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1474 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10150 9862 288 2.83 0 14019 24 0 288
v_all_customers 36356 36356 0 0.00 0 27 1 0 0
v_all_suppliers 29272 29272 0 0.00 0 21 1 0 0
v_all_wares 39890 39890 0 0.00 0 142 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2643 12 0 0
v_cancel_client_order 3458 3458 0 0.00 0 2291 5 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2319 11 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 13981 14 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 683 6 0 0
v_cancel_supplier_invoice 794 774 20 2.51 0 2291 16 0 20
v_cancel_supplier_order 832 832 0 0.00 0 250 4 0 0
v_cancel_write_off 1938 1938 0 0.00 0 2122 12 0 0
v_max_id_clo_ord 5350 5350 0 0.00 0 965 1 0 0
v_max_id_clo_res 975 975 0 0.00 0 1198 10 0 0
v_max_id_ord_sup 5331 5331 0 0.00 0 5052 6 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 412 3 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 2703 4 0 0
v_min_id_clo_ord 5350 5350 0 0.00 0 5466 9 0 0
v_min_id_clo_res 975 975 0 0.00 0 2384 6 0 0
v_min_id_ord_sup 5331 5331 0 0.00 0 6912 9 0 0
v_min_non_paid_invoice 5619 5619 0 0.00 0 12461 15 0 0
v_min_non_paid_realizn 6977 6977 0 0.00 0 8718 19 0 0
v_our_firm 10072 10072 0 0.00 0 17 1 0 0
v_reserve_write_off 19368 19368 0 0.00 0 13794 8 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 26901 134 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 10248 431 0 0
x_make_qstorno_1000_1200 5352 5352 0 0.00 1 45814 1178 0 0
x_make_qstorno_1200_2000 5348 5348 0 0.00 3 108309 3354 0 0
x_make_qstorno_2100_3300 455235 455235 0 0.00 0 85452 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2023 19:41:14 to 31-01-2023 19:41:18.

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:45 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:45 17754 8192000000 9899374048 10207866880 985304976 10.736 100 90 180 0 1015030400 6121392 23604032
17:11:07 14863 8192000000 9943369936 10263347200 1025563280 11.126 100 91 182 0 1057668528 8507056 23598192
17:12:43 14902 8192000000 9990704144 10324377600 1075590736 11.605 100 87 172 0 1107009728 10196624 21222368
17:14:10 7920 8192000000 10036681312 10382381056 1121455232 12.041 100 83 166 0 1152372848 11183984 19733632
17:15:23 5400 8192000000 10078183888 10437738496 1166999168 12.469 100 79 158 0 1197792944 12787056 18006720
17:16:35 3588 8192000000 10128306928 10504613888 1220997440 12.971 100 65 130 0 1246660960 10703968 14959552
17:17:40 6285 8192000000 10165331424 10539024384 1246779232 13.209 100 88 176 0 1280995200 15086016 19129952
17:18:49 1216 8192000000 10188393968 10572398592 1282348816 13.534 100 82 164 0 1312287504 11497728 18440960
17:19:52 1036 8192000000 10212625824 10598227968 1303251264 13.725 100 86 171 0 1336276800 13309376 19716160
17:20:53 4285 8192000000 10196904032 10602700800 1302400896 13.717 100 33 66 0 1318372928 8380144 7591888
17:22:02 6231 8192000000 10203495280 10617450496 1314167392 13.824 100 21 42 0 1324465392 5083328 5214672
17:23:10 18290 8192000000 10204134672 10624262144 1322324528 13.898 100 16 28 0 1330316800 5316928 2675344
17:24:29 27892 8192000000 10228502816 10643431424 1331980320 13.985 100 41 80 0 1347085248 5987408 9117520
17:25:57 7483 8192000000 10230425632 10652057600 1336562720 14.026 100 18 34 1 1350151024 9756112 3832192
17:27:05 11006 8192000000 10231040624 10650013696 1336553424 14.026 100 18 34 1 1346472528 5799360 4119744
17:28:17 52411 8192000000 10203959408 10610782208 1281415600 13.526 97 33 64 1 1298289504 9303888 7570016
17:30:10 8777 8192000000 10183946080 10595536896 1284609328 13.555 99 26 52 0 1297458496 6845200 6003968
17:31:22 6440 8192000000 10146476480 10549469184 1245210336 13.194 98 18 34 0 1254449840 5825280 3414224
17:32:30 13824 8192000000 10146106720 10548563968 1217136336 12.935 96 19 36 0 1227242656 5308896 4797424
17:33:45 38307 8192000000 10143879344 10546397184 1250591520 13.244 99 32 66 0 1263534656 4650640 8292496
17:35:24 2938 8192000000 10192337360 10598055936 1293082768 13.632 100 50 100 1 1312264240 8014848 11166624
17:36:28 8083 8192000000 10201700688 10614181888 1308561120 13.773 100 19 39 0 1318516976 5281376 4674480
17:37:36 8852 8192000000 10213515536 10627190784 1319308880 13.870 100 24 48 1 1330985968 5539888 6137200
17:38:47 98778 8192000000 10237280560 10656473088 1338394048 14.043 100 50 98 1 1355816080 5950528 11471504
17:41:33 12470 8192000000 10268346704 10690908160 1363005568 14.264 100 40 81 0 1383921696 11595792 9320336
17:42:52 43801 8192000000 10281590256 10712776704 1384446880 14.456 100 25 50 0 1401925584 11345312 6133392
17:44:48 10440 8192000000 10307632224 10734227456 1396962256 14.568 100 54 108 0 1427697968 17516016 13219696
17:46:07 10740 8192000000 10301302912 10729140224 1386695648 14.476 99 41 80 2 1415269936 18128384 10445904
17:47:23 12495 8192000000 10317163232 10746003456 1400794848 14.602 100 44 88 0 1430571808 19489376 10287584
17:48:43 1429 8192000000 10337884160 10756616192 1406159568 14.650 100 73 146 3 1444954864 22376176 16419120
17:49:48 6471 8192000000 10341750592 10762428416 1405093776 14.640 100 71 142 1 1449750800 28058768 16598256
17:50:59 5413 8192000000 10321051472 10753851392 1381292576 14.428 98 39 77 1 1416718560 25181680 10244304
17:52:11 1518 8192000000 10346713008 10758098944 1400245712 14.597 100 78 158 0 1448310704 29175264 18889728
17:53:18 1879 8192000000 10338649056 10751700992 1386954208 14.479 99 89 174 2 1439888480 31947488 20986784
17:54:25 4321 8192000000 10332037088 10747109376 1384823920 14.460 99 66 132 1 1433564736 32616816 16124000
17:55:33 2796 8192000000 10320590704 10733146112 1371280160 14.339 99 69 133 0 1427941840 39715520 16946160
17:56:41 27109 8192000000 10304470096 10727211008 1342932944 14.084 98 39 76 0 1391569776 38286640 10350192
17:58:17 3094 8192000000 10346017072 10757472256 1382661888 14.440 100 74 146 0 1445712080 44544736 18505456
17:59:24 8376 8192000000 10324303680 10749267968 1383894816 14.451 100 37 74 0 1427555248 33490960 10169472
18:00:38 17018 8192000000 10324886000 10761736192 1391383872 14.518 100 36 72 1 1439419632 38622256 9413504
18:01:57 7514 8192000000 10342142096 10767278080 1399909520 14.594 100 44 84 0 1444175936 33176528 11089888
18:03:18 15104 8192000000 10306489808 10739138560 1352906832 14.174 98 35 70 0 1396797296 34221776 9668688
18:04:35 18513 8192000000 10293553616 10739458048 1355995312 14.201 100 18 36 0 1405468512 43846864 5626336
18:06:23 21300 8192000000 10262857376 10708111360 1335289520 14.015 99 24 50 0 1360394368 19437776 5667072
18:08:06 38075 8192000000 10285603024 10728071168 1348806160 14.137 98 18 36 0 1373819872 20583904 4429808
18:09:45 60234 8192000000 10305248176 10744602624 1341189232 14.068 97 18 38 1 1371334080 26002032 4142816
18:11:56 93216 8192000000 10306392752 10732552192 1360276560 14.240 99 29 59 2 1378209136 11608016 6324560
18:14:30 70434 8192000000 10282365712 10735763456 1377333184 14.393 100 16 32 0 1394697424 13506304 3857936
18:16:49 105436 8192000000 10285327104 10756481024 1364374640 14.277 98 24 49 0 1381692960 11476160 5842160
18:19:37 41955 8192000000 10291710656 10749349888 1382451344 14.438 99 13 26 1 1397037664 11266240 3320080
18:21:25 132657 8192000000 10315022752 10776334336 1402763712 14.620 100 16 32 0 1420692512 13312272 4616528
18:24:46 100599 8192000000 10314636336 10779983872 1411478416 14.697 100 21 43 0 1436549648 20016880 5054352
18:28:16 50378 8192000000 10303869168 10763104256 1369944592 14.327 97 20 40 0 1384286080 9818000 4523488
18:30:10 54992 8192000000 10301202096 10766217216 1405250976 14.642 100 16 32 1 1418156160 9320528 3584656
18:32:05 49312 8192000000 10300757984 10773815296 1394346272 14.545 99 14 26 0 1413343488 16607392 2389824
18:34:00 80856 8192000000 10326021760 10788614144 1411657312 14.699 100 6 12 0 1425776064 12953968 1164784
18:36:25 19337 8192000000 10312669216 10767593472 1405553696 14.644 100 20 40 0 1419317808 9510512 4253600
18:37:53 23177 8192000000 10299103952 10747195392 1390856656 14.514 100 38 74 0 1406731920 8214352 7660912
18:39:40 49278 8192000000 10296584688 10756325376 1373231232 14.356 98 27 54 0 1391197744 12002016 5964496
18:41:30 48393 8192000000 10278453920 10727071744 1311938624 13.804 95 11 22 0 1326046400 11856912 2250864
18:43:21 70462 8192000000 10260880304 10699350016 1278531216 13.500 94 9 17 0 1290749360 10762320 1455824
18:45:32 62060 8192000000 10273303936 10722988032 1329782704 13.965 97 6 12 1 1340635552 9711696 1141152
18:47:34 106739 8192000000 10271039792 10714976256 1304257232 13.734 95 17 34 0 1325622608 17449664 3915712
18:50:52 78113 8192000000 10258337776 10712231936 1347109088 14.121 99 35 70 0 1367403664 12439712 7854864
18:53:11 55962 8192000000 10238348016 10700500992 1333862064 14.002 98 10 20 0 1344058976 7918112 2278800
18:55:07 65945 8192000000 10268765248 10718752768 1347553472 14.125 99 12 24 0 1361771824 10768256 3450096
18:57:15 74101 8192000000 10265671312 10727247872 1352106816 14.166 99 6 12 1 1364178896 10464048 1608032
18:59:31 35833 8192000000 10267386128 10714476544 1329187664 13.960 97 31 62 0 1348521712 12577440 6756608
19:01:07 83964 8192000000 10267468944 10736844800 1332759984 13.992 97 15 30 0 1350902992 14633136 3509872
19:04:37 77843 8192000000 10292679088 10739433472 1374032944 14.363 100 25 50 0 1388481568 9356352 5092272
19:06:58 32787 8192000000 10292370944 10732744704 1354517728 14.188 98 34 69 0 1377004976 14244816 8242432
19:08:33 131804 8192000000 10302871312 10766888960 1356824752 14.209 97 12 22 0 1370089552 10918960 2345840
19:11:45 139316 8192000000 10298629904 10765705216 1389309392 14.500 99 25 50 0 1411381744 16340944 5731408
19:15:12 31114 8192000000 10292890240 10776186880 1386510720 14.475 99 2 4 0 1396720640 9990816 219104
19:16:44 26355 8192000000 10304972720 10762764288 1387536928 14.484 99 26 52 0 1400661744 7149600 5975216
19:18:14 79413 8192000000 10316826896 10792951808 1413300320 14.713 100 20 40 0 1438075184 19949104 4825760
19:21:02 47788 8192000000 10322174448 10785099776 1388369856 14.491 98 21 42 0 1406722352 13645728 4706768
19:22:54 75327 8192000000 10324429376 10782400512 1396659024 14.565 99 24 48 0 1418727456 16509360 5559072
19:25:14 90050 8192000000 10299620672 10761584640 1359977296 14.237 97 13 26 0 1377159888 14525424 2657168
19:27:56 68442 8192000000 10309336768 10764218368 1362015456 14.255 97 12 24 0 1376174368 11574704 2584208
19:30:09 141579 8192000000 10267162368 10719838208 1293879376 13.640 94 29 58 0 1311810224 11868688 6062160
19:33:38 72709 8192000000 10233821664 10696613888 1303647152 13.728 97 9 18 0 1316829520 11160528 2021840
19:35:51 59952 8192000000 10236786128 10701082624 1313798032 13.821 98 7 14 0 1323627504 8357008 1472464
19:37:55 67286 8192000000 9296060112 9587101696 454918336 5.261 34 10 20 0 467793232 10581696 2293200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-01-2023 19:41:20 to 31-01-2023 19:41:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2023 19:41:36 to 31-01-2023 19:41:38.

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          237594
MON$OLDEST_ACTIVE               237595
MON$OLDEST_SNAPSHOT             237595
MON$NEXT_TRANSACTION            237595
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                       2040800
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:1061: 
:1062: mscs-test-161.budzdorov.ru    Tue Jan 31 19:39:18 2023
:1063:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1064: 
--- end of diff output ---

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

31.01.23 19:41:39. 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-01-31 19:41:42 No severe PSQL-related problems occured
31.01.23 19:41:43. End of report.