Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.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 15185388 kB
MemAvailable 30417992 kB
Buffers 0 kB
Cached 16540728 kB
SwapCached 3348 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-02-16 19:38:39
DTS_END                         2023-02-16 19:38:39.4260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44746
TRN_ID                          243977
ATT_ID                          722
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.41 19512 1104889
customer order: creation 176.23 363 21147
customer order: refuse 14.73 353 1768
order to supplier: creation 46.84 1290 5621
order to supplier: removal 3.08 110999 369
invoice (draft): creation 46.51 3553 5581
invoice (draft): removal 51.20 927 6144
invoice accept: apply 42.39 17260 5087
invoice accept: cancel 46.42 738 5570
customer reserve: creation 3906.38 172 468765
customer reserve: removal 4579.18 57 549501
realization accept: apply 84.96 198 10195
realization accept: cancel 78.53 102 9424
payment from customer: creation 60.00 140 7200
payment from customer: removal 5.70 73 684
payment to supplier: creation 49.90 138 5988
payment to supplier: removal 3.28 87 393
service: total inventory turnovers 4.47 1191 537
service: total monetary turnovers 6.86 28 823
service: aggregate perf. data 0.73 21454 88
service: refresh index statistics 0.03 231107 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2023 19:40:34 to 16-02-2023 19:40:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3499
TEST_TIME 2 5741
TEST_TIME 3 4767
TEST_TIME 4 4834
TEST_TIME 5 5025
TEST_TIME 6 5214
TEST_TIME 7 5130
TEST_TIME 8 5498
TEST_TIME 9 5306
TEST_TIME 10 5452
TEST_TIME 11 6079
TEST_TIME 12 6358
TEST_TIME 13 6888
TEST_TIME 14 7201
TEST_TIME 15 7341
TEST_TIME 16 7597
TEST_TIME 17 7859
TEST_TIME 18 8108
TEST_TIME 19 8352
TEST_TIME 20 8453
TEST_TIME 21 8476
TEST_TIME 22 8802
TEST_TIME 23 8787
TEST_TIME 24 8974
TEST_TIME 25 8911
TEST_TIME 26 8755
TEST_TIME 27 8826
TEST_TIME 28 8806
TEST_TIME 29 8865
TEST_TIME 30 9679
TEST_TIME 31 10444
TEST_TIME 32 10965
TEST_TIME 33 10653
TEST_TIME 34 10517
TEST_TIME 35 10299
TEST_TIME 36 10343
TEST_TIME 37 10163
TEST_TIME 38 9972
TEST_TIME 39 9916
TEST_TIME 40 9897
TEST_TIME 41 9850
TEST_TIME 42 9868
TEST_TIME 43 9797
TEST_TIME 44 9617
TEST_TIME 45 9588
TEST_TIME 46 9593
TEST_TIME 47 9419
TEST_TIME 48 9387
TEST_TIME 49 9316
TEST_TIME 50 9237
TEST_TIME 51 9199
TEST_TIME 52 9083
TEST_TIME 53 9050
TEST_TIME 54 9211
TEST_TIME 55 9177
TEST_TIME 56 9188
TEST_TIME 57 9240
TEST_TIME 58 9293
TEST_TIME 59 9286
TEST_TIME 60 9295
TEST_TIME 61 9407
TEST_TIME 62 9406
TEST_TIME 63 9383
TEST_TIME 64 9353
TEST_TIME 65 9340
TEST_TIME 66 9454
TEST_TIME 67 9488
TEST_TIME 68 9509
TEST_TIME 69 9485
TEST_TIME 70 9382
TEST_TIME 71 9377
TEST_TIME 72 9322
TEST_TIME 73 9303
TEST_TIME 74 9318
TEST_TIME 75 9263
TEST_TIME 76 9222
TEST_TIME 77 9176
TEST_TIME 78 9131
TEST_TIME 79 9121
TEST_TIME 80 9195
TEST_TIME 81 9199
TEST_TIME 82 9262
TEST_TIME 83 9240
TEST_TIME 84 9204
TEST_TIME 85 9314
TEST_TIME 86 9312
TEST_TIME 87 9300
TEST_TIME 88 9299
TEST_TIME 89 9326
TEST_TIME 90 9428
TEST_TIME 91 9436
TEST_TIME 92 9462
TEST_TIME 93 9371
TEST_TIME 94 9346
TEST_TIME 95 9300
TEST_TIME 96 9252
TEST_TIME 97 9230
TEST_TIME 98 9182
TEST_TIME 99 9251
TEST_TIME 100 9293
TEST_TIME 101 9238
TEST_TIME 102 9267
TEST_TIME 103 9263
TEST_TIME 104 9241
TEST_TIME 105 9259
TEST_TIME 106 9239
TEST_TIME 107 9239
TEST_TIME 108 9296
TEST_TIME 109 9288
TEST_TIME 110 9277
TEST_TIME 111 9378
TEST_TIME 112 9376
TEST_TIME 113 9310
TEST_TIME 114 9348
TEST_TIME 115 9352
TEST_TIME 116 9318
TEST_TIME 117 9302
TEST_TIME 118 9276
TEST_TIME 119 9257
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-02-2023 19:40:38 to 16-02-2023 19:40:41.

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 1539647 1539647 0 0.00 0 20650 6 0 0
doc_list_biud 1539642 1539642 0 0.00 0 23701 26 0 0
sp_add_invoice_to_stock 5215 5087 128 2.45 31 167901 17260 0 128
sp_cancel_adding_invoice 5570 5570 0 0.00 4 46763 739 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 11579 354 0 0
sp_cancel_customer_reserve 549501 549501 0 0.00 0 17118 57 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 4364 74 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5207 87 0 0
sp_cancel_supplier_invoice 6155 6144 11 0.17 1 23782 928 0 11
sp_cancel_supplier_order 369 369 0 0.00 5 960573 110999 0 0
sp_cancel_write_off 9424 9424 0 0.00 1 9472 103 0 0
sp_client_order 21147 21147 0 0.00 3 76939 363 0 0
sp_customer_reserve 468765 468765 0 0.00 0 112139 172 0 0
sp_fill_shopping_cart 32344 32344 0 0.00 1 12193 43 0 0
sp_fill_shopping_cart_clo_res 468764 468764 0 0.00 0 12285 12 0 0
sp_get_clo_for_invoice 5086 5086 0 0.00 0 34939 299 0 0
sp_kill_cost_storno 16071 16071 0 0.00 0 6267 55 0 0
sp_kill_qstorno_ret_qs2qd 1114939 1114939 0 0.00 0 23692 32 0 0
sp_kill_qty_storno 588054 588054 0 0.00 0 23697 69 0 0
sp_lock_selected_doc 20998 20998 0 0.00 0 9633 5 0 0
sp_make_cost_storno 28469 28469 0 0.00 0 20638 66 0 0
sp_multiply_rows_for_pdistr 28467 28467 0 0.00 0 9720 31 0 0
sp_multiply_rows_for_qdistr 21147 21147 0 0.00 0 76933 153 0 0
sp_pay_from_customer 7200 7200 0 0.00 4 19113 140 0 0
sp_pay_to_supplier 5988 5988 0 0.00 4 18177 138 0 0
sp_payment_common 13186 13186 0 0.00 1 16459 45 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 11570 322 0 0
sp_qd_handle_on_invoice_upd_sts 10655 10655 0 0.00 0 20298 329 0 0
sp_qd_handle_on_reserve_upd_sts 10193 10193 0 0.00 0 11116 55 0 0
sp_reserve_write_off 10195 10195 0 0.00 6 18568 199 0 0
sp_supplier_invoice 5581 5581 0 0.00 5 77366 3553 0 0
sp_supplier_order 5621 5621 0 0.00 5 27916 1291 0 0
srv_aggregate_perf_data 88 88 0 0.00 2280 256980 21454 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1363 136045 45363 0 0
srv_find_qd_qs_mism 1056719 1056719 0 0.00 0 11437 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 51 29821 1191 0 0
srv_make_money_saldo 823 823 0 0.00 1 2012 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 112558 377197 231107 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 13437 158 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 9965 109 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 2773 13 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1383 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 1001 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 2124 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2467 17 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 38012 1649 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 2081 9 0 0
t$perf-norm:sp_client_order 21147 21147 0 0.00 0 12655 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 1267 6 0 0
t$perf-norm:sp_pay_from_customer 7200 7200 0 0.00 0 11190 10 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 10173 9 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 12586 11 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 8433 17 0 0
t$perf-norm:sp_supplier_order 5621 5621 0 0.00 0 7757 11 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 194 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2564 40 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 1361 18 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 1639 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10420 10164 256 2.45 0 5958 21 0 256
v_all_customers 38434 38434 0 0.00 0 90 1 0 0
v_all_suppliers 30708 30708 0 0.00 0 26 1 0 0
v_all_wares 42294 42294 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 537 7 0 0
v_cancel_client_order 3536 3536 0 0.00 0 4441 7 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 636 4 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1323 5 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 520 4 0 0
v_cancel_supplier_invoice 760 738 22 2.89 0 503 15 0 22
v_cancel_supplier_order 738 738 0 0.00 0 2296 11 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3013 17 0 0
v_max_id_clo_ord 5621 5621 0 0.00 0 1174 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1777 10 0 0
v_max_id_ord_sup 5576 5576 0 0.00 0 8053 6 0 0
v_max_non_paid_invoice 1830 1830 0 0.00 0 511 3 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 4712 9 0 0
v_min_id_clo_ord 5621 5621 0 0.00 0 3552 7 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 490 2 0 0
v_min_id_ord_sup 5576 5576 0 0.00 0 6935 11 0 0
v_min_non_paid_invoice 5987 5987 0 0.00 0 8294 30 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 8836 36 0 0
v_our_firm 10456 10456 0 0.00 0 16 1 0 0
v_reserve_write_off 20384 20384 0 0.00 0 11838 9 0 0
x_lock_depdocs_on_canc_invoice 11345 11345 0 0.00 0 27903 123 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 7175 384 0 0
x_make_qstorno_1000_1200 5621 5621 0 0.00 2 27901 1208 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 2 71741 3397 0 0
x_make_qstorno_2100_3300 468765 468765 0 0.00 0 112138 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2023 19:40:42 to 16-02-2023 19:40:46.

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:43 4 8192000000 8801697632 8814039040 7596000 0.092 3 2 2 0 7704480 65696 42784
17:09:44 21967 8192000000 9910689312 10222735360 985920992 10.742 100 79 155 0 1012020384 5907424 20191968
17:11:11 18261 8192000000 9950067680 10269356032 1032550608 11.193 100 91 182 0 1064165248 8189824 23424816
17:12:35 22225 8192000000 9990279504 10327117824 1067145856 11.525 100 78 156 0 1095092512 8761776 19184880
17:14:02 11684 8192000000 10037118544 10386350080 1122219296 12.048 100 79 154 1 1150828496 10739792 17869408
17:15:16 7761 8192000000 10074844560 10435973120 1164549536 12.446 100 75 151 1 1193455568 11571664 17334368
17:16:25 1758 8192000000 10123569808 10493493248 1212373152 12.891 100 78 153 0 1240908208 11323328 17211728
17:17:31 1447 8192000000 10158046128 10532134912 1247676272 13.217 100 83 163 3 1278171872 12079232 18416368
17:18:34 2978 8192000000 10184748512 10563780608 1267631184 13.400 100 83 166 0 1299704416 14393728 17679504
17:19:39 1250 8192000000 10210842512 10594381824 1294177632 13.642 100 91 179 1 1327861216 13322208 20361376
17:20:41 33623 8192000000 10217539216 10619318272 1314865984 13.830 100 59 118 0 1336797792 7970128 13961680
17:22:16 6602 8192000000 10206813056 10626994176 1318435728 13.863 100 23 46 0 1334721376 11419104 4866544
17:23:23 11359 8192000000 10205709472 10626060288 1323782704 13.911 100 21 42 1 1334382656 6259280 4340672
17:24:36 14360 8192000000 10222237120 10641227776 1340051808 14.058 100 33 65 1 1353521920 5050592 8419520
17:25:52 18001 8192000000 10229160000 10657153024 1338019648 14.040 100 30 60 1 1355144688 10537856 6587184
17:27:13 5667 8192000000 10241042992 10657546240 1331017776 13.976 99 32 65 0 1344368224 6253024 7097424
17:28:22 3649 8192000000 10221724032 10633658368 1312182208 13.806 99 32 65 0 1333835744 14715888 6937648
17:29:28 66321 8192000000 10150658208 10556989440 1164015808 12.441 90 34 68 2 1175962960 4023488 7923664
17:31:36 2723 8192000000 10150100416 10550677504 1252558192 13.262 100 32 64 1 1266586112 7064816 6963104
17:32:39 7216 8192000000 10140228464 10542886912 1230029600 13.054 98 20 40 0 1240301952 5444544 4827808
17:33:49 16388 8192000000 10153350448 10557632512 1251179440 13.249 98 15 30 1 1259955552 5527952 3248160
17:35:07 19789 8192000000 10173179968 10588020736 1285581168 13.564 100 20 40 1 1295158816 5014400 4563248
17:36:30 9143 8192000000 10186189040 10597847040 1296498384 13.663 100 19 34 0 1305789264 5252624 4038256
17:37:42 15299 8192000000 10191725664 10613055488 1305809136 13.748 100 16 32 0 1314826448 5063312 3954000
17:39:01 10138 8192000000 10244879904 10650075136 1332871696 13.993 100 58 111 4 1351134592 5412960 12849936
17:40:13 32703 8192000000 10252576512 10670051328 1345602032 14.108 100 38 74 0 1362873088 8242272 9028784
17:41:52 24943 8192000000 10288720816 10703093760 1368895328 14.317 100 51 101 0 1393735072 13999712 10840032
17:43:20 9498 8192000000 10282330160 10700955648 1372502176 14.349 100 55 107 0 1398862960 14147296 12213488
17:44:41 29366 8192000000 10292446336 10715242496 1375421504 14.376 100 47 90 1 1401619920 15858304 10340112
17:46:17 15405 8192000000 10307859472 10723753984 1381800688 14.433 100 56 111 2 1414885184 19409936 13674560
17:47:37 18508 8192000000 10304151520 10731720704 1389798800 14.504 100 36 71 2 1415594848 16836992 8959056
17:49:06 4537 8192000000 10306094400 10722611200 1357290016 14.213 97 59 116 1 1391418848 20082848 14045984
17:50:16 6689 8192000000 10304123680 10735210496 1378642992 14.404 99 34 66 0 1410659936 23786272 8230672
17:51:32 12391 8192000000 10300101088 10729218048 1382340704 14.437 100 37 74 1 1408621728 17669824 8611200
17:52:54 1353 8192000000 10323708384 10730999808 1391038704 14.515 100 81 160 1 1432621728 22862848 18720176
17:54:01 3431 8192000000 10303277856 10708799488 1353129024 14.176 98 74 146 1 1397243616 26327488 17787104
17:55:08 2439 8192000000 10312234112 10719866880 1365847488 14.290 100 73 137 2 1412450240 30877712 15725040
17:56:15 1435 8192000000 10310877216 10711564288 1356460176 14.206 100 79 156 0 1409306928 34289392 18557360
17:57:21 5188 8192000000 10293486064 10711072768 1356957296 14.210 100 53 105 0 1402700224 32617600 13125328
17:58:38 4326 8192000000 10313259696 10725797888 1366035616 14.292 100 57 113 0 1416428176 36064992 14327568
17:59:46 14265 8192000000 10298622144 10716188672 1338872112 14.047 98 46 92 1 1390476576 38923872 12680592
18:01:10 8606 8192000000 10326174384 10744934400 1381469808 14.430 100 45 88 1 1432641424 39912080 11259536
18:02:27 8049 8192000000 10334173344 10761912320 1393070288 14.533 100 51 102 1 1444278336 36874128 14333920
18:03:48 11698 8192000000 10335356880 10761670656 1384701344 14.459 99 39 78 0 1433329456 38335760 10292352
18:05:07 13268 8192000000 10345974048 10776756224 1401308416 14.607 100 34 64 1 1453647520 43096704 9242400
18:06:25 17652 8192000000 10355447024 10783502336 1386116480 14.471 100 36 73 0 1451533264 54629792 10786992
18:07:50 13196 8192000000 10359290528 10793873408 1381995168 14.434 100 33 66 0 1462379360 69734992 10649200
18:09:05 39973 8192000000 10307102896 10751016960 1345482944 14.107 97 17 34 1 1395594144 45694672 4416528
18:10:46 63198 8192000000 10298856112 10732748800 1379437472 14.412 100 45 81 1 1395700112 5237248 11025392
18:13:07 42804 8192000000 10291226800 10738053120 1378219296 14.401 100 32 61 1 1400963744 15408816 7335632
18:14:58 21371 8192000000 10286484032 10737860608 1384819392 14.460 100 22 44 2 1397950240 8345376 4785472
18:16:23 112446 8192000000 10307429376 10773667840 1387956832 14.488 99 21 42 0 1411428944 18211440 5260672
18:19:33 52478 8192000000 10294277440 10754244608 1365686000 14.288 98 13 26 0 1394770128 25552048 3532080
18:21:45 125565 8192000000 10288093216 10752004096 1356209520 14.203 98 20 40 0 1376425936 15616336 4600080
18:24:51 68272 8192000000 10283302032 10732888064 1371669728 14.342 99 15 28 0 1388812800 13915248 3227824
18:27:00 65393 8192000000 10295085008 10750025728 1378901536 14.407 99 13 26 0 1392859568 11085472 2872560
18:29:12 48710 8192000000 10296059024 10759188480 1384297792 14.455 99 16 33 0 1403110240 14809072 4003376
18:31:01 78377 8192000000 10296357520 10768310272 1392117216 14.525 100 20 40 0 1411377536 14316416 4943904
18:34:32 62088 8192000000 10288346912 10755756032 1383061552 14.444 100 17 34 0 1402173520 15246096 3865872
18:36:38 58439 8192000000 10285259760 10747420672 1350487808 14.152 98 19 38 1 1371408752 16739360 4181584
18:38:44 69215 8192000000 10290260464 10756407296 1353514144 14.179 98 18 36 0 1375954400 18842816 3597440
18:41:03 86980 8192000000 10272772640 10702700544 1199476336 12.771 87 14 25 1 1209705888 7303760 2925792
18:43:30 32508 8192000000 10285830368 10724040704 1354647712 14.189 99 35 68 0 1377353024 14410160 8295152
18:45:04 136045 8192000000 10261368080 10707124224 1232822208 13.080 91 17 34 0 1251046208 14811296 3412704
18:48:23 66452 8192000000 10236047472 10678329344 1335794304 14.019 100 27 53 0 1347556608 6552784 5209520
18:50:34 60210 8192000000 10260330992 10693320704 1340201344 14.059 100 39 76 0 1363027520 14136640 8689536
18:52:35 60377 8192000000 10229127200 10676883456 1309234160 13.779 98 15 30 0 1322920320 10536416 3149744
18:54:46 49482 8192000000 10248420064 10705510400 1340600864 14.063 99 16 32 0 1358076336 13377568 4097904
18:56:49 50392 8192000000 10250226208 10715688960 1338190320 14.041 99 9 18 0 1357619360 17439488 1989552
18:58:39 61287 8192000000 10261260560 10710237184 1339761408 14.055 99 21 42 0 1354850944 10484272 4605264
19:00:45 84435 8192000000 10277483984 10729545728 1354385584 14.187 99 13 26 0 1365483024 8394416 2703024
19:03:10 111360 8192000000 10279132208 10728644608 1291531840 13.618 94 13 26 0 1310176864 15937920 2707104
19:06:06 46824 8192000000 10295715072 10738876416 1376298544 14.383 100 33 66 0 1397390208 13784128 7307536
19:07:58 67036 8192000000 10303257120 10751066112 1371733264 14.343 99 15 30 0 1393360544 18499392 3127888
19:10:06 87488 8192000000 10284095552 10743541760 1359744272 14.235 98 9 18 0 1374572560 13154912 1673376
19:12:43 21984 8192000000 10293305968 10741039104 1381171568 14.427 100 32 63 0 1395424368 6773744 7479056
19:14:06 81702 8192000000 10290471216 10758606848 1378751920 14.405 99 5 10 0 1391342944 11474064 1116960
19:16:31 34546 8192000000 10294672048 10745974784 1368473424 14.313 98 31 62 0 1391030208 14625344 7931440
19:18:09 76283 8192000000 10286993936 10753716224 1360841664 14.245 98 29 58 0 1377139904 9805840 6492400
19:20:25 60272 8192000000 10289480240 10742231040 1352309056 14.168 98 12 25 1 1366924800 12390688 2225056
19:22:26 31984 8192000000 10282897264 10731024384 1352399920 14.169 98 26 52 0 1368006080 10075312 5530848
19:23:58 29608 8192000000 10297715248 10747670528 1374218560 14.365 99 26 53 0 1389427840 9445920 5763360
19:25:28 76889 8192000000 10279322816 10743439360 1339559312 14.053 97 7 14 1 1353758432 13119824 1079296
19:27:48 41254 8192000000 10289903760 10736750592 1327607536 13.946 97 34 67 0 1345030304 10351744 7071024
19:29:31 84864 8192000000 10277948720 10753261568 1343785104 14.092 98 9 18 0 1371246960 25621728 1840128
19:31:55 110457 8192000000 10269493248 10743189504 1333773792 14.001 97 16 33 0 1352382640 15013568 3595280
19:34:46 54228 8192000000 10260130192 10724007936 1365397696 14.286 100 11 20 0 1376633088 8944336 2291056
19:36:41 47267 8192000000 10242273616 10695753728 1297111744 13.669 95 16 30 0 1306177040 5849920 3215376
19:38:33 18919 8192000000 9372009632 9691566080 458963712 5.305 34 5 10 0 467683184 7751408 968064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-02-2023 19:40:48 to 16-02-2023 19:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 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 16-02-2023 19:41:04 to 16-02-2023 19:41:06.

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          243976
MON$OLDEST_ACTIVE               245127
MON$OLDEST_SNAPSHOT             245127
MON$NEXT_TRANSACTION            245127
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                       2044832
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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Thu Feb 16 19:38:59 2023
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Thu Feb 16 19:39:02 2023
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Thu Feb 16 19:39:02 2023
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
--- end of diff output ---

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

16.02.23 19:41:07. 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-02-16 19:41:10 No severe PSQL-related problems occured
16.02.23 19:41:11. End of report.