Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2021 16: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16394320 kB
MemAvailable 31034892 kB
Buffers 0 kB
Cached 15291092 kB
SwapCached 2592 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.8
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 2021-08-09 16:37:48
DTS_END                         2021-08-09 16:37:48.0290
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/49074
TRN_ID                          240278
ATT_ID                          629
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: 9217.06 21160 1106047
customer order: creation 175.08 382 21009
customer order: refuse 15.55 363 1866
order to supplier: creation 45.35 1454 5442
order to supplier: removal 3.53 102346 423
invoice (draft): creation 46.47 3426 5576
invoice (draft): removal 53.39 906 6407
invoice accept: apply 41.80 18893 5016
invoice accept: cancel 47.17 802 5660
customer reserve: creation 3836.54 187 460385
customer reserve: removal 4655.87 59 558704
realization accept: apply 82.44 182 9893
realization accept: cancel 82.63 118 9915
payment from customer: creation 60.42 158 7250
payment from customer: removal 6.15 69 738
payment to supplier: creation 48.82 143 5858
payment to supplier: removal 3.51 86 421
service: total inventory turnovers 4.83 1157 579
service: total monetary turnovers 6.62 39 794
service: aggregate perf. data 0.89 18373 107
service: refresh index statistics 0.03 274059 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-08-2021 16:39:24 to 09-08-2021 16:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2218
TEST_TIME 2 2958
TEST_TIME 3 3649
TEST_TIME 4 4579
TEST_TIME 5 4565
TEST_TIME 6 4978
TEST_TIME 7 5289
TEST_TIME 8 5733
TEST_TIME 9 5829
TEST_TIME 10 6351
TEST_TIME 11 6408
TEST_TIME 12 6621
TEST_TIME 13 6619
TEST_TIME 14 6666
TEST_TIME 15 6940
TEST_TIME 16 7075
TEST_TIME 17 7375
TEST_TIME 18 7491
TEST_TIME 19 7946
TEST_TIME 20 8100
TEST_TIME 21 8265
TEST_TIME 22 8986
TEST_TIME 23 9202
TEST_TIME 24 9165
TEST_TIME 25 9121
TEST_TIME 26 9692
TEST_TIME 27 10138
TEST_TIME 28 10856
TEST_TIME 29 10603
TEST_TIME 30 10464
TEST_TIME 31 10373
TEST_TIME 32 10400
TEST_TIME 33 10181
TEST_TIME 34 10070
TEST_TIME 35 9978
TEST_TIME 36 9866
TEST_TIME 37 9853
TEST_TIME 38 10018
TEST_TIME 39 9878
TEST_TIME 40 9889
TEST_TIME 41 9789
TEST_TIME 42 9633
TEST_TIME 43 9497
TEST_TIME 44 9614
TEST_TIME 45 9565
TEST_TIME 46 9464
TEST_TIME 47 9384
TEST_TIME 48 9304
TEST_TIME 49 9324
TEST_TIME 50 9356
TEST_TIME 51 9282
TEST_TIME 52 9388
TEST_TIME 53 9392
TEST_TIME 54 9308
TEST_TIME 55 9403
TEST_TIME 56 9475
TEST_TIME 57 9503
TEST_TIME 58 9510
TEST_TIME 59 9550
TEST_TIME 60 9527
TEST_TIME 61 9543
TEST_TIME 62 9606
TEST_TIME 63 9555
TEST_TIME 64 9603
TEST_TIME 65 9692
TEST_TIME 66 9647
TEST_TIME 67 9612
TEST_TIME 68 9513
TEST_TIME 69 9491
TEST_TIME 70 9402
TEST_TIME 71 9501
TEST_TIME 72 9482
TEST_TIME 73 9429
TEST_TIME 74 9380
TEST_TIME 75 9341
TEST_TIME 76 9304
TEST_TIME 77 9253
TEST_TIME 78 9282
TEST_TIME 79 9316
TEST_TIME 80 9270
TEST_TIME 81 9282
TEST_TIME 82 9287
TEST_TIME 83 9357
TEST_TIME 84 9364
TEST_TIME 85 9332
TEST_TIME 86 9408
TEST_TIME 87 9400
TEST_TIME 88 9405
TEST_TIME 89 9401
TEST_TIME 90 9403
TEST_TIME 91 9417
TEST_TIME 92 9402
TEST_TIME 93 9307
TEST_TIME 94 9354
TEST_TIME 95 9320
TEST_TIME 96 9297
TEST_TIME 97 9316
TEST_TIME 98 9284
TEST_TIME 99 9263
TEST_TIME 100 9255
TEST_TIME 101 9228
TEST_TIME 102 9201
TEST_TIME 103 9272
TEST_TIME 104 9256
TEST_TIME 105 9237
TEST_TIME 106 9275
TEST_TIME 107 9316
TEST_TIME 108 9326
TEST_TIME 109 9355
TEST_TIME 110 9383
TEST_TIME 111 9364
TEST_TIME 112 9307
TEST_TIME 113 9267
TEST_TIME 114 9233
TEST_TIME 115 9193
TEST_TIME 116 9191
TEST_TIME 117 9194
TEST_TIME 118 9221
TEST_TIME 119 9277
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2021 16:39:28 to 09-08-2021 16:39:31.

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 1533319 1533319 0 0.00 0 40216 7 0 0
doc_list_biud 1533319 1533319 0 0.00 0 80779 28 0 0
sp_add_invoice_to_stock 5132 5016 116 2.26 27 226219 18893 0 116
sp_cancel_adding_invoice 5660 5660 0 0.00 5 94658 803 0 0
sp_cancel_client_order 1866 1866 0 0.00 4 14561 363 0 0
sp_cancel_customer_reserve 558704 558704 0 0.00 1 40826 59 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 2 7709 70 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 7172 86 0 0
sp_cancel_supplier_invoice 6419 6407 12 0.18 2 80810 907 0 12
sp_cancel_supplier_order 423 423 0 0.00 13 1112704 102347 0 0
sp_cancel_write_off 9915 9915 0 0.00 1 12254 119 0 0
sp_client_order 21009 21009 0 0.00 4 47444 383 0 0
sp_customer_reserve 460385 460385 0 0.00 0 151204 187 0 0
sp_fill_shopping_cart 32019 32019 0 0.00 1 15013 40 0 0
sp_fill_shopping_cart_clo_res 460375 460375 0 0.00 0 16445 14 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 20462 315 0 0
sp_kill_cost_storno 16734 16734 0 0.00 0 40207 67 0 0
sp_kill_qstorno_ret_qs2qd 1134153 1134153 0 0.00 0 80754 33 0 0
sp_kill_qty_storno 597865 597865 0 0.00 0 80769 71 0 0
sp_lock_selected_doc 20930 20930 0 0.00 0 10040 5 0 0
sp_make_cost_storno 27998 27998 0 0.00 0 13075 59 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 37708 30 0 0
sp_multiply_rows_for_qdistr 21009 21009 0 0.00 0 16999 159 0 0
sp_pay_from_customer 7250 7250 0 0.00 4 27445 159 0 0
sp_pay_to_supplier 5858 5858 0 0.00 5 37732 144 0 0
sp_payment_common 13108 13108 0 0.00 1 15064 40 0 0
sp_qd_handle_on_cancel_clo 1866 1866 0 0.00 0 14552 319 0 0
sp_qd_handle_on_invoice_upd_sts 10657 10657 0 0.00 0 33198 342 0 0
sp_qd_handle_on_reserve_upd_sts 9893 9893 0 0.00 0 20595 65 0 0
sp_reserve_write_off 9893 9893 0 0.00 6 20616 182 0 0
sp_supplier_invoice 5576 5576 0 0.00 6 89320 3426 0 0
sp_supplier_order 5442 5442 0 0.00 5 82807 1454 0 0
srv_aggregate_perf_data 107 107 0 0.00 3253 137006 18373 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1454 165537 47091 0 0
srv_find_qd_qs_mism 1058467 1058467 0 0.00 0 13392 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 36 92493 1158 0 0
srv_make_money_saldo 794 794 0 0.00 1 7023 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 219140 299368 274060 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 45049 211 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 7822 100 0 0
t$perf-norm:sp_cancel_client_order 1866 1866 0 0.00 0 2577 14 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 3606 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 2305 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 97 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 4256 31 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 34268 1735 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 10247 23 0 0
t$perf-norm:sp_client_order 21009 21009 0 0.00 0 9180 20 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 3592 10 0 0
t$perf-norm:sp_pay_from_customer 7250 7250 0 0.00 0 10133 18 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 9690 12 0 0
t$perf-norm:sp_reserve_write_off 9893 9893 0 0.00 0 8858 14 0 0
t$perf-norm:sp_supplier_invoice 5576 5576 0 0.00 0 8795 23 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 6634 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 427 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 186 4 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1733 21 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 6151 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 121 33 0 0
v_add_invoice_to_stock 10224 9992 232 2.26 0 27854 21 0 232
v_all_customers 38448 38448 0 0.00 0 92 1 0 0
v_all_suppliers 30816 30816 0 0.00 0 19 1 0 0
v_all_wares 42018 42018 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1086 9 0 0
v_cancel_client_order 3732 3732 0 0.00 0 9092 9 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 7691 11 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 1208 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 3091 12 0 0
v_cancel_supplier_invoice 884 860 24 2.71 0 5427 27 0 24
v_cancel_supplier_order 846 846 0 0.00 0 2991 9 0 0
v_cancel_write_off 2094 2094 0 0.00 0 2564 18 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 689 1 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 3077 19 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 5147 7 0 0
v_max_non_paid_invoice 1460 1460 0 0.00 0 821 3 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 7306 7 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 4859 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 2563 5 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 4856 8 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 7636 20 0 0
v_min_non_paid_realizn 7250 7250 0 0.00 0 15060 32 0 0
v_our_firm 10636 10636 0 0.00 0 16 1 0 0
v_reserve_write_off 19786 19786 0 0.00 0 4752 5 0 0
x_lock_depdocs_on_canc_invoice 11637 11637 0 0.00 0 12771 126 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 7118 369 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 1 82783 1375 0 0
x_make_qstorno_1200_2000 5576 5576 0 0.00 2 88169 3281 0 0
x_make_qstorno_2100_3300 460385 460385 0 0.00 0 151202 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2021 16:39:32 to 09-08-2021 16:39:36.

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
14:07:51 6 8192000000 8802632576 8814170112 8572016 0.104 2 1 2 0 8691424 56848 62560
14:08:52 25408 8192000000 10386297424 10706337792 1437830624 14.931 100 88 176 0 1471735520 6006192 27898704
14:10:22 19009 8192000000 10434251952 10760171520 1487002960 15.363 100 94 188 1 1525401264 8091696 30306608
14:11:54 18097 8192000000 10483727664 10824409088 1540989600 15.832 100 81 162 1 1575754256 9505472 25259184
14:13:15 11803 8192000000 10584271264 10945527808 1644877264 16.721 100 81 162 0 1679672016 10776096 24018656
14:14:32 7659 8192000000 10667223472 11036745728 1724566848 17.390 100 84 168 1 1762787600 13865536 24355216
14:15:43 4488 8192000000 10713447392 11090784256 1772586656 17.788 100 89 179 2 1811542560 13854336 25101568
14:16:50 10732 8192000000 10742369376 11125923840 1804651024 18.052 100 76 149 0 1839187168 13840064 20696080
14:18:03 1869 8192000000 10795156640 11178917888 1849434528 18.418 100 91 183 0 1888863728 13869248 25559952
14:19:06 2527 8192000000 10816313680 11212341248 1873452000 18.612 100 81 160 2 1912379376 16593680 22333696
14:20:11 6683 8192000000 10783800608 11204354048 1867650176 18.565 100 36 72 1 1887362800 10018560 9694064
14:21:21 3396 8192000000 10804748464 11223494656 1887797312 18.728 100 38 75 2 1905824832 7728800 10298720
14:22:26 61947 8192000000 10806993632 11236626432 1895700032 18.792 100 39 79 1 1913178064 6497328 10980704
14:24:30 6948 8192000000 10815415776 11247624192 1900564768 18.831 100 23 46 0 1913211424 5579952 7066704
14:25:37 8030 8192000000 10821727696 11249516544 1903046928 18.851 100 22 44 1 1915933232 5887504 6998800
14:26:47 49400 8192000000 10788776080 11212886016 1838052096 18.325 97 26 51 0 1851914096 5722784 8139216
14:28:37 5502 8192000000 10793335344 11210084352 1854207024 18.456 99 35 71 0 1870428096 6011744 10209328
14:29:45 6511 8192000000 10749002320 11165384704 1804158400 18.048 98 29 58 0 1819006896 6823920 8024576
14:30:51 12118 8192000000 10709185552 11130753024 1758800384 17.674 97 36 72 1 1775313952 5091200 11422368
14:32:07 40554 8192000000 10741589616 11150741504 1799820672 18.012 99 50 100 1 1820198784 4687760 15690352
14:33:49 8548 8192000000 10748782064 11164405760 1825275872 18.221 100 28 56 0 1839354528 5846128 8232528
14:34:58 14155 8192000000 10784296960 11211517952 1865521216 18.548 100 20 38 1 1876507552 5037904 5948432
14:36:13 9046 8192000000 10797852736 11225858048 1879348320 18.660 100 20 40 2 1891017056 6146800 5521936
14:37:22 81533 8192000000 10829134304 11258458112 1900466160 18.830 100 40 78 2 1917460064 5739376 11254528
14:39:50 33373 8192000000 10877266304 11305193472 1943014128 19.171 100 48 96 0 1972254656 16056432 13184096
14:41:26 14811 8192000000 10893965088 11326533632 1961247664 19.316 100 48 96 1 1989903440 14396992 14258784
14:42:45 32733 8192000000 10900969024 11340029952 1971966752 19.401 100 41 81 1 1999975360 16308256 11700352
14:44:22 9152 8192000000 10927846144 11365916672 1988312528 19.530 100 55 110 2 2027568048 23590512 15665008
14:45:41 3603 8192000000 10938518240 11354157056 1979620144 19.462 100 75 151 2 2023033776 20893936 22519696
14:46:58 9424 8192000000 10950750048 11378581504 1994152928 19.577 100 63 124 0 2039647856 26838208 18656720
14:48:12 8410 8192000000 10970633680 11408207872 2011347408 19.712 100 56 112 0 2056230880 27914368 16969104
14:49:32 5319 8192000000 10973642288 11404574720 1997246192 19.601 99 59 119 0 2046166528 31162528 17757808
14:50:41 3579 8192000000 10990654480 11413458944 2014125824 19.734 99 80 156 2 2071577888 33974960 23477104
14:51:50 3528 8192000000 10996728000 11415842816 2015914400 19.748 100 79 158 0 2078207824 38025920 24267504
14:52:59 1673 8192000000 11005377552 11416932352 2014097568 19.734 100 86 172 1 2080566240 39304288 27164384
14:54:03 1454 8192000000 10999825472 11419013120 1997789472 19.605 99 84 167 1 2068029696 43395056 26845168
14:55:11 2541 8192000000 10991529392 11407376384 1996129264 19.592 100 88 176 1 2069983808 46755680 27098864
14:56:17 6197 8192000000 10966184656 11405795328 1974483744 19.421 99 49 98 1 2040642480 50240992 15917744
14:57:30 2082 8192000000 10977574176 11408683008 1991975872 19.559 100 69 133 1 2063181952 48758544 22447536
14:58:38 3902 8192000000 10965998032 11403743232 1987846528 19.527 100 56 111 1 2054609120 49018496 17744096
14:59:46 17150 8192000000 10933349232 11389538304 1972152704 19.403 100 35 70 2 2026552672 42175280 12224688
15:01:07 19673 8192000000 10938275856 11398119424 1984116848 19.497 100 27 54 3 2029867440 36100816 9649776
15:02:30 19635 8192000000 10924741648 11378094080 1953458224 19.254 100 28 56 2 2007358320 44106416 9793680
15:04:02 48173 8192000000 10895792496 11366633472 1922541600 19.007 99 18 36 2 1968041648 39155344 6344704
15:05:51 53712 8192000000 10889004832 11328143360 1924334080 19.022 99 24 48 1 1941742496 10510432 6897984
15:07:55 88600 8192000000 10882524512 11340054528 1868176448 18.570 95 22 41 1 1881480656 7505968 5798240
15:10:26 27997 8192000000 10873702352 11331985408 1935745536 19.113 99 12 24 1 1946212448 7024144 3442768
15:11:56 37993 8192000000 10882470432 11333160960 1899970064 18.826 97 35 70 0 1918458864 8680608 9808192
15:13:35 16052 8192000000 10911571616 11350228992 1964530784 19.342 100 53 106 2 1994176720 14684240 14961696
15:14:59 165537 8192000000 10883394016 11364769792 1946583312 19.199 99 10 20 1 1965613808 15928176 3102320
15:18:46 59505 8192000000 10890192352 11363295232 1969929584 19.385 100 23 46 1 1987620560 10950880 6740096
15:20:46 128192 8192000000 10904133328 11380260864 1975491280 19.429 100 21 42 1 1993549600 11647344 6410976
15:24:05 45495 8192000000 10913956288 11383312384 1990019488 19.544 100 22 44 0 2007159456 10582832 6557136
15:25:57 59879 8192000000 10907844112 11386163200 1970187712 19.387 99 16 32 0 1987849248 12713312 4948224
15:27:58 65527 8192000000 10913686848 11387523072 1993292864 19.570 100 28 56 0 2013333728 12147280 7893584
15:31:36 52581 8192000000 10909471712 11378475008 1917924592 18.970 96 27 54 1 1944337040 18454656 7957792
15:33:33 56786 8192000000 10909603968 11380408320 1960474992 19.310 99 22 44 1 1979258432 12467952 6315488
15:35:31 117136 8192000000 10880922720 11342942208 1827892976 18.242 92 16 32 0 1849168256 16594240 4681040
15:38:28 52460 8192000000 10913997808 11370905600 1952987536 19.250 99 19 37 1 1972484432 14240656 5256240
15:41:24 119995 8192000000 10880510448 11353808896 1870387184 18.587 94 20 40 4 1890703376 14787648 5528544
15:44:27 108963 8192000000 10890000240 11367895040 1946551856 19.199 98 37 74 0 1972772064 15339888 10880320
15:47:17 73316 8192000000 10914096992 11394375680 1977999808 19.449 100 23 46 0 2011504816 26461280 7043728
15:49:30 35434 8192000000 10886126080 11357360128 1966368336 19.357 100 22 43 0 1981923392 9753264 5801792
15:51:22 29835 8192000000 10883037952 11350409216 1949076160 19.219 99 24 48 1 1964815648 9221680 6517808
15:52:55 49262 8192000000 10858143936 11336499200 1921394560 18.998 98 10 20 1 1935066112 10293168 3378384
15:56:11 59726 8192000000 10820537152 11294650368 1857166048 18.480 97 11 22 0 1868750640 8408848 3175744
15:58:17 62598 8192000000 10865527312 11338670080 1919628192 18.984 99 11 22 2 1932568976 9705904 3234880
16:00:39 56700 8192000000 10856109616 11328733184 1902813872 18.849 98 12 24 1 1920436352 13910400 3712080
16:02:37 53251 8192000000 10879951088 11353939968 1953464112 19.254 100 25 50 0 1971290048 10897472 6928464
16:04:31 32181 8192000000 10897071648 11357143040 1943358656 19.174 99 27 54 0 1960194800 9323856 7512288
16:06:03 37195 8192000000 10891563728 11350163456 1906883712 18.882 97 30 60 4 1926335328 11292752 8158864
16:07:44 108029 8192000000 10919114320 11400679424 1986078272 19.513 100 24 47 0 2011249488 18615632 6555584
16:10:33 49958 8192000000 10922022864 11386208256 1956922640 19.282 98 48 94 2 1985895328 16356288 12616400
16:12:23 46110 8192000000 10910937968 11394818048 1984025344 19.497 100 10 20 0 2003063440 16247008 2791088
16:14:13 53735 8192000000 10904242304 11404320768 1955161424 19.268 98 7 14 1 1970196752 12771056 2264272
16:16:08 40417 8192000000 10930734304 11401936896 1985178352 19.506 99 22 43 0 1998398032 7446496 5773184
16:17:57 29754 8192000000 10908229984 11373772800 1934972416 19.107 97 31 62 2 1953685360 10047024 8665920
16:19:31 68810 8192000000 10890307584 11379642368 1919278848 18.981 97 12 24 0 1939941424 17302736 3359840
16:21:40 75205 8192000000 10905037904 11376455680 1946248304 19.197 98 21 42 4 1965309104 12717072 6343728
16:24:02 57914 8192000000 10912576256 11390623744 1974070048 19.418 99 15 30 1 1990794624 12466080 4258496
16:26:00 45291 8192000000 10936994688 11408527360 1983896880 19.496 99 28 55 1 2006114256 14116960 8100416
16:27:46 31508 8192000000 10904998944 11369512960 1918615968 18.976 97 18 36 0 1937423552 13696880 5110704
16:29:18 110868 8192000000 10893624592 11366936576 1891166224 18.755 95 14 28 0 1908834832 13850896 3817712
16:32:09 52432 8192000000 10897097120 11378507776 1954016096 19.258 99 14 28 3 1979258672 21161904 4080672
16:34:50 65978 8192000000 10919199728 11401764864 1991660736 19.557 100 19 38 0 2021988400 24255760 6071904
16:37:05 56830 8192000000 9342344016 9630224384 491429168 5.659 25 4 8 1 499193680 6753824 1010688

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-08-2021 16:39:38 to 09-08-2021 16:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2021 16:39:54 to 09-08-2021 16:39:56.

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          240718
MON$OLDEST_ACTIVE               240719
MON$OLDEST_SNAPSHOT             240719
MON$NEXT_TRANSACTION            240719
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                       2018560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:430: 
:431: mscs-test-161.budzdorov.ru    Mon Aug  9 16:38:08 2021
:432:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:433: 
:434: 
:435: mscs-test-161.budzdorov.ru    Mon Aug  9 16:38:17 2021
:436:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:437: 
--- end of diff output ---

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

09.08.21 16:39:57. 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
2021-08-09 16:39:59 No severe PSQL-related problems occured
09.08.21 16:40:00. End of report.