Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2022 01: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 15336800 kB
MemAvailable 30113388 kB
Buffers 0 kB
Cached 16407184 kB
SwapCached 3240 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 2022-12-25 01:38:33
DTS_END                         2022-12-25 01:38:33.1160
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/38778
TRN_ID                          239174
ATT_ID                          683
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: 9133.71 22571 1096045
customer order: creation 165.45 392 19854
customer order: refuse 14.21 323 1705
order to supplier: creation 44.48 1304 5338
order to supplier: removal 3.23 109043 387
invoice (draft): creation 45.99 3403 5519
invoice (draft): removal 51.62 851 6194
invoice accept: apply 41.43 17019 4972
invoice accept: cancel 47.22 853 5666
customer reserve: creation 3832.88 170 459946
customer reserve: removal 4590.77 60 550893
realization accept: apply 80.78 168 9694
realization accept: cancel 87.62 104 10514
payment from customer: creation 59.02 105 7082
payment from customer: removal 5.86 75 703
payment to supplier: creation 48.58 107 5829
payment to supplier: removal 3.34 55 401
service: total inventory turnovers 3.97 1104 476
service: total monetary turnovers 6.43 47 771
service: aggregate perf. data 0.81 17620 97
service: refresh index statistics 0.03 298610 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2022 01:40:25 to 25-12-2022 01:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5190
TEST_TIME 2 4642
TEST_TIME 3 5111
TEST_TIME 4 5263
TEST_TIME 5 5214
TEST_TIME 6 5122
TEST_TIME 7 4915
TEST_TIME 8 5047
TEST_TIME 9 4938
TEST_TIME 10 5114
TEST_TIME 11 5224
TEST_TIME 12 5260
TEST_TIME 13 5878
TEST_TIME 14 6203
TEST_TIME 15 6685
TEST_TIME 16 6896
TEST_TIME 17 7225
TEST_TIME 18 7593
TEST_TIME 19 8219
TEST_TIME 20 8949
TEST_TIME 21 9688
TEST_TIME 22 9491
TEST_TIME 23 9892
TEST_TIME 24 10550
TEST_TIME 25 10193
TEST_TIME 26 10149
TEST_TIME 27 10441
TEST_TIME 28 10416
TEST_TIME 29 10256
TEST_TIME 30 10230
TEST_TIME 31 10041
TEST_TIME 32 10302
TEST_TIME 33 10238
TEST_TIME 34 10103
TEST_TIME 35 9911
TEST_TIME 36 9839
TEST_TIME 37 9825
TEST_TIME 38 9758
TEST_TIME 39 9745
TEST_TIME 40 9754
TEST_TIME 41 9662
TEST_TIME 42 9571
TEST_TIME 43 9530
TEST_TIME 44 9491
TEST_TIME 45 9364
TEST_TIME 46 9395
TEST_TIME 47 9275
TEST_TIME 48 9226
TEST_TIME 49 9250
TEST_TIME 50 9177
TEST_TIME 51 9082
TEST_TIME 52 9119
TEST_TIME 53 9027
TEST_TIME 54 9153
TEST_TIME 55 9148
TEST_TIME 56 9109
TEST_TIME 57 9105
TEST_TIME 58 9198
TEST_TIME 59 9260
TEST_TIME 60 9180
TEST_TIME 61 9320
TEST_TIME 62 9350
TEST_TIME 63 9440
TEST_TIME 64 9456
TEST_TIME 65 9472
TEST_TIME 66 9455
TEST_TIME 67 9523
TEST_TIME 68 9497
TEST_TIME 69 9520
TEST_TIME 70 9442
TEST_TIME 71 9393
TEST_TIME 72 9435
TEST_TIME 73 9402
TEST_TIME 74 9395
TEST_TIME 75 9323
TEST_TIME 76 9308
TEST_TIME 77 9236
TEST_TIME 78 9150
TEST_TIME 79 9179
TEST_TIME 80 9293
TEST_TIME 81 9255
TEST_TIME 82 9347
TEST_TIME 83 9323
TEST_TIME 84 9354
TEST_TIME 85 9358
TEST_TIME 86 9390
TEST_TIME 87 9357
TEST_TIME 88 9415
TEST_TIME 89 9396
TEST_TIME 90 9490
TEST_TIME 91 9457
TEST_TIME 92 9528
TEST_TIME 93 9484
TEST_TIME 94 9423
TEST_TIME 95 9354
TEST_TIME 96 9342
TEST_TIME 97 9352
TEST_TIME 98 9289
TEST_TIME 99 9252
TEST_TIME 100 9209
TEST_TIME 101 9217
TEST_TIME 102 9186
TEST_TIME 103 9197
TEST_TIME 104 9232
TEST_TIME 105 9271
TEST_TIME 106 9249
TEST_TIME 107 9265
TEST_TIME 108 9269
TEST_TIME 109 9292
TEST_TIME 110 9328
TEST_TIME 111 9347
TEST_TIME 112 9359
TEST_TIME 113 9357
TEST_TIME 114 9332
TEST_TIME 115 9298
TEST_TIME 116 9306
TEST_TIME 117 9260
TEST_TIME 118 9250
TEST_TIME 119 9205
TEST_TIME 120 9140

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2022 01:40:29 to 25-12-2022 01:40:32.

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 1521567 1521567 0 0.00 0 27836 6 0 0
doc_list_biud 1521567 1521567 0 0.00 0 22980 28 0 0
sp_add_invoice_to_stock 5069 4972 97 1.91 31 178337 17020 0 97
sp_cancel_adding_invoice 5666 5666 0 0.00 4 53455 854 0 0
sp_cancel_client_order 1705 1705 0 0.00 4 10184 324 0 0
sp_cancel_customer_reserve 550893 550893 0 0.00 0 19754 60 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 8334 75 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 3506 55 0 0
sp_cancel_supplier_invoice 6201 6194 7 0.11 1 15359 851 0 7
sp_cancel_supplier_order 387 387 0 0.00 8 1021726 109043 0 0
sp_cancel_write_off 10514 10514 0 0.00 1 17585 104 0 0
sp_client_order 19854 19854 0 0.00 3 20807 392 0 0
sp_customer_reserve 459946 459946 0 0.00 0 42005 171 0 0
sp_fill_shopping_cart 30709 30709 0 0.00 1 13596 38 0 0
sp_fill_shopping_cart_clo_res 459943 459943 0 0.00 0 11759 15 0 0
sp_get_clo_for_invoice 4960 4960 0 0.00 0 32580 307 0 0
sp_kill_cost_storno 17284 17284 0 0.00 0 27827 54 0 0
sp_kill_qstorno_ret_qs2qd 1118881 1118881 0 0.00 0 22978 33 0 0
sp_kill_qty_storno 590012 590012 0 0.00 0 22979 71 0 0
sp_lock_selected_doc 20341 20341 0 0.00 0 7074 5 0 0
sp_make_cost_storno 27564 27564 0 0.00 0 17140 50 0 0
sp_multiply_rows_for_pdistr 27564 27564 0 0.00 0 13991 22 0 0
sp_multiply_rows_for_qdistr 19854 19854 0 0.00 0 20193 184 0 0
sp_pay_from_customer 7082 7082 0 0.00 4 14874 105 0 0
sp_pay_to_supplier 5829 5829 0 0.00 4 17551 107 0 0
sp_payment_common 12911 12911 0 0.00 1 14859 26 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 10176 303 0 0
sp_qd_handle_on_invoice_upd_sts 10625 10625 0 0.00 0 22434 324 0 0
sp_qd_handle_on_reserve_upd_sts 9694 9694 0 0.00 0 19535 60 0 0
sp_reserve_write_off 9694 9694 0 0.00 5 19556 168 0 0
sp_supplier_invoice 5519 5519 0 0.00 7 147157 3404 0 0
sp_supplier_order 5338 5338 0 0.00 4 39010 1305 0 0
srv_aggregate_perf_data 97 97 0 0.00 2518 88710 17620 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1395 126677 43965 0 0
srv_find_qd_qs_mism 1048784 1048784 0 0.00 0 14353 5 0 0
srv_make_invnt_saldo 476 476 0 0.00 56 32076 1104 0 0
srv_make_money_saldo 771 771 0 0.00 1 15346 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 120554 508506 298611 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4972 4972 0 0.00 0 27659 174 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 9451 108 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 3009 11 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 2028 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2543 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1750 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 374 9 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 13081 1469 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 2801 11 0 0
t$perf-norm:sp_client_order 19854 19854 0 0.00 0 6562 14 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 5647 29 0 0
t$perf-norm:sp_pay_from_customer 7082 7082 0 0.00 0 4811 9 0 0
t$perf-norm:sp_pay_to_supplier 5829 5829 0 0.00 0 5690 11 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 6110 11 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 17117 23 0 0
t$perf-norm:sp_supplier_order 5338 5338 0 0.00 0 16241 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 2731 42 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5058 79 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 611 12 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 2245 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10112 9918 194 1.91 0 3900 15 0 194
v_all_customers 36628 36628 0 0.00 0 34 0 0 0
v_all_suppliers 30336 30336 0 0.00 0 58 1 0 0
v_all_wares 39708 39708 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 1900 8 0 0
v_cancel_client_order 3410 3410 0 0.00 0 2736 4 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3413 11 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1974 6 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3499 8 0 0
v_cancel_supplier_invoice 834 820 14 1.67 0 2017 16 0 14
v_cancel_supplier_order 774 774 0 0.00 0 1855 7 0 0
v_cancel_write_off 1984 1984 0 0.00 0 5587 17 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 1870 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 4693 21 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 5234 4 0 0
v_max_non_paid_invoice 1516 1516 0 0.00 0 943 2 0 0
v_max_non_paid_realizn 3652 3652 0 0.00 0 463 3 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 4957 8 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 2547 7 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 3891 5 0 0
v_min_non_paid_invoice 5829 5829 0 0.00 0 6989 16 0 0
v_min_non_paid_realizn 7082 7082 0 0.00 0 14853 24 0 0
v_our_firm 9940 9940 0 0.00 0 23 1 0 0
v_reserve_write_off 19388 19388 0 0.00 0 3631 6 0 0
x_lock_depdocs_on_canc_invoice 11450 11450 0 0.00 0 23947 114 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 8874 339 0 0
x_make_qstorno_1000_1200 5338 5338 0 0.00 2 38996 1244 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 3 138862 3261 0 0
x_make_qstorno_2100_3300 459946 459946 0 0.00 0 42002 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2022 01:40:33 to 25-12-2022 01:40:37.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:37 32659 8192000000 9897911312 10207358976 978947968 10.674 100 88 176 0 1008007376 5806208 23253200
23:11:23 21060 8192000000 9946455872 10269110272 1028072368 11.150 100 88 176 0 1059313104 8184112 23056624
23:12:49 12404 8192000000 9978692240 10310406144 1063283728 11.488 100 89 178 0 1094925264 9608624 22032912
23:14:12 5992 8192000000 10037579552 10382626816 1121460080 12.041 100 82 162 0 1151585408 11162560 18962768
23:15:19 7434 8192000000 10086867360 10445434880 1181069872 12.600 100 83 165 0 1212221584 13365456 17786256
23:16:32 3010 8192000000 10129547984 10497400832 1220823840 12.969 100 84 168 0 1252965584 13966320 18175424
23:17:37 1947 8192000000 10164738192 10538545152 1253021168 13.266 100 87 172 1 1285765184 14007360 18736656
23:18:40 846 8192000000 10192821664 10575777792 1282577168 13.537 100 84 169 2 1313172304 11839616 18755520
23:19:42 28867 8192000000 10194902160 10595262464 1294041232 13.641 100 49 98 2 1314397664 8649392 11707040
23:21:14 9297 8192000000 10190791424 10602180608 1305946752 13.749 100 19 38 0 1316223584 6146544 4130288
23:22:24 4989 8192000000 10198858528 10614448128 1314399424 13.826 100 21 41 0 1324816880 5945472 4471984
23:23:33 45678 8192000000 10204931568 10629275648 1319034784 13.868 100 27 53 0 1329731152 4779616 5916752
23:25:19 7945 8192000000 10216318160 10634821632 1325448992 13.926 100 23 46 0 1336412032 6311952 4651088
23:26:27 5218 8192000000 10218864272 10643046400 1331469328 13.980 100 29 58 0 1343592448 5680336 6442784
23:27:35 16918 8192000000 10225982784 10637824000 1314219376 13.824 99 27 55 0 1335002992 14538720 6244896
23:28:53 9282 8192000000 10192567296 10607067136 1290556336 13.609 99 20 40 2 1300664784 5839120 4269328
23:30:08 12290 8192000000 10160954000 10568638464 1198985056 12.767 93 18 36 0 1208117408 5229024 3903328
23:31:25 8168 8192000000 10120101472 10510704640 1190117216 12.684 96 21 42 0 1200018864 5345264 4556384
23:32:34 56992 8192000000 10130815632 10538315776 1246803312 13.209 100 30 61 0 1259764176 6058944 6901920
23:34:34 4592 8192000000 10178258560 10583252992 1280052144 13.513 100 31 58 0 1293595872 7163600 6380128
23:35:39 6157 8192000000 10190048960 10598756352 1293825040 13.639 100 36 71 0 1307674704 5206576 8643088
23:36:51 12685 8192000000 10196506496 10607697920 1303105168 13.723 100 23 47 0 1313270320 5023760 5141392
23:38:04 9452 8192000000 10201289904 10618544128 1315419488 13.835 100 18 33 1 1323128880 4633296 3076096
23:39:26 21896 8192000000 10243571056 10649538560 1333115072 13.995 100 47 92 2 1352377120 9415632 9846416
23:40:52 13770 8192000000 10264347904 10681499648 1352801200 14.173 100 58 114 0 1382900768 17108768 12990800
23:42:12 53584 8192000000 10278616944 10704166912 1372891392 14.353 100 35 68 1 1396346480 14899072 8556016
23:44:24 22787 8192000000 10307648640 10729467904 1387950432 14.488 100 38 76 1 1415349152 18390592 9008128
23:45:50 2028 8192000000 10337280720 10753216512 1407430496 14.661 100 70 138 1 1446974896 22700448 16843952
23:46:58 12087 8192000000 10323608544 10749001728 1402347600 14.616 100 42 83 0 1440568432 27277296 10943536
23:48:31 7194 8192000000 10354244800 10778415104 1425482160 14.821 100 48 95 3 1467758240 29923808 12352272
23:49:43 5849 8192000000 10354262064 10770743296 1400373584 14.598 99 54 108 0 1452115088 37127840 14613664
23:51:11 5771 8192000000 10371569936 10789339136 1419746016 14.770 100 65 129 0 1480871536 44407552 16717968
23:52:24 3712 8192000000 10372851008 10799058944 1427316640 14.838 100 59 117 0 1486585120 43660976 15607504
23:53:35 1393 8192000000 10384872144 10794614784 1419628544 14.769 100 77 151 1 1487198912 48051104 19519264
23:54:39 7271 8192000000 10343896688 10772332544 1397677520 14.574 100 44 87 0 1456544224 47209376 11657328
23:55:53 17109 8192000000 10333771280 10757189632 1367368000 14.303 98 39 78 0 1427765296 49584208 10813088
23:57:13 9083 8192000000 10311549696 10727985152 1343630752 14.090 99 46 91 0 1404532608 48380000 12521856
23:58:25 7099 8192000000 10299730432 10728099840 1356039408 14.202 100 44 88 0 1412756208 44747856 11968944
23:59:44 24013 8192000000 10276285056 10705481728 1354186864 14.185 100 14 28 0 1383699568 25898416 3614288
00:01:14 25304 8192000000 10287935408 10715029504 1338436320 14.043 100 26 52 0 1390060544 44291152 7333072
00:02:40 38742 8192000000 10255539168 10681077760 1300890960 13.703 96 16 30 0 1326946784 22225904 3829920
00:04:19 11221 8192000000 10285890016 10716573696 1351126480 14.158 100 32 64 0 1390249680 31019888 8103312
00:05:49 57760 8192000000 10281218368 10726215680 1357410976 14.214 100 22 44 0 1391212496 28153424 5648096
00:07:56 108329 8192000000 10266493248 10690928640 1317898320 13.858 97 40 78 1 1344713792 16623600 10191872
00:10:51 59850 8192000000 10263048304 10701836288 1346894800 14.120 99 25 48 0 1359520736 7781248 4844688
00:12:52 32657 8192000000 10292976096 10723958784 1376923872 14.389 100 50 100 0 1398554864 9667568 11963424
00:14:35 43089 8192000000 10283648960 10726809600 1371111648 14.337 99 23 44 0 1386517968 10534640 4871680
00:16:29 26126 8192000000 10286503312 10723414016 1371953856 14.345 99 40 79 2 1395619344 15074496 8590992
00:18:22 51098 8192000000 10273245600 10718101504 1371438880 14.340 100 5 10 0 1382952208 10497488 1015840
00:20:23 64245 8192000000 10288918192 10741223424 1376540976 14.386 99 17 35 0 1393392096 12658128 4192992
00:22:46 61204 8192000000 10289334288 10739331072 1390300944 14.509 100 40 78 5 1404778064 6459760 8017360
00:24:53 51726 8192000000 10291143936 10750509056 1396092896 14.560 100 9 18 0 1410101632 11896000 2112736
00:26:46 34116 8192000000 10299519952 10757476352 1401388272 14.607 100 22 44 0 1417053216 10119744 5545200
00:28:26 44224 8192000000 10300286544 10762715136 1404587952 14.636 100 10 21 0 1417632496 11046448 1998096
00:30:14 60065 8192000000 10311424048 10771415040 1413850432 14.718 100 19 36 1 1436651488 18557504 4243552
00:32:24 59802 8192000000 10296196096 10760785920 1379972176 14.416 98 10 18 0 1397135280 14998432 2164672
00:34:30 43327 8192000000 10296533856 10747744256 1388623456 14.494 99 32 63 0 1408133952 12495936 7014560
00:36:19 55616 8192000000 10282154480 10742231040 1375296368 14.374 99 12 22 0 1392204592 14253216 2655008
00:38:16 34591 8192000000 10319959632 10759684096 1402589424 14.618 100 16 29 0 1417012256 10320208 4102624
00:39:53 126677 8192000000 10277242944 10737274880 1329074064 13.959 95 10 20 0 1348187536 17049872 2063600
00:43:06 56135 8192000000 10255208000 10691952640 1307707680 13.765 95 14 28 0 1318047632 7683664 2656288
00:45:03 16141 8192000000 10273777968 10706657280 1347421440 14.124 99 30 60 0 1365145840 11276352 6448048
00:46:20 49051 8192000000 10277899504 10702508032 1285926576 13.567 94 37 74 1 1307463152 12812528 8724048
00:48:09 59884 8192000000 10256300192 10696351744 1337890208 14.038 98 28 56 0 1350663344 6312096 6461040
00:50:10 73569 8192000000 10266246096 10710016000 1338576672 14.045 98 41 82 0 1358073536 10352256 9144608
00:52:52 56669 8192000000 10263427920 10708611072 1318479568 13.863 96 11 22 0 1336346320 15281760 2584992
00:54:49 44649 8192000000 10264699568 10715463680 1362154432 14.257 100 19 38 1 1384407232 17458640 4794160
00:56:50 47424 8192000000 10270780864 10719551488 1355448640 14.196 100 22 44 0 1384224944 23490928 5285376
00:58:38 72831 8192000000 10260093152 10708119552 1309251472 13.779 96 11 22 0 1326384656 14660128 2473056
01:00:51 32928 8192000000 10271049232 10703044608 1334038176 14.004 98 25 48 0 1347482320 7478560 5965584
01:02:28 55969 8192000000 10265744800 10714087424 1337119024 14.031 98 25 50 0 1356841744 13915904 5806816
01:04:27 54395 8192000000 10289723664 10734231552 1373289408 14.357 100 25 50 0 1388025664 9489504 5246752
01:06:37 72827 8192000000 10296157088 10740842496 1378569360 14.404 100 14 29 0 1392552256 10750352 3232544
01:08:55 96066 8192000000 10300528928 10748952576 1364198384 14.275 98 16 32 0 1386651424 18291616 4161424
01:11:36 85875 8192000000 10276986528 10726031360 1366613456 14.297 99 19 38 1 1382406896 11314032 4479408
01:14:08 48361 8192000000 10281223776 10736218112 1375355648 14.375 99 22 44 1 1391421120 11035696 5029776
01:16:00 40719 8192000000 10286407216 10745274368 1374121008 14.364 99 8 16 0 1386008848 10138288 1749552
01:17:53 74080 8192000000 10285360496 10738565120 1351086240 14.157 97 14 28 0 1362218288 7860720 3271328
01:21:04 39011 8192000000 10300344448 10752888832 1384598224 14.458 99 12 24 0 1397015072 9787184 2629664
01:22:44 45051 8192000000 10318793520 10761515008 1375677520 14.378 98 30 62 1 1397103184 13410384 8015280
01:24:34 45855 8192000000 10300830432 10749468672 1368406896 14.313 98 27 54 0 1385225104 9648272 7169936
01:26:29 63137 8192000000 10295096384 10749296640 1360258784 14.240 97 15 30 0 1375101408 11721808 3120816
01:28:36 41169 8192000000 10310101456 10752352256 1387528624 14.484 99 37 72 1 1407826752 11651264 8646864
01:30:19 76027 8192000000 10284962944 10729500672 1320807856 13.884 95 19 38 0 1336735792 12331920 3596016
01:32:43 19718 8192000000 10294957680 10726305792 1371259328 14.338 100 49 98 0 1396257792 14079408 10919056
01:34:08 67172 8192000000 10237312304 10690785280 1328834912 13.957 98 25 50 1 1345616432 10729792 6051728
01:36:16 74531 8192000000 10231258224 10682880000 1299818736 13.694 96 6 12 0 1311910032 10596176 1495120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-12-2022 01:40:39 to 25-12-2022 01:40:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-12-2022 01:40:56 to 25-12-2022 01:40:58.

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          237677
MON$OLDEST_ACTIVE               239912
MON$OLDEST_SNAPSHOT             239912
MON$NEXT_TRANSACTION            239912
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                       1996224
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sun Dec 25 01:38:39 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:32: 
--- end of diff output ---

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

25.12.22 01:40:59. 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
2022-12-25 01:41:01 No severe PSQL-related problems occured
25.12.22 01:41:02. End of report.