Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.01.2023 22: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 15276528 kB
MemAvailable 30451636 kB
Buffers 0 kB
Cached 16457752 kB
SwapCached 3680 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-30 22:38:40
DTS_END                         2023-01-30 22:38:40.4040
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/38908
TRN_ID                          245747
ATT_ID                          686
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: 9258.04 20972 1110965
customer order: creation 178.18 349 21382
customer order: refuse 15.31 352 1837
order to supplier: creation 47.14 1155 5657
order to supplier: removal 3.60 95174 432
invoice (draft): creation 47.06 3223 5647
invoice (draft): removal 52.36 711 6283
invoice accept: apply 42.57 16961 5108
invoice accept: cancel 47.48 749 5698
customer reserve: creation 3891.42 169 466970
customer reserve: removal 4634.74 58 556169
realization accept: apply 86.66 187 10399
realization accept: cancel 76.94 132 9233
payment from customer: creation 61.68 133 7401
payment from customer: removal 6.09 68 731
payment to supplier: creation 50.73 155 6088
payment to supplier: removal 3.47 65 416
service: total inventory turnovers 4.86 1184 583
service: total monetary turnovers 6.82 45 818
service: aggregate perf. data 0.91 15603 109
service: refresh index statistics 0.03 282976 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2023 22:40:33 to 30-01-2023 22:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3642
TEST_TIME 2 4890
TEST_TIME 3 4074
TEST_TIME 4 4142
TEST_TIME 5 4937
TEST_TIME 6 4775
TEST_TIME 7 4759
TEST_TIME 8 4790
TEST_TIME 9 4929
TEST_TIME 10 5257
TEST_TIME 11 5195
TEST_TIME 12 5427
TEST_TIME 13 5614
TEST_TIME 14 5786
TEST_TIME 15 6031
TEST_TIME 16 6228
TEST_TIME 17 6591
TEST_TIME 18 6961
TEST_TIME 19 7052
TEST_TIME 20 7882
TEST_TIME 21 8219
TEST_TIME 22 8309
TEST_TIME 23 8655
TEST_TIME 24 9253
TEST_TIME 25 9242
TEST_TIME 26 9476
TEST_TIME 27 10175
TEST_TIME 28 10632
TEST_TIME 29 10443
TEST_TIME 30 10216
TEST_TIME 31 10051
TEST_TIME 32 10025
TEST_TIME 33 10180
TEST_TIME 34 10115
TEST_TIME 35 9993
TEST_TIME 36 9968
TEST_TIME 37 9842
TEST_TIME 38 9805
TEST_TIME 39 9990
TEST_TIME 40 9926
TEST_TIME 41 9787
TEST_TIME 42 9755
TEST_TIME 43 9743
TEST_TIME 44 9636
TEST_TIME 45 9613
TEST_TIME 46 9492
TEST_TIME 47 9426
TEST_TIME 48 9457
TEST_TIME 49 9365
TEST_TIME 50 9316
TEST_TIME 51 9288
TEST_TIME 52 9262
TEST_TIME 53 9184
TEST_TIME 54 9221
TEST_TIME 55 9553
TEST_TIME 56 9621
TEST_TIME 57 9625
TEST_TIME 58 9691
TEST_TIME 59 9643
TEST_TIME 60 9727
TEST_TIME 61 9800
TEST_TIME 62 9772
TEST_TIME 63 9760
TEST_TIME 64 9675
TEST_TIME 65 9620
TEST_TIME 66 9642
TEST_TIME 67 9677
TEST_TIME 68 9622
TEST_TIME 69 9540
TEST_TIME 70 9500
TEST_TIME 71 9472
TEST_TIME 72 9439
TEST_TIME 73 9376
TEST_TIME 74 9368
TEST_TIME 75 9418
TEST_TIME 76 9362
TEST_TIME 77 9305
TEST_TIME 78 9328
TEST_TIME 79 9319
TEST_TIME 80 9307
TEST_TIME 81 9314
TEST_TIME 82 9330
TEST_TIME 83 9289
TEST_TIME 84 9297
TEST_TIME 85 9357
TEST_TIME 86 9311
TEST_TIME 87 9424
TEST_TIME 88 9462
TEST_TIME 89 9483
TEST_TIME 90 9468
TEST_TIME 91 9519
TEST_TIME 92 9552
TEST_TIME 93 9568
TEST_TIME 94 9577
TEST_TIME 95 9487
TEST_TIME 96 9492
TEST_TIME 97 9457
TEST_TIME 98 9419
TEST_TIME 99 9371
TEST_TIME 100 9321
TEST_TIME 101 9341
TEST_TIME 102 9339
TEST_TIME 103 9370
TEST_TIME 104 9368
TEST_TIME 105 9414
TEST_TIME 106 9395
TEST_TIME 107 9373
TEST_TIME 108 9390
TEST_TIME 109 9391
TEST_TIME 110 9412
TEST_TIME 111 9390
TEST_TIME 112 9444
TEST_TIME 113 9473
TEST_TIME 114 9443
TEST_TIME 115 9387
TEST_TIME 116 9380
TEST_TIME 117 9354
TEST_TIME 118 9337
TEST_TIME 119 9311
TEST_TIME 120 9259

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2023 22:40:36 to 30-01-2023 22:40:40.

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 1544493 1544493 0 0.00 0 29719 6 0 0
doc_list_biud 1544493 1544493 0 0.00 0 40182 26 0 0
sp_add_invoice_to_stock 5230 5108 122 2.33 38 181771 16961 0 122
sp_cancel_adding_invoice 5698 5698 0 0.00 4 53952 750 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 20796 353 0 0
sp_cancel_customer_reserve 556169 556169 0 0.00 0 40186 59 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 3953 68 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 2560 66 0 0
sp_cancel_supplier_invoice 6288 6283 5 0.07 1 34268 711 0 5
sp_cancel_supplier_order 432 432 0 0.00 8 1084160 95174 0 0
sp_cancel_write_off 9233 9233 0 0.00 1 21432 133 0 0
sp_client_order 21382 21382 0 0.00 3 36084 349 0 0
sp_customer_reserve 466970 466970 0 0.00 0 95227 170 0 0
sp_fill_shopping_cart 32684 32684 0 0.00 1 8660 36 0 0
sp_fill_shopping_cart_clo_res 466970 466970 0 0.00 0 22133 14 0 0
sp_get_clo_for_invoice 5106 5106 0 0.00 0 19394 310 0 0
sp_kill_cost_storno 16078 16078 0 0.00 0 11980 60 0 0
sp_kill_qstorno_ret_qs2qd 1128286 1128286 0 0.00 0 40149 32 0 0
sp_kill_qty_storno 595157 595157 0 0.00 0 40182 68 0 0
sp_lock_selected_doc 21553 21553 0 0.00 0 5800 4 0 0
sp_make_cost_storno 28994 28994 0 0.00 0 29470 69 0 0
sp_multiply_rows_for_pdistr 28994 28994 0 0.00 0 16215 29 0 0
sp_multiply_rows_for_qdistr 21382 21382 0 0.00 0 32972 147 0 0
sp_pay_from_customer 7401 7401 0 0.00 4 41318 133 0 0
sp_pay_to_supplier 6088 6088 0 0.00 4 25825 156 0 0
sp_payment_common 13489 13489 0 0.00 1 15849 39 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 20790 320 0 0
sp_qd_handle_on_invoice_upd_sts 10804 10804 0 0.00 0 13326 307 0 0
sp_qd_handle_on_reserve_upd_sts 10399 10399 0 0.00 0 23125 61 0 0
sp_reserve_write_off 10399 10399 0 0.00 6 23155 187 0 0
sp_supplier_invoice 5647 5647 0 0.00 6 89913 3224 0 0
sp_supplier_order 5657 5657 0 0.00 5 39376 1156 0 0
srv_aggregate_perf_data 109 109 0 0.00 2981 190029 15603 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 973 149615 47665 0 0
srv_find_qd_qs_mism 1062508 1062508 0 0.00 0 16952 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 27 86756 1184 0 0
srv_make_money_saldo 818 818 0 0.00 1 8352 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 107869 354046 282977 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 0 14255 178 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 3144 87 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 3403 10 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 7206 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 6167 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 703 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 947 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 37530 1696 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 1752 7 0 0
t$perf-norm:sp_client_order 21382 21382 0 0.00 0 7638 18 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 23634 31 0 0
t$perf-norm:sp_pay_from_customer 7401 7401 0 0.00 0 25816 11 0 0
t$perf-norm:sp_pay_to_supplier 6088 6088 0 0.00 0 24064 22 0 0
t$perf-norm:sp_reserve_write_off 10399 10399 0 0.00 0 18259 16 0 0
t$perf-norm:sp_supplier_invoice 5647 5647 0 0.00 0 13934 23 0 0
t$perf-norm:sp_supplier_order 5657 5657 0 0.00 0 7773 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 274 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 756 15 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1601 22 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 5401 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10454 10210 244 2.33 0 32310 23 0 244
v_all_customers 38690 38690 0 0.00 0 19 1 0 0
v_all_suppliers 31422 31422 0 0.00 0 16 1 0 0
v_all_wares 42764 42764 0 0.00 0 137 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 2057 10 0 0
v_cancel_client_order 3674 3674 0 0.00 0 4316 8 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 764 5 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 2126 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2546 10 0 0
v_cancel_supplier_invoice 844 834 10 1.18 0 1992 17 0 10
v_cancel_supplier_order 864 864 0 0.00 0 629 5 0 0
v_cancel_write_off 2144 2144 0 0.00 0 2481 17 0 0
v_max_id_clo_ord 5655 5655 0 0.00 0 8533 4 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 4065 19 0 0
v_max_id_ord_sup 5647 5647 0 0.00 0 3971 5 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 1059 3 0 0
v_max_non_paid_realizn 4029 4029 0 0.00 0 8681 6 0 0
v_min_id_clo_ord 5655 5655 0 0.00 0 8567 9 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 6187 9 0 0
v_min_id_ord_sup 5647 5647 0 0.00 0 7028 8 0 0
v_min_non_paid_invoice 6088 6088 0 0.00 0 9730 32 0 0
v_min_non_paid_realizn 7401 7401 0 0.00 0 15833 30 0 0
v_our_firm 10818 10818 0 0.00 0 28 1 0 0
v_reserve_write_off 20798 20798 0 0.00 0 6443 7 0 0
x_lock_depdocs_on_canc_invoice 11564 11564 0 0.00 0 12487 113 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 4628 303 0 0
x_make_qstorno_1000_1200 5657 5657 0 0.00 1 39354 1077 0 0
x_make_qstorno_1200_2000 5647 5647 0 0.00 3 89904 3100 0 0
x_make_qstorno_2100_3300 466970 466970 0 0.00 0 95224 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 22:40:41 to 30-01-2023 22:40:45.

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
20:08:44 2 8192000000 8800005040 8811544576 6401568 0.078 2 1 2 0 6501200 56848 42784
20:09:46 5227 8192000000 9877865424 10185023488 972405568 10.610 100 83 168 0 999418192 5187744 21824880
20:10:57 24508 8192000000 9936703536 10257399808 1022667216 11.098 100 88 176 0 1052686784 7480592 22538976
20:12:25 18262 8192000000 9971143232 10298667008 1054633184 11.405 100 86 172 0 1085620400 9025760 21961456
20:13:50 7467 8192000000 10031603328 10372845568 1116161184 11.991 100 87 174 0 1147973888 10388368 21424336
20:15:01 5883 8192000000 10080277744 10438811648 1170362512 12.500 100 87 172 1 1202459600 12304528 19792560
20:16:08 3455 8192000000 10120114720 10486992896 1215344672 12.919 100 81 161 0 1245888816 12980080 17564064
20:17:13 1868 8192000000 10160016064 10535231488 1256758096 13.300 100 88 171 1 1287807824 12222752 18826976
20:18:17 2112 8192000000 10180228848 10562678784 1267878736 13.402 100 86 171 0 1301403984 14405472 19119776
20:19:21 1233 8192000000 10213970992 10596311040 1293674528 13.638 100 96 191 3 1328047440 12934592 21438320
20:20:23 1462 8192000000 10223187424 10615123968 1311475056 13.799 100 77 154 0 1342000736 13265360 17260320
20:21:27 1112 8192000000 10226760752 10618224640 1312442448 13.808 100 69 136 1 1338036752 10467456 15126848
20:22:29 11506 8192000000 10204043680 10622435328 1319574864 13.873 100 24 50 0 1336014336 10877968 5561504
20:23:42 9965 8192000000 10212243312 10633216000 1330324416 13.970 100 17 34 0 1339652576 5658304 3669856
20:24:52 12191 8192000000 10219653776 10642055168 1337294704 14.033 100 18 36 0 1347152944 6026048 3832192
20:26:13 14637 8192000000 10254618128 10662146048 1339857488 14.056 100 66 129 2 1361432128 5883040 15691600
20:27:28 6073 8192000000 10227066816 10646515712 1335096448 14.013 100 30 60 0 1348876064 6501568 7278048
20:28:34 6227 8192000000 10191656608 10607841280 1276095312 13.477 97 16 32 1 1284333168 4538112 3699744
20:29:41 14165 8192000000 10191176336 10606592000 1271694144 13.437 97 14 28 0 1280382528 5856608 2831776
20:30:57 16138 8192000000 10178983632 10596839424 1261676896 13.345 97 20 40 0 1271515792 5386160 4452736
20:32:16 8206 8192000000 10109330384 10502656000 1159968512 12.403 93 19 38 0 1168882544 4126080 4787952
20:33:25 12760 8192000000 10153452896 10553806848 1249369376 13.232 99 30 60 2 1262223664 5247456 7606832
20:34:38 41303 8192000000 10174965792 10587549696 1285818864 13.566 100 36 72 1 1299984816 6362784 7803168
20:36:26 5914 8192000000 10181942784 10598289408 1295462336 13.654 100 35 72 1 1310353008 6611344 8279328
20:37:33 6846 8192000000 10197140048 10612330496 1308514944 13.773 100 30 60 0 1320784496 5344368 6925184
20:38:40 11674 8192000000 10230482208 10642718720 1334763360 14.010 100 33 65 1 1347321200 4850400 7707440
20:39:59 91770 8192000000 10259574320 10683346944 1341033600 14.067 99 37 72 0 1360783616 11370256 8379760
20:42:32 15047 8192000000 10278216992 10696323072 1370966352 14.336 100 50 100 0 1393830800 12067280 10797168
20:43:52 11847 8192000000 10292706816 10711695360 1374603520 14.368 100 51 100 0 1401378880 15162672 11612688
20:45:08 16273 8192000000 10296577616 10724720640 1387743808 14.486 100 38 75 0 1414009424 17542688 8722928
20:46:30 22623 8192000000 10313453648 10742951936 1398381648 14.581 100 49 97 0 1431078464 21149856 11546960
20:47:58 14680 8192000000 10328528064 10754748416 1400863088 14.603 100 38 76 0 1436078144 25504752 9710304
20:49:19 5238 8192000000 10344332576 10765443072 1413362944 14.714 100 53 106 2 1454388608 27844528 13181136
20:50:35 7737 8192000000 10370580496 10795057152 1432481120 14.883 100 54 108 1 1478164032 31745168 13937744
20:51:46 6165 8192000000 10386382096 10805600256 1437617984 14.929 100 75 149 0 1492826800 37519008 17689808
20:52:57 2326 8192000000 10396540480 10815492096 1441645472 14.964 100 85 168 0 1501238160 39136704 20455984
20:54:06 13461 8192000000 10365293072 10787319808 1415894304 14.736 100 56 112 0 1472415888 42258720 14262864
20:55:26 973 8192000000 10368091424 10777038848 1400201744 14.597 100 95 188 2 1468805904 44233856 24370304
20:56:36 3603 8192000000 10356580880 10765086720 1391797632 14.522 100 77 153 0 1458704624 47916688 18990304
20:57:44 2331 8192000000 10336363632 10742300672 1363180176 14.266 99 80 158 1 1438494928 55335472 19979280
20:58:51 11891 8192000000 10325289456 10750689280 1371488608 14.340 99 45 90 0 1431016048 46792176 12735264
21:00:11 4890 8192000000 10354327664 10772856832 1394949632 14.550 100 57 112 2 1452507344 42312960 15244752
21:01:21 9831 8192000000 10334911936 10770685952 1391317200 14.518 100 34 66 0 1447292320 46419504 9555616
21:02:34 25571 8192000000 10316846960 10763431936 1369412976 14.322 99 30 59 1 1419853680 42035488 8405216
21:04:00 32349 8192000000 10292800832 10739945472 1347996944 14.129 98 25 49 0 1388524704 33809728 6718032
21:06:00 42622 8192000000 10287299552 10731368448 1352082064 14.166 99 18 37 1 1379218112 22514016 4622032
21:07:43 63367 8192000000 10278234064 10715238400 1362576464 14.260 100 34 71 1 1376862096 6359440 7926192
21:09:52 75813 8192000000 10290533264 10729328640 1363888912 14.272 99 28 55 0 1378255312 8458240 5908160
21:12:14 63894 8192000000 10278617248 10729381888 1366472000 14.295 99 8 16 0 1377390288 9113440 1804848
21:14:20 47994 8192000000 10287791216 10714767360 1356332864 14.204 98 18 36 0 1368374912 8046288 3995760
21:16:11 19488 8192000000 10297514480 10730037248 1374447184 14.367 99 46 91 1 1395519840 10573504 10499152
21:17:34 112709 8192000000 10293538960 10751377408 1385088032 14.462 100 26 53 0 1404411280 12962336 6360912
21:20:27 35329 8192000000 10281806400 10737848320 1375846272 14.379 99 9 18 0 1385692976 7612304 2234400
21:22:03 45535 8192000000 10289243584 10751479808 1390676272 14.512 100 9 18 0 1400630096 7540784 2413040
21:24:24 39986 8192000000 10297348048 10757271552 1400793856 14.602 100 16 32 0 1412609264 7718800 4096608
21:26:05 71907 8192000000 10295252560 10765672448 1407803088 14.664 100 16 32 0 1420471424 8753392 3914944
21:28:27 62705 8192000000 10303855232 10769481728 1379104160 14.409 98 9 16 1 1390649248 9934128 1610960
21:30:40 59774 8192000000 10311827264 10762420224 1360736064 14.244 96 26 50 0 1384015056 17508672 5770320
21:32:59 61216 8192000000 10283140640 10745937920 1360745616 14.244 98 17 32 0 1388006736 23001632 4259488
21:35:01 32329 8192000000 10300190192 10748010496 1365793280 14.289 98 30 58 1 1384682128 12714320 6174528
21:36:52 63551 8192000000 10276009392 10714451968 1282915680 13.540 92 15 25 0 1294555584 9101808 2538096
21:39:01 91480 8192000000 10277622672 10728411136 1323073568 13.905 95 12 24 0 1338994752 13482432 2438752
21:41:41 134108 8192000000 10266614736 10713509888 1332505072 13.990 97 26 50 2 1353283360 15007936 5770352
21:44:56 57874 8192000000 10245248112 10707742720 1332892720 13.993 97 15 30 0 1348908208 12540432 3475056
21:47:02 51498 8192000000 10228632352 10675892224 1274821632 13.466 94 17 31 1 1284432864 6149168 3462064
21:49:01 76575 8192000000 10241111664 10690691072 1320360720 13.880 98 25 50 0 1335617312 9835456 5421136
21:51:20 55353 8192000000 10233554096 10677633024 1312855168 13.812 98 26 52 0 1331590240 12848112 5886960
21:53:23 40218 8192000000 10223759776 10677653504 1301326608 13.707 97 11 22 1 1314629264 10562688 2739968
21:55:04 38794 8192000000 10259971536 10700091392 1344623680 14.099 99 36 72 0 1364603712 11634576 8345456
21:56:48 60992 8192000000 10268376912 10726535168 1365252112 14.284 100 17 34 0 1385288048 15969520 4066416
21:58:50 54798 8192000000 10266429584 10722787328 1366224240 14.293 100 10 20 0 1379211520 11089792 1897488
22:00:45 149615 8192000000 10268045232 10724114432 1293308960 13.634 94 9 18 0 1309738800 14422832 2007008
22:04:16 33322 8192000000 10281310240 10727067648 1361970640 14.255 99 32 64 0 1382462256 13458160 7033456
22:05:49 67863 8192000000 10299982912 10752950272 1379318848 14.410 100 17 34 0 1398785040 15616368 3849824
22:08:02 64437 8192000000 10285476496 10718560256 1327687904 13.946 97 18 36 0 1342105936 10979648 3438384
22:10:09 78585 8192000000 10286359984 10733346816 1362759856 14.262 99 15 28 0 1382100160 16671408 2668896
22:12:37 66140 8192000000 10290275168 10730147840 1373016928 14.354 99 40 76 3 1390518864 8450304 9051632
22:14:46 68361 8192000000 10282635856 10748448768 1386046160 14.471 100 7 14 0 1396394528 8545888 1802480
22:16:56 63632 8192000000 10286753344 10740666368 1363409264 14.268 98 17 34 1 1373652176 6226640 4016272
22:19:02 48021 8192000000 10288992672 10743705600 1369872384 14.326 99 22 40 0 1382774608 8261744 4640480
22:20:54 60543 8192000000 10276081792 10728587264 1334350400 14.006 96 11 22 0 1346095488 9375824 2369264
22:23:02 99975 8192000000 10267617664 10725564416 1319259808 13.870 95 8 16 0 1330916912 10362672 1294432
22:25:42 35017 8192000000 10292072848 10744467456 1369437120 14.322 99 22 44 0 1390505744 16195312 4873312
22:27:20 11609 8192000000 10284998176 10718330880 1353206800 14.176 98 49 99 0 1374324880 9696992 11421088
22:28:32 32505 8192000000 10263448704 10712961024 1323480512 13.908 97 23 46 0 1341593776 13089440 5023824
22:30:13 58970 8192000000 10271127328 10720763904 1344242592 14.096 98 10 20 0 1355093440 8947856 1902992
22:32:12 97959 8192000000 10254499552 10698731520 1320352096 13.880 97 21 40 0 1338077648 12446032 5279520
22:35:04 50322 8192000000 10234660480 10689896448 1325710704 13.928 99 14 28 0 1339896048 11057040 3128304
22:37:09 93419 8192000000 10047957840 10474438656 1144747680 12.260 84 10 20 0 1152530064 5563824 2218560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-01-2023 22:40:47 to 30-01-2023 22:41:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 22:41:03 to 30-01-2023 22:41:05.

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          245511
MON$OLDEST_ACTIVE               246134
MON$OLDEST_SNAPSHOT             246134
MON$NEXT_TRANSACTION            246134
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                       2040544
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 ---
:1026: 
:1027: mscs-test-161.budzdorov.ru    Mon Jan 30 22:38:57 2023
:1028:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1029: 
--- end of diff output ---

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

30.01.23 22:41:06. 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-30 22:41:09 No severe PSQL-related problems occured
30.01.23 22:41:10. End of report.