Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.2022 19: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 16386800 kB
MemAvailable 31516904 kB
Buffers 0 kB
Cached 15295840 kB
SwapCached 2364 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.10
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-04-09 19:38:17
DTS_END                         2022-04-09 19:38:17.6080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34602
TRN_ID                          239475
ATT_ID                          627
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: 9324.66 22981 1118959
customer order: creation 169.34 350 20321
customer order: refuse 14.43 366 1731
order to supplier: creation 45.74 1390 5489
order to supplier: removal 3.58 106909 430
invoice (draft): creation 46.63 3365 5595
invoice (draft): removal 53.35 937 6402
invoice accept: apply 41.81 18157 5017
invoice accept: cancel 47.76 809 5731
customer reserve: creation 3926.42 177 471170
customer reserve: removal 4670.75 64 560490
realization accept: apply 82.91 194 9949
realization accept: cancel 90.89 108 10907
payment from customer: creation 58.83 128 7059
payment from customer: removal 6.01 106 721
payment to supplier: creation 49.95 138 5994
payment to supplier: removal 3.73 84 447
service: total inventory turnovers 4.61 1113 553
service: total monetary turnovers 7.15 30 858
service: aggregate perf. data 0.76 25759 91
service: refresh index statistics 0.03 299442 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-04-2022 19:39:36 to 09-04-2022 19:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4347
TEST_TIME 2 5420
TEST_TIME 3 5103
TEST_TIME 4 6307
TEST_TIME 5 5580
TEST_TIME 6 5633
TEST_TIME 7 5808
TEST_TIME 8 5595
TEST_TIME 9 5890
TEST_TIME 10 5861
TEST_TIME 11 6123
TEST_TIME 12 6013
TEST_TIME 13 5985
TEST_TIME 14 6662
TEST_TIME 15 6615
TEST_TIME 16 6569
TEST_TIME 17 6705
TEST_TIME 18 7333
TEST_TIME 19 7433
TEST_TIME 20 7785
TEST_TIME 21 8096
TEST_TIME 22 8214
TEST_TIME 23 8543
TEST_TIME 24 8671
TEST_TIME 25 8993
TEST_TIME 26 8936
TEST_TIME 27 9125
TEST_TIME 28 10143
TEST_TIME 29 11199
TEST_TIME 30 11148
TEST_TIME 31 10908
TEST_TIME 32 10895
TEST_TIME 33 10858
TEST_TIME 34 10748
TEST_TIME 35 10762
TEST_TIME 36 10582
TEST_TIME 37 10440
TEST_TIME 38 10552
TEST_TIME 39 10524
TEST_TIME 40 10466
TEST_TIME 41 10471
TEST_TIME 42 10485
TEST_TIME 43 10505
TEST_TIME 44 10479
TEST_TIME 45 10448
TEST_TIME 46 10437
TEST_TIME 47 10337
TEST_TIME 48 10278
TEST_TIME 49 10206
TEST_TIME 50 10085
TEST_TIME 51 10134
TEST_TIME 52 10151
TEST_TIME 53 10023
TEST_TIME 54 9902
TEST_TIME 55 9920
TEST_TIME 56 9872
TEST_TIME 57 9827
TEST_TIME 58 9877
TEST_TIME 59 9939
TEST_TIME 60 9904
TEST_TIME 61 9867
TEST_TIME 62 9783
TEST_TIME 63 9689
TEST_TIME 64 9598
TEST_TIME 65 9670
TEST_TIME 66 9650
TEST_TIME 67 9601
TEST_TIME 68 9690
TEST_TIME 69 9708
TEST_TIME 70 9734
TEST_TIME 71 9712
TEST_TIME 72 9670
TEST_TIME 73 9713
TEST_TIME 74 9806
TEST_TIME 75 9791
TEST_TIME 76 9840
TEST_TIME 77 9801
TEST_TIME 78 9835
TEST_TIME 79 9827
TEST_TIME 80 9742
TEST_TIME 81 9699
TEST_TIME 82 9632
TEST_TIME 83 9588
TEST_TIME 84 9604
TEST_TIME 85 9600
TEST_TIME 86 9540
TEST_TIME 87 9471
TEST_TIME 88 9499
TEST_TIME 89 9536
TEST_TIME 90 9523
TEST_TIME 91 9566
TEST_TIME 92 9572
TEST_TIME 93 9627
TEST_TIME 94 9613
TEST_TIME 95 9605
TEST_TIME 96 9581
TEST_TIME 97 9651
TEST_TIME 98 9682
TEST_TIME 99 9652
TEST_TIME 100 9681
TEST_TIME 101 9688
TEST_TIME 102 9678
TEST_TIME 103 9734
TEST_TIME 104 9661
TEST_TIME 105 9604
TEST_TIME 106 9580
TEST_TIME 107 9566
TEST_TIME 108 9532
TEST_TIME 109 9478
TEST_TIME 110 9485
TEST_TIME 111 9472
TEST_TIME 112 9429
TEST_TIME 113 9412
TEST_TIME 114 9369
TEST_TIME 115 9350
TEST_TIME 116 9357
TEST_TIME 117 9367
TEST_TIME 118 9371
TEST_TIME 119 9385
TEST_TIME 120 9326

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-04-2022 19:39:40 to 09-04-2022 19:39:43.

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 1556002 1556002 0 0.00 0 16377 6 0 0
doc_list_biud 1556002 1556002 0 0.00 0 83301 29 0 0
sp_add_invoice_to_stock 5138 5017 121 2.35 28 194349 18157 0 121
sp_cancel_adding_invoice 5731 5731 0 0.00 3 67320 810 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 12920 367 0 0
sp_cancel_customer_reserve 560490 560490 0 0.00 1 21210 64 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 8797 106 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 3719 84 0 0
sp_cancel_supplier_invoice 6410 6402 8 0.12 2 83309 938 0 8
sp_cancel_supplier_order 430 430 0 0.00 6 1065246 106910 0 0
sp_cancel_write_off 10907 10907 0 0.00 1 16379 108 0 0
sp_client_order 20321 20321 0 0.00 3 22213 351 0 0
sp_customer_reserve 471170 471170 0 0.00 0 43366 177 0 0
sp_fill_shopping_cart 31402 31402 0 0.00 1 18799 41 0 0
sp_fill_shopping_cart_clo_res 471165 471165 0 0.00 0 24615 14 0 0
sp_get_clo_for_invoice 5011 5011 0 0.00 0 15431 253 0 0
sp_kill_cost_storno 17806 17806 0 0.00 0 16376 59 0 0
sp_kill_qstorno_ret_qs2qd 1138719 1138719 0 0.00 0 83297 35 0 0
sp_kill_qty_storno 600650 600650 0 0.00 0 83300 75 0 0
sp_lock_selected_doc 20784 20784 0 0.00 0 12443 7 0 0
sp_make_cost_storno 28012 28012 0 0.00 0 14589 58 0 0
sp_multiply_rows_for_pdistr 28012 28012 0 0.00 0 8317 29 0 0
sp_multiply_rows_for_qdistr 20321 20321 0 0.00 0 14662 151 0 0
sp_pay_from_customer 7059 7059 0 0.00 4 20172 129 0 0
sp_pay_to_supplier 5994 5994 0 0.00 5 17046 139 0 0
sp_payment_common 13053 13053 0 0.00 2 20169 35 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 12913 336 0 0
sp_qd_handle_on_invoice_upd_sts 10741 10741 0 0.00 0 35450 330 0 0
sp_qd_handle_on_reserve_upd_sts 9949 9949 0 0.00 0 24824 64 0 0
sp_reserve_write_off 9949 9949 0 0.00 6 24911 194 0 0
sp_supplier_invoice 5595 5595 0 0.00 7 84918 3366 0 0
sp_supplier_order 5489 5489 0 0.00 4 45983 1391 0 0
srv_aggregate_perf_data 91 91 0 0.00 2416 567562 25760 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1305 186780 45702 0 0
srv_find_qd_qs_mism 1071219 1071219 0 0.00 0 12463 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 25 101051 1114 0 0
srv_make_money_saldo 858 858 0 0.00 1 6487 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 143887 457781 299443 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 14328 185 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 8525 94 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 6670 18 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 747 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 463 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 160 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1290 18 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 28187 1627 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 3147 9 0 0
t$perf-norm:sp_client_order 20321 20321 0 0.00 0 11134 17 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 2552 9 0 0
t$perf-norm:sp_pay_from_customer 7059 7059 0 0.00 0 9148 13 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 7153 7 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 7764 12 0 0
t$perf-norm:sp_supplier_invoice 5595 5595 0 0.00 0 6722 18 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 3256 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1371 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 385 8 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 1116 18 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 1351 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 8 6 0 0
v_add_invoice_to_stock 10262 10020 242 2.35 0 9588 19 0 242
v_all_customers 37590 37590 0 0.00 0 28 1 0 0
v_all_suppliers 31340 31340 0 0.00 0 18 1 0 0
v_all_wares 40642 40642 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3168 10 0 0
v_cancel_client_order 3462 3462 0 0.00 0 5061 7 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 5004 18 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 3537 12 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2553 10 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 3537 20 0 16
v_cancel_supplier_order 860 860 0 0.00 0 1151 8 0 0
v_cancel_write_off 2058 2058 0 0.00 0 4716 16 0 0
v_max_id_clo_ord 5489 5489 0 0.00 0 807 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 7415 22 0 0
v_max_id_ord_sup 5592 5592 0 0.00 0 3810 3 0 0
v_max_non_paid_invoice 1405 1405 0 0.00 0 1164 4 0 0
v_max_non_paid_realizn 3520 3520 0 0.00 0 4007 5 0 0
v_min_id_clo_ord 5489 5489 0 0.00 0 10200 8 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 5707 9 0 0
v_min_id_ord_sup 5592 5592 0 0.00 0 4621 7 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 16929 20 0 0
v_min_non_paid_realizn 7059 7059 0 0.00 0 20165 29 0 0
v_our_firm 10130 10130 0 0.00 0 16 1 0 0
v_reserve_write_off 19898 19898 0 0.00 0 3172 5 0 0
x_lock_depdocs_on_canc_invoice 11737 11737 0 0.00 0 12999 122 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 23925 363 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 1 40120 1307 0 0
x_make_qstorno_1200_2000 5595 5595 0 0.00 4 75124 3223 0 0
x_make_qstorno_2100_3300 471170 471170 0 0.00 0 43364 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-04-2022 19:39:44 to 09-04-2022 19:39:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:21 6 8192000000 8800045536 8811548672 6310704 0.076 2 1 2 0 6410336 56848 42784
17:09:21 31330 8192000000 9919048544 10233049088 998874208 10.868 100 85 170 2 1026948528 6494160 21580160
17:10:58 11485 8192000000 9950383968 10275299328 1036304944 11.229 100 92 184 0 1068814752 9078432 23431376
17:12:19 13796 8192000000 10001883808 10337153024 1081078400 11.658 100 83 166 0 1111938320 10432768 20427152
17:13:36 15413 8192000000 10035120976 10385616896 1122072960 12.047 100 89 178 0 1156114400 12677808 21363632
17:14:55 9881 8192000000 10074536272 10432520192 1163195552 12.433 100 82 162 0 1193943840 11902416 18845872
17:16:08 7162 8192000000 10123214720 10493415424 1204809456 12.821 100 75 149 1 1234439968 13139744 16490768
17:17:17 3100 8192000000 10155571248 10532040704 1242389296 13.168 100 85 170 1 1275877696 14794288 18694112
17:18:22 1449 8192000000 10199666080 10584080384 1287618720 13.583 100 90 180 2 1319996384 12374960 20002704
17:19:25 3062 8192000000 10204764528 10597855232 1299716224 13.693 100 55 110 1 1323643648 11317760 12609664
17:20:28 7087 8192000000 10204242224 10613936128 1314405456 13.826 100 29 58 1 1328541152 7885056 6250640
17:21:39 72277 8192000000 10219509504 10637115392 1324217424 13.915 100 32 64 1 1337654048 5599088 7837536
17:23:51 4646 8192000000 10230835424 10647597056 1334775360 14.010 100 42 84 0 1351942336 7238080 9928896
17:24:59 10496 8192000000 10230576160 10652581888 1339723312 14.055 100 16 32 0 1348909360 5806896 3379152
17:26:10 62109 8192000000 10222770384 10650402816 1324584128 13.918 99 23 43 1 1334441904 4815328 5042448
17:28:13 7029 8192000000 10213520256 10634821632 1322813968 13.902 100 26 52 0 1335466768 7020144 5632656
17:29:20 11142 8192000000 10164052992 10566103040 1227111200 13.027 95 18 36 0 1236916448 5619664 4185584
17:30:32 47406 8192000000 10124272272 10528980992 1198763952 12.765 96 25 49 2 1210092624 4602432 6726240
17:32:31 6702 8192000000 10145713168 10542919680 1249872944 13.237 100 28 56 1 1261567728 5108096 6586688
17:33:39 8836 8192000000 10157583200 10565201920 1264181600 13.368 100 34 68 1 1277973216 5637168 8154448
17:34:49 9847 8192000000 10176859024 10591780864 1286751456 13.575 100 24 47 0 1299183472 6567440 5864576
17:36:00 57830 8192000000 10194550800 10607792128 1298957152 13.686 100 35 68 1 1312830048 4874784 8998112
17:38:06 5428 8192000000 10208027744 10620178432 1314535312 13.827 100 27 54 0 1325971888 5594384 5842192
17:39:18 20695 8192000000 10248393552 10657112064 1336304880 14.024 100 57 112 3 1358512080 9653616 12553584
17:40:43 10992 8192000000 10279107152 10679296000 1353625760 14.180 100 60 119 1 1380291072 13388080 13277232
17:41:58 49906 8192000000 10274582912 10700566528 1363540960 14.269 100 44 88 0 1390669472 16599696 10528816
17:43:54 11936 8192000000 10289535488 10710089728 1365799168 14.289 99 53 106 0 1395197872 17242768 12155936
17:45:19 14056 8192000000 10311368048 10730926080 1386402096 14.474 100 62 125 1 1422674032 21414048 14857888
17:46:39 1305 8192000000 10334033824 10754535424 1405444336 14.643 100 56 111 2 1440458672 22467104 12547232
17:47:45 5779 8192000000 10343965952 10763284480 1408162224 14.668 100 64 126 0 1450409568 27076432 15170912
17:48:56 6132 8192000000 10351074768 10774224896 1414063296 14.720 100 61 122 2 1460481552 30641744 15776512
17:50:08 6049 8192000000 10367624848 10785243136 1422051600 14.791 100 72 144 4 1478094560 38438336 17604624
17:51:25 7500 8192000000 10390985216 10814099456 1432743024 14.886 100 60 118 3 1494991536 47290608 14957904
17:52:38 3671 8192000000 10365602320 10781749248 1409152352 14.676 100 60 118 0 1470792928 46705088 14935488
17:53:45 9729 8192000000 10374997248 10793037824 1413413024 14.714 100 61 122 3 1478777184 49704336 15659824
17:55:03 4426 8192000000 10388978128 10808160256 1421068144 14.782 100 63 126 0 1492260720 54394976 16797600
17:56:16 3851 8192000000 10392964416 10805989376 1416208976 14.739 100 78 154 2 1498675664 62155392 20311296
17:57:31 4443 8192000000 10384789312 10793426944 1397105424 14.569 99 83 166 1 1477658240 58890976 21661840
17:58:40 3699 8192000000 10397568816 10810314752 1413770368 14.717 100 74 148 0 1496841760 63900144 19171248
17:59:46 7430 8192000000 10374154032 10798071808 1404747424 14.637 100 46 92 1 1479117424 61815856 12554144
18:01:01 17521 8192000000 10349627776 10783211520 1373509280 14.358 98 38 76 1 1445001664 60830016 10662368
18:02:24 14786 8192000000 10344987408 10772160512 1362982704 14.264 98 36 72 2 1426433424 52948352 10502368
18:03:42 31610 8192000000 10342543120 10782908416 1392416256 14.527 100 30 60 1 1455633920 54705216 8512448
18:05:16 19125 8192000000 10331865248 10763599872 1353409920 14.178 99 23 46 2 1424396560 62277856 8708784
18:06:41 20473 8192000000 10318147936 10787770368 1363910304 14.272 100 12 24 0 1426290624 58722112 3658208
18:08:02 128840 8192000000 10291555584 10725212160 1337168800 14.032 97 26 53 3 1360006736 16105312 6732624
18:11:14 85204 8192000000 10284915120 10733969408 1359507344 14.233 98 13 26 1 1377647888 15425888 2714656
18:13:41 88786 8192000000 10290188672 10742505472 1357076288 14.211 98 14 28 0 1380710784 20367840 3266656
18:16:19 78761 8192000000 10290803616 10743980032 1347367424 14.124 97 22 44 1 1374558704 21844144 5347136
18:18:51 109976 8192000000 10300779120 10748428288 1390065744 14.506 100 19 38 0 1409901824 15426576 4409504
18:21:59 186780 8192000000 10314600320 10776043520 1383164608 14.445 99 4 8 1 1402118800 18463680 490512
18:26:06 62655 8192000000 10314683536 10769522688 1389752928 14.504 99 21 42 0 1410629280 15209824 5666528
18:28:10 80832 8192000000 10321483184 10777952256 1390729696 14.512 99 29 58 0 1414702944 16670400 7302848
18:30:34 36356 8192000000 10328212704 10766508032 1390185744 14.508 99 36 65 4 1409091040 10563600 8341696
18:32:11 36904 8192000000 10328688176 10775478272 1394972640 14.550 99 45 90 1 1416058480 11192096 9893744
18:33:48 43204 8192000000 10315846688 10771501056 1387098112 14.480 98 20 40 0 1405846912 14049984 4698816
18:35:34 13312 8192000000 10318682000 10773393408 1406266016 14.651 100 28 56 2 1430637152 17391424 6979712
18:36:55 45300 8192000000 10312554352 10776297472 1385256416 14.464 98 38 75 0 1412429600 18613520 8559664
18:38:45 25449 8192000000 10284383232 10747957248 1368445408 14.313 99 8 16 0 1387579664 17487216 1647040
18:40:14 41377 8192000000 10291817360 10748055552 1382520928 14.439 99 12 25 1 1391836432 6283392 3032112
18:41:56 51895 8192000000 10302412768 10763075584 1386089376 14.471 99 24 48 0 1402357744 11028480 5239888
18:45:00 44173 8192000000 10312173744 10762698752 1381160032 14.427 99 34 68 0 1398475024 9480000 7834992
18:46:47 63834 8192000000 10286048096 10739568640 1325106528 13.923 95 18 36 0 1340475792 11370640 3998624
18:49:00 41127 8192000000 10267135328 10713964544 1302088048 13.714 95 15 30 0 1314513792 9212784 3212960
18:50:42 26802 8192000000 10271450560 10718388224 1327401216 13.944 97 36 72 0 1349689936 14527136 7761584
18:52:09 92986 8192000000 10255247312 10712256512 1288786848 13.593 95 11 22 0 1308181136 16396160 2998128
18:54:50 61155 8192000000 10275144128 10726596608 1332124336 13.986 98 8 16 0 1348939072 15318160 1496576
18:56:53 36258 8192000000 10263283648 10716663808 1333131648 13.995 98 21 42 1 1347803648 9733392 4938608
18:58:43 48941 8192000000 10255317344 10691112960 1330925904 13.976 99 43 86 0 1353542480 12400192 10216384
19:00:34 71859 8192000000 10240932496 10680061952 1313066528 13.814 97 34 69 0 1331426304 10533792 7825984
19:02:48 39197 8192000000 10259513280 10715541504 1348725008 14.136 99 12 24 1 1368414368 16831072 2858288
19:04:28 66251 8192000000 10280153968 10730852352 1355875936 14.200 99 14 28 0 1376929136 17903712 3149488
19:06:35 78856 8192000000 10257970752 10715566080 1323293056 13.907 97 8 16 0 1341581264 16111472 2176736
19:09:00 70187 8192000000 10286732032 10737577984 1361313456 14.249 99 19 38 0 1375206208 9940192 3952560
19:11:14 68986 8192000000 10284661472 10744262656 1365810544 14.289 99 14 26 3 1377455056 8505280 3139232
19:13:30 36675 8192000000 10316644912 10755207168 1366010192 14.291 98 35 70 1 1384819744 11441872 7367680
19:15:11 88427 8192000000 10309276512 10779058176 1351117440 14.158 97 26 52 1 1383036128 26223264 5695424
19:17:40 92162 8192000000 10309841040 10761699328 1371548864 14.341 98 16 32 0 1385801744 10758192 3494688
19:20:15 36948 8192000000 10316699520 10765508608 1387428912 14.483 99 26 52 1 1403462416 10244768 5788736
19:21:56 109670 8192000000 10285568128 10740670464 1382356944 14.438 99 10 20 0 1394457280 10038192 2062144
19:24:52 46581 8192000000 10293369088 10752536576 1380591088 14.422 99 7 14 0 1389093248 6985904 1516256
19:26:41 60189 8192000000 10285128560 10743304192 1358567968 14.224 97 6 12 0 1367055888 7057824 1430096
19:29:15 57810 8192000000 10269600288 10722951168 1365336112 14.285 99 9 18 0 1377478880 9726944 2415824
19:31:33 33969 8192000000 10262583248 10719985664 1355671744 14.198 99 21 42 1 1367317600 7445744 4200112
19:33:14 66989 8192000000 10276255344 10742321152 1367686720 14.306 99 12 24 0 1377277360 6965184 2625456
19:35:29 45608 8192000000 10270561760 10731581440 1339680816 14.055 97 23 46 0 1356905312 11730816 5493680
19:37:17 48499 8192000000 10279110656 10733977600 1331554576 13.981 97 37 74 0 1358726864 18488848 8683440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-04-2022 19:39:50 to 09-04-2022 19:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          236880
MON$OLDEST_ACTIVE               239619
MON$OLDEST_SNAPSHOT             239619
MON$NEXT_TRANSACTION            239619
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                       2058464
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Sat Apr  9 19:38:19 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Sat Apr  9 19:38:21 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Sat Apr  9 19:38:27 2022
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
--- end of diff output ---

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

09.04.22 19:40:09. 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-04-09 19:40:11 No severe PSQL-related problems occured
09.04.22 19:40:13. End of report.