Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16053996 kB
MemAvailable 30900948 kB
Buffers 0 kB
Cached 15653040 kB
SwapCached 2548 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-08-13 22:38:25
DTS_END                         2022-08-13 22:38:25.5900
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/53444
TRN_ID                          255173
ATT_ID                          652
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9326.53 17282 1119184
customer order: creation 192.09 370 23051
customer order: refuse 16.38 379 1966
order to supplier: creation 49.89 1287 5987
order to supplier: removal 3.65 78970 438
invoice (draft): creation 51.23 3180 6148
invoice (draft): removal 50.45 760 6054
invoice accept: apply 46.10 16361 5532
invoice accept: cancel 45.01 768 5401
customer reserve: creation 4158.05 166 498966
customer reserve: removal 4401.53 50 528184
realization accept: apply 92.30 170 11076
realization accept: cancel 74.92 129 8990
payment from customer: creation 66.73 107 8008
payment from customer: removal 6.68 70 802
payment to supplier: creation 54.18 123 6502
payment to supplier: removal 4.03 69 483
service: total inventory turnovers 5.03 958 603
service: total monetary turnovers 7.28 27 874
service: aggregate perf. data 0.96 16155 115
service: refresh index statistics 0.03 225535 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 22:39:32 to 13-08-2022 22:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4189
TEST_TIME 2 3961
TEST_TIME 3 4066
TEST_TIME 4 4538
TEST_TIME 5 4415
TEST_TIME 6 4733
TEST_TIME 7 5144
TEST_TIME 8 5349
TEST_TIME 9 5486
TEST_TIME 10 5740
TEST_TIME 11 5695
TEST_TIME 12 5855
TEST_TIME 13 6487
TEST_TIME 14 6614
TEST_TIME 15 6830
TEST_TIME 16 7043
TEST_TIME 17 7531
TEST_TIME 18 7665
TEST_TIME 19 7800
TEST_TIME 20 7708
TEST_TIME 21 8519
TEST_TIME 22 8569
TEST_TIME 23 9300
TEST_TIME 24 9533
TEST_TIME 25 10030
TEST_TIME 26 10083
TEST_TIME 27 10131
TEST_TIME 28 10139
TEST_TIME 29 10018
TEST_TIME 30 9927
TEST_TIME 31 9910
TEST_TIME 32 9753
TEST_TIME 33 9780
TEST_TIME 34 9733
TEST_TIME 35 9771
TEST_TIME 36 9734
TEST_TIME 37 9747
TEST_TIME 38 9715
TEST_TIME 39 9626
TEST_TIME 40 9568
TEST_TIME 41 9670
TEST_TIME 42 9522
TEST_TIME 43 9558
TEST_TIME 44 9528
TEST_TIME 45 9437
TEST_TIME 46 9339
TEST_TIME 47 9296
TEST_TIME 48 9275
TEST_TIME 49 9283
TEST_TIME 50 9265
TEST_TIME 51 9417
TEST_TIME 52 9398
TEST_TIME 53 9466
TEST_TIME 54 9526
TEST_TIME 55 9512
TEST_TIME 56 9515
TEST_TIME 57 9619
TEST_TIME 58 9697
TEST_TIME 59 9749
TEST_TIME 60 9729
TEST_TIME 61 9589
TEST_TIME 62 9598
TEST_TIME 63 9518
TEST_TIME 64 9544
TEST_TIME 65 9486
TEST_TIME 66 9417
TEST_TIME 67 9310
TEST_TIME 68 9289
TEST_TIME 69 9321
TEST_TIME 70 9348
TEST_TIME 71 9374
TEST_TIME 72 9401
TEST_TIME 73 9401
TEST_TIME 74 9433
TEST_TIME 75 9420
TEST_TIME 76 9431
TEST_TIME 77 9378
TEST_TIME 78 9410
TEST_TIME 79 9555
TEST_TIME 80 9536
TEST_TIME 81 9526
TEST_TIME 82 9438
TEST_TIME 83 9401
TEST_TIME 84 9382
TEST_TIME 85 9370
TEST_TIME 86 9311
TEST_TIME 87 9288
TEST_TIME 88 9261
TEST_TIME 89 9308
TEST_TIME 90 9284
TEST_TIME 91 9297
TEST_TIME 92 9381
TEST_TIME 93 9390
TEST_TIME 94 9395
TEST_TIME 95 9370
TEST_TIME 96 9455
TEST_TIME 97 9415
TEST_TIME 98 9487
TEST_TIME 99 9521
TEST_TIME 100 9518
TEST_TIME 101 9527
TEST_TIME 102 9539
TEST_TIME 103 9520
TEST_TIME 104 9466
TEST_TIME 105 9416
TEST_TIME 106 9399
TEST_TIME 107 9390
TEST_TIME 108 9344
TEST_TIME 109 9318
TEST_TIME 110 9297
TEST_TIME 111 9296
TEST_TIME 112 9305
TEST_TIME 113 9310
TEST_TIME 114 9306
TEST_TIME 115 9324
TEST_TIME 116 9338
TEST_TIME 117 9340
TEST_TIME 118 9366
TEST_TIME 119 9349
TEST_TIME 120 9330

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2022 22:39:36 to 13-08-2022 22:39:39.

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 1580859 1580859 0 0.00 0 24456 7 0 0
doc_list_biud 1580858 1580858 0 0.00 0 27995 22 0 0
sp_add_invoice_to_stock 5627 5532 95 1.68 25 196900 16362 0 95
sp_cancel_adding_invoice 5401 5401 0 0.00 4 67737 769 0 0
sp_cancel_client_order 1966 1966 0 0.00 4 12830 379 0 0
sp_cancel_customer_reserve 528184 528184 0 0.00 0 22473 51 0 0
sp_cancel_pay_from_customer 802 802 0 0.00 2 3556 70 0 0
sp_cancel_pay_to_supplier 483 483 0 0.00 2 3031 69 0 0
sp_cancel_supplier_invoice 6063 6054 9 0.14 1 28004 760 0 9
sp_cancel_supplier_order 438 438 0 0.00 9 923187 78970 0 0
sp_cancel_write_off 8990 8990 0 0.00 1 19992 130 0 0
sp_client_order 23051 23051 0 0.00 3 31676 371 0 0
sp_customer_reserve 498966 498966 0 0.00 0 39115 167 0 0
sp_fill_shopping_cart 35182 35182 0 0.00 1 14317 36 0 0
sp_fill_shopping_cart_clo_res 498957 498957 0 0.00 0 22053 15 0 0
sp_get_clo_for_invoice 5522 5522 0 0.00 0 42555 299 0 0
sp_kill_cost_storno 15676 15676 0 0.00 0 5881 54 0 0
sp_kill_qstorno_ret_qs2qd 1071850 1071850 0 0.00 0 27991 28 0 0
sp_kill_qty_storno 567631 567631 0 0.00 0 27994 62 0 0
sp_lock_selected_doc 23051 23051 0 0.00 0 8751 7 0 0
sp_make_cost_storno 31108 31108 0 0.00 0 14400 47 0 0
sp_multiply_rows_for_pdistr 31108 31108 0 0.00 0 6275 24 0 0
sp_multiply_rows_for_qdistr 23051 23051 0 0.00 0 22041 188 0 0
sp_pay_from_customer 8008 8008 0 0.00 4 10548 108 0 0
sp_pay_to_supplier 6502 6502 0 0.00 5 10860 123 0 0
sp_payment_common 14510 14510 0 0.00 1 8846 26 0 0
sp_qd_handle_on_cancel_clo 1966 1966 0 0.00 0 12823 347 0 0
sp_qd_handle_on_invoice_upd_sts 10923 10923 0 0.00 0 19497 288 0 0
sp_qd_handle_on_reserve_upd_sts 11076 11076 0 0.00 0 13518 56 0 0
sp_reserve_write_off 11076 11076 0 0.00 6 13532 171 0 0
sp_supplier_invoice 6148 6148 0 0.00 4 83183 3180 0 0
sp_supplier_order 5987 5987 0 0.00 4 31607 1287 0 0
srv_aggregate_perf_data 115 115 0 0.00 2518 169699 16155 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1008 108874 39098 0 0
srv_find_qd_qs_mism 1066351 1066351 0 0.00 0 24454 7 0 0
srv_make_invnt_saldo 603 603 0 0.00 23 67864 959 0 0
srv_make_money_saldo 874 874 0 0.00 0 3840 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 128775 356996 225536 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5532 5532 0 0.00 0 15027 138 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3254 86 0 0
t$perf-norm:sp_cancel_client_order 1966 1966 0 0.00 0 9018 20 0 0
t$perf-norm:sp_cancel_customer_reserve 761 761 0 0.00 0 3336 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 802 802 0 0.00 0 531 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 483 483 0 0.00 0 2421 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 16342 52 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 25396 1447 0 0
t$perf-norm:sp_cancel_write_off 1144 1144 0 0.00 0 3514 9 0 0
t$perf-norm:sp_client_order 23051 23051 0 0.00 0 25078 17 0 0
t$perf-norm:sp_customer_reserve 1103 1103 0 0.00 0 6443 13 0 0
t$perf-norm:sp_pay_from_customer 8008 8008 0 0.00 0 6208 9 0 0
t$perf-norm:sp_pay_to_supplier 6502 6502 0 0.00 0 6834 10 0 0
t$perf-norm:sp_reserve_write_off 11076 11076 0 0.00 0 5258 8 0 0
t$perf-norm:sp_supplier_invoice 6148 6148 0 0.00 0 23511 20 0 0
t$perf-norm:sp_supplier_order 5987 5987 0 0.00 0 5369 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 352 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 865 18 0 0
t$perf-norm:srv_make_money_saldo 874 874 0 0.00 0 668 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 40 13 0 0
v_add_invoice_to_stock 11234 11044 190 1.69 0 7698 17 0 190
v_all_customers 41736 41736 0 0.00 0 149 1 0 0
v_all_suppliers 33430 33430 0 0.00 0 19 1 0 0
v_all_wares 46102 46102 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1297 9 0 0
v_cancel_client_order 3932 3932 0 0.00 0 1775 4 0 0
v_cancel_customer_prepayment 1604 1604 0 0.00 0 2151 6 0 0
v_cancel_customer_reserve 1522 1522 0 0.00 0 3440 12 0 0
v_cancel_payment_to_supplier 966 966 0 0.00 0 3024 11 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 4010 18 0 18
v_cancel_supplier_order 876 876 0 0.00 0 4879 17 0 0
v_cancel_write_off 2288 2288 0 0.00 0 2674 14 0 0
v_max_id_clo_ord 5986 5986 0 0.00 0 2261 2 0 0
v_max_id_clo_res 1103 1103 0 0.00 0 3800 18 0 0
v_max_id_ord_sup 6145 6145 0 0.00 0 6190 5 0 0
v_max_non_paid_invoice 1918 1918 0 0.00 0 726 3 0 0
v_max_non_paid_realizn 4305 4305 0 0.00 0 5701 6 0 0
v_min_id_clo_ord 5986 5986 0 0.00 0 8682 7 0 0
v_min_id_clo_res 1103 1103 0 0.00 0 3291 6 0 0
v_min_id_ord_sup 6145 6145 0 0.00 0 5855 8 0 0
v_min_non_paid_invoice 6502 6502 0 0.00 0 7607 16 0 0
v_min_non_paid_realizn 8008 8008 0 0.00 0 8670 18 0 0
v_our_firm 11772 11772 0 0.00 0 28 1 0 0
v_reserve_write_off 22152 22152 0 0.00 0 6993 6 0 0
x_lock_depdocs_on_canc_invoice 11021 11021 0 0.00 0 20431 115 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 10486 333 0 0
x_make_qstorno_1000_1200 5987 5987 0 0.00 1 31595 1214 0 0
x_make_qstorno_1200_2000 6148 6148 0 0.00 2 83151 3048 0 0
x_make_qstorno_2100_3300 498965 498965 0 0.00 0 39114 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 22:39:40 to 13-08-2022 22:39:44.

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:29 21 8192000000 8816312704 8833638400 18311200 0.223 6 4 4 0 18550160 100672 138288
20:09:30 23486 8192000000 9901417328 10213580800 984313248 10.726 100 87 174 0 1013441776 6365920 22762608
20:10:56 24633 8192000000 9955221760 10281000960 1032304240 11.191 100 82 164 0 1061431904 8223616 20904048
20:12:28 15948 8192000000 10001938480 10339184640 1081931472 11.666 100 79 158 0 1111434336 10283760 19219104
20:13:56 6562 8192000000 10041528224 10391683072 1127299488 12.096 100 76 150 0 1156307136 11415072 17592576
20:15:05 5335 8192000000 10096310272 10453639168 1175737840 12.550 100 86 170 0 1207969952 12804736 19427376
20:16:12 3780 8192000000 10128853440 10497376256 1219677152 12.959 100 88 174 0 1252675408 13185280 19812976
20:17:18 2262 8192000000 10152814240 10523807744 1244246080 13.185 100 84 163 1 1276400000 13449744 18704176
20:18:22 1143 8192000000 10186384928 10563358720 1274015152 13.458 100 90 176 0 1308191008 15484832 18691024
20:19:25 1322 8192000000 10212049568 10594238464 1295525264 13.655 100 89 178 2 1328008704 13164544 19318896
20:20:27 3235 8192000000 10197231664 10599968768 1301660208 13.710 100 47 92 0 1321354704 9730560 9963936
20:21:31 55898 8192000000 10206151728 10613628928 1311854240 13.803 100 46 87 4 1327670528 4864528 10951760
20:23:28 4500 8192000000 10211122016 10625622016 1320283840 13.879 100 34 68 0 1334789872 6583936 7922096
20:24:36 17957 8192000000 10214900128 10635128832 1324503616 13.917 100 15 30 0 1332429472 5071024 2854832
20:25:56 5285 8192000000 10218943408 10638159872 1324775360 13.920 100 14 26 0 1338018880 9840848 3402672
20:27:01 25439 8192000000 10201369056 10624557056 1311962576 13.804 99 61 122 2 1333410512 5475056 15972880
20:28:31 4131 8192000000 10205697904 10618290176 1302063264 13.714 99 29 58 0 1315372992 6996800 6312928
20:29:37 13126 8192000000 10187192672 10605477888 1282549136 13.536 98 19 38 0 1292168912 5265216 4354560
20:30:51 13326 8192000000 10115853440 10502193152 1146448432 12.276 92 24 48 0 1158244016 4378752 7416832
20:32:07 18212 8192000000 10128601904 10521706496 1163900880 12.440 93 20 40 0 1173870336 6201168 3768288
20:33:25 5830 8192000000 10155398464 10553954304 1250017440 13.238 99 36 72 1 1265589808 7296000 8276368
20:34:32 7597 8192000000 10170819712 10583826432 1280096560 13.514 100 26 50 0 1296398608 10257008 6045040
20:35:42 46999 8192000000 10182997136 10589868032 1290556336 13.609 100 33 66 1 1303867728 5594048 7717344
20:37:32 7527 8192000000 10195803680 10604843008 1301794384 13.712 100 44 89 1 1319112272 7683232 9634656
20:38:41 16623 8192000000 10228148640 10638958592 1327946784 13.949 100 44 83 1 1343086240 4896704 10242752
20:40:03 12484 8192000000 10251801824 10664247296 1345794224 14.110 100 48 96 0 1365342816 8913232 10635360
20:41:23 43622 8192000000 10264792512 10693197824 1361892704 14.254 100 30 60 0 1380498480 11123232 7482544
20:43:31 4937 8192000000 10281311648 10699542528 1371008368 14.336 100 51 101 1 1396232352 13535264 11688720
20:44:41 15830 8192000000 10296327120 10715168768 1378368224 14.402 100 41 82 0 1403387200 15025008 9993968
20:46:09 19563 8192000000 10309103008 10737139712 1391144160 14.516 100 37 73 0 1416111424 15365760 9601504
20:47:35 9419 8192000000 10291847616 10717749248 1370607952 14.332 99 46 92 2 1398819072 16832368 11378752
20:48:52 2632 8192000000 10305301152 10722406400 1379770416 14.414 100 66 132 0 1415311232 19951008 15589808
20:49:59 6603 8192000000 10324611760 10748633088 1399926096 14.594 100 55 108 0 1438850064 25836464 13087504
20:51:11 2221 8192000000 10341903520 10755981312 1394221168 14.544 100 73 147 1 1448575968 36168624 18186176
20:52:18 4877 8192000000 10321610672 10730016768 1369506368 14.323 98 72 145 0 1415838160 27301952 19029840
20:53:26 2130 8192000000 10342859360 10765287424 1404210416 14.632 100 64 126 0 1452688704 31769136 16709152
20:54:35 7072 8192000000 10359725808 10778202112 1411474192 14.697 100 71 138 0 1463072960 34537232 17061536
20:55:46 1005 8192000000 10354398752 10763997184 1401180416 14.606 99 83 163 1 1456697616 35542368 19974832
20:56:51 7434 8192000000 10344287936 10769387520 1401137440 14.605 100 49 97 0 1449626320 35675120 12813760
20:58:11 18138 8192000000 10314594144 10743963648 1377328992 14.393 100 40 80 0 1427962272 38930960 11702320
20:59:32 22329 8192000000 10295401584 10735583232 1359094128 14.229 100 29 58 0 1412197648 44435264 8668256
21:01:02 10042 8192000000 10260798512 10688933888 1308125520 13.769 97 24 48 0 1358810480 44285312 6399648
21:02:21 26729 8192000000 10253045856 10687004672 1318049328 13.859 99 16 32 0 1352303120 29705392 4548400
21:03:49 32030 8192000000 10292025792 10716143616 1346678256 14.118 99 34 65 0 1367701712 13542512 7480944
21:05:24 47715 8192000000 10272074208 10714189824 1346127504 14.113 99 16 32 0 1367344864 17350048 3867312
21:07:16 14247 8192000000 10267004080 10695618560 1328459952 13.953 98 32 65 1 1350784752 15622832 6701968
21:08:31 81521 8192000000 10252120128 10676453376 1310818800 13.793 97 31 58 0 1329844656 11757600 7268256
21:10:56 21797 8192000000 10266349248 10703552512 1339019424 14.049 98 32 64 0 1356916416 10792512 7104480
21:12:21 48977 8192000000 10266689904 10707357696 1355370752 14.196 99 30 57 0 1370668896 8834592 6463552
21:14:15 21293 8192000000 10300844784 10730930176 1378893984 14.407 100 46 92 0 1399201408 9076272 11231152
21:15:38 24517 8192000000 10278970704 10726645760 1367506400 14.305 99 17 34 1 1382192608 10768512 3917696
21:17:11 46906 8192000000 10274892672 10723295232 1368802464 14.316 99 12 24 0 1383178784 11852816 2523504
21:19:02 16185 8192000000 10284954304 10737799168 1384965856 14.461 100 12 24 0 1400127632 12231680 2930096
21:20:21 69001 8192000000 10283756544 10742525952 1384552688 14.457 100 23 46 0 1399198896 8789280 5856928
21:23:29 81308 8192000000 10272311008 10736128000 1386420336 14.474 100 13 26 0 1397211088 7828256 2962496
21:26:14 45022 8192000000 10279954192 10745626624 1387858320 14.487 100 16 32 0 1402958144 11321232 3778592
21:28:04 42295 8192000000 10281035632 10730934272 1379401936 14.411 99 16 32 0 1395064880 11864816 3798128
21:29:48 33681 8192000000 10278924736 10730926080 1360306448 14.240 98 18 36 0 1378203568 13698640 4198480
21:31:27 64703 8192000000 10293031584 10747461632 1381198144 14.427 99 15 30 0 1394437520 9866976 3372400
21:33:37 16801 8192000000 10299083808 10739789824 1373170496 14.355 99 41 80 0 1393523808 11298144 9055168
21:34:58 47337 8192000000 10279728928 10738372608 1351743792 14.163 97 15 31 0 1369722800 14545776 3433232
21:36:46 108874 8192000000 10285285024 10744115200 1331918016 13.984 96 8 16 1 1349866992 16366336 1582640
21:39:35 66215 8192000000 10288369472 10744864768 1365266688 14.285 98 23 46 0 1378715904 8485968 4963248
21:41:44 42064 8192000000 10270375792 10719358976 1354882368 14.191 98 23 46 0 1368238320 8110336 5245616
21:43:27 41915 8192000000 10257523584 10711031808 1358492880 14.224 99 6 12 0 1365474272 5903760 1077632
21:45:16 57250 8192000000 10262682064 10712625152 1335400752 14.016 97 13 26 0 1350513824 11823904 3289168
21:48:35 54493 8192000000 10260258576 10711883776 1321095568 13.887 96 23 47 0 1339771040 13330432 5345040
21:50:31 74024 8192000000 10266314816 10710355968 1339693440 14.055 98 22 44 1 1358739856 14098512 4947904
21:52:46 22477 8192000000 10265066896 10700353536 1332611088 13.991 98 42 84 1 1352525808 10568576 9346144
21:54:09 13073 8192000000 10267933824 10707279872 1353796128 14.182 100 39 76 1 1374384752 12371504 8217120
21:55:31 21477 8192000000 10280639440 10706087936 1333618128 14.000 98 60 119 0 1362205040 15491808 13095104
21:56:52 17300 8192000000 10284431632 10724507648 1355194144 14.194 99 52 105 0 1384765328 18165264 11405920
21:58:25 100183 8192000000 10271860176 10711347200 1325714944 13.928 97 23 44 0 1341346064 10716400 4914720
22:01:08 46176 8192000000 10282903216 10722521088 1361740192 14.253 99 20 40 0 1372853712 6842336 4271184
22:02:56 35086 8192000000 10268610352 10729254912 1377032416 14.390 100 24 46 1 1392882112 10891824 4957872
22:04:33 37908 8192000000 10276515568 10738839552 1383066880 14.444 100 6 12 0 1395235328 10685552 1482896
22:06:26 67819 8192000000 10283914048 10751512576 1386059216 14.471 100 7 14 0 1403451392 15649600 1742576
22:08:34 61894 8192000000 10318728048 10768130048 1409771184 14.682 100 14 26 1 1424637856 11939584 2927088
22:10:38 38189 8192000000 10309500976 10755854336 1375385376 14.375 98 16 33 0 1385834800 6500624 3948800
22:12:19 72627 8192000000 10305523376 10756841472 1381397168 14.429 99 23 46 0 1397605296 11004928 5203200
22:14:32 61683 8192000000 10298781456 10743595008 1372854896 14.353 98 12 24 0 1386404064 10727392 2821776
22:16:35 81558 8192000000 10286014000 10749919232 1330908544 13.975 95 6 10 0 1357255392 25213584 1133264
22:18:56 28532 8192000000 10281486688 10719100928 1323389648 13.907 96 18 36 2 1335097024 7559792 4147584
22:20:30 68361 8192000000 10277163648 10716569600 1311360560 13.798 95 16 32 0 1328094736 12789968 3944208
22:22:39 76330 8192000000 10257047552 10705928192 1338659936 14.045 98 17 34 0 1350526912 6861584 5005392
22:24:56 50607 8192000000 10253368512 10706669568 1340661312 14.063 97 12 24 0 1349386560 6399632 2325616
22:26:47 56438 8192000000 10272020512 10732273664 1355545056 14.197 98 8 16 0 1367280960 9864640 1871264
22:28:53 71638 8192000000 10270016960 10731212800 1331859088 13.984 96 15 31 0 1349709808 13962208 3888512
22:31:46 14962 8192000000 10247041216 10691710976 1330964928 13.976 98 30 59 0 1343804480 6768640 6070912
22:33:02 55959 8192000000 10257935840 10705711104 1318370672 13.862 97 21 42 0 1335104656 11645968 5088016
22:34:58 58961 8192000000 10264747184 10709049344 1325487648 13.926 97 20 40 0 1340213264 10815248 3910368
22:37:00 65324 8192000000 10276496432 10712260608 1319700624 13.874 97 20 40 0 1341011104 16799024 4511456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2022 22:39:46 to 13-08-2022 22:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 22:40:02 to 13-08-2022 22:40:04.

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          253251
MON$OLDEST_ACTIVE               255320
MON$OLDEST_SNAPSHOT             255320
MON$NEXT_TRANSACTION            255320
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                       2008576
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:208: 
:209: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:27 2022
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:28 2022
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:29 2022
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:42 2022
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:43 2022
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:227: 
:228: 
:229: mscs-test-161.budzdorov.ru    Sat Aug 13 22:38:43 2022
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:231: 
--- end of diff output ---

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

13.08.22 22:40:05. 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-08-13 22:40:08 No severe PSQL-related problems occured
13.08.22 22:40:09. End of report.