Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.2021 16:38
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 15904924 kB
MemAvailable 30860844 kB
Buffers 0 kB
Cached 15791052 kB
SwapCached 388 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-09-12 16:37:50
DTS_END                         2021-09-12 16:37:50.7530
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/50038
TRN_ID                          231972
ATT_ID                          645
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: 9181.67 20136 1101800
customer order: creation 162.86 392 19543
customer order: refuse 14.66 415 1759
order to supplier: creation 43.37 1392 5204
order to supplier: removal 3.66 114855 439
invoice (draft): creation 43.60 3852 5232
invoice (draft): removal 55.03 979 6603
invoice accept: apply 38.96 18946 4675
invoice accept: cancel 50.01 816 6001
customer reserve: creation 3602.34 183 432281
customer reserve: removal 4873.47 65 584816
realization accept: apply 80.56 222 9667
realization accept: cancel 90.18 111 10821
payment from customer: creation 55.59 148 6671
payment from customer: removal 5.43 117 652
payment to supplier: creation 46.58 161 5590
payment to supplier: removal 3.18 137 382
service: total inventory turnovers 4.88 1082 585
service: total monetary turnovers 6.53 41 783
service: aggregate perf. data 0.77 20918 92
service: refresh index statistics 0.03 237883 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-09-2021 16:39:14 to 12-09-2021 16:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4162
TEST_TIME 2 3592
TEST_TIME 3 3688
TEST_TIME 4 4590
TEST_TIME 5 4529
TEST_TIME 6 4686
TEST_TIME 7 4823
TEST_TIME 8 5391
TEST_TIME 9 5620
TEST_TIME 10 5451
TEST_TIME 11 6003
TEST_TIME 12 6187
TEST_TIME 13 6317
TEST_TIME 14 6295
TEST_TIME 15 6266
TEST_TIME 16 6560
TEST_TIME 17 6800
TEST_TIME 18 7066
TEST_TIME 19 7167
TEST_TIME 20 7456
TEST_TIME 21 7479
TEST_TIME 22 7746
TEST_TIME 23 7729
TEST_TIME 24 8051
TEST_TIME 25 8117
TEST_TIME 26 8409
TEST_TIME 27 8292
TEST_TIME 28 8865
TEST_TIME 29 10034
TEST_TIME 30 10168
TEST_TIME 31 11173
TEST_TIME 32 11054
TEST_TIME 33 11024
TEST_TIME 34 11017
TEST_TIME 35 10902
TEST_TIME 36 10685
TEST_TIME 37 10483
TEST_TIME 38 10238
TEST_TIME 39 10100
TEST_TIME 40 10043
TEST_TIME 41 9894
TEST_TIME 42 9952
TEST_TIME 43 9854
TEST_TIME 44 9844
TEST_TIME 45 9799
TEST_TIME 46 9885
TEST_TIME 47 9733
TEST_TIME 48 9703
TEST_TIME 49 9604
TEST_TIME 50 9578
TEST_TIME 51 9600
TEST_TIME 52 9555
TEST_TIME 53 9493
TEST_TIME 54 9373
TEST_TIME 55 9354
TEST_TIME 56 9332
TEST_TIME 57 9329
TEST_TIME 58 9302
TEST_TIME 59 9222
TEST_TIME 60 9157
TEST_TIME 61 9103
TEST_TIME 62 9113
TEST_TIME 63 9179
TEST_TIME 64 9189
TEST_TIME 65 9234
TEST_TIME 66 9267
TEST_TIME 67 9296
TEST_TIME 68 9316
TEST_TIME 69 9285
TEST_TIME 70 9415
TEST_TIME 71 9425
TEST_TIME 72 9472
TEST_TIME 73 9448
TEST_TIME 74 9452
TEST_TIME 75 9469
TEST_TIME 76 9368
TEST_TIME 77 9345
TEST_TIME 78 9351
TEST_TIME 79 9332
TEST_TIME 80 9286
TEST_TIME 81 9325
TEST_TIME 82 9283
TEST_TIME 83 9270
TEST_TIME 84 9220
TEST_TIME 85 9183
TEST_TIME 86 9121
TEST_TIME 87 9105
TEST_TIME 88 9177
TEST_TIME 89 9234
TEST_TIME 90 9190
TEST_TIME 91 9189
TEST_TIME 92 9274
TEST_TIME 93 9259
TEST_TIME 94 9287
TEST_TIME 95 9375
TEST_TIME 96 9346
TEST_TIME 97 9398
TEST_TIME 98 9411
TEST_TIME 99 9479
TEST_TIME 100 9455
TEST_TIME 101 9412
TEST_TIME 102 9489
TEST_TIME 103 9457
TEST_TIME 104 9405
TEST_TIME 105 9376
TEST_TIME 106 9356
TEST_TIME 107 9331
TEST_TIME 108 9275
TEST_TIME 109 9240
TEST_TIME 110 9229
TEST_TIME 111 9269
TEST_TIME 112 9269
TEST_TIME 113 9266
TEST_TIME 114 9238
TEST_TIME 115 9209
TEST_TIME 116 9140
TEST_TIME 117 9178
TEST_TIME 118 9208
TEST_TIME 119 9214
TEST_TIME 120 9188

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-09-2021 16:39:18 to 12-09-2021 16:39:21.

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 1503631 1503631 0 0.00 0 20323 7 0 0
doc_list_biud 1503631 1503631 0 0.00 0 18768 33 0 0
sp_add_invoice_to_stock 4799 4675 124 2.58 41 212964 18947 0 124
sp_cancel_adding_invoice 6001 6001 0 0.00 5 54251 817 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 11404 416 0 0
sp_cancel_customer_reserve 584816 584816 0 0.00 1 18769 66 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 7712 118 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 10016 137 0 0
sp_cancel_supplier_invoice 6605 6603 2 0.03 1 18733 980 0 2
sp_cancel_supplier_order 439 439 0 0.00 6 1259833 114855 0 0
sp_cancel_write_off 10821 10821 0 0.00 1 14213 111 0 0
sp_client_order 19543 19543 0 0.00 4 25227 393 0 0
sp_customer_reserve 432281 432281 0 0.00 0 35437 184 0 0
sp_fill_shopping_cart 29977 29977 0 0.00 1 21401 46 0 0
sp_fill_shopping_cart_clo_res 432272 432272 0 0.00 0 13534 13 0 0
sp_get_clo_for_invoice 4665 4665 0 0.00 0 34133 365 0 0
sp_kill_cost_storno 17856 17856 0 0.00 0 18114 65 0 0
sp_kill_qstorno_ret_qs2qd 1187495 1187495 0 0.00 0 18741 36 0 0
sp_kill_qty_storno 624771 624771 0 0.00 0 18767 78 0 0
sp_lock_selected_doc 19915 19915 0 0.00 0 9271 5 0 0
sp_make_cost_storno 26593 26593 0 0.00 0 20256 70 0 0
sp_multiply_rows_for_pdistr 26593 26593 0 0.00 0 13801 36 0 0
sp_multiply_rows_for_qdistr 19543 19543 0 0.00 0 12882 164 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 19786 148 0 0
sp_pay_to_supplier 5590 5590 0 0.00 4 20140 162 0 0
sp_payment_common 12261 12261 0 0.00 1 10901 37 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 11397 380 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 15761 396 0 0
sp_qd_handle_on_reserve_upd_sts 9667 9667 0 0.00 0 13867 77 0 0
sp_reserve_write_off 9667 9667 0 0.00 6 20425 223 0 0
sp_supplier_invoice 5232 5232 0 0.00 8 94472 3853 0 0
sp_supplier_order 5204 5204 0 0.00 5 50685 1393 0 0
srv_aggregate_perf_data 92 92 0 0.00 3374 201572 20919 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1159 122624 42789 0 0
srv_find_qd_qs_mism 1057556 1057556 0 0.00 0 15667 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 34 70790 1083 0 0
srv_make_money_saldo 783 783 0 0.00 1 8830 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 147032 336842 237884 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4675 4675 0 0.00 0 15788 216 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 6175 121 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 9185 22 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 4021 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 2705 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 2006 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2393 28 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 30245 1972 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 5164 19 0 0
t$perf-norm:sp_client_order 19543 19543 0 0.00 0 9465 25 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 4453 24 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 5515 12 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 5501 17 0 0
t$perf-norm:sp_reserve_write_off 9667 9667 0 0.00 0 5450 12 0 0
t$perf-norm:sp_supplier_invoice 5232 5232 0 0.00 0 7557 27 0 0
t$perf-norm:sp_supplier_order 5204 5204 0 0.00 0 5175 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 467 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1109 18 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1210 35 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 2320 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 9 5 0 0
v_add_invoice_to_stock 9578 9330 248 2.58 0 5690 21 0 248
v_all_customers 35776 35776 0 0.00 0 30 1 0 0
v_all_suppliers 28974 28974 0 0.00 0 22 1 0 0
v_all_wares 39086 39086 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 1929 12 0 0
v_cancel_client_order 3518 3518 0 0.00 0 2497 6 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 3267 8 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1476 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 6323 13 0 0
v_cancel_supplier_invoice 810 806 4 0.49 0 2776 22 0 4
v_cancel_supplier_order 878 878 0 0.00 0 8801 19 0 0
v_cancel_write_off 1826 1826 0 0.00 0 5020 17 0 0
v_max_id_clo_ord 5204 5204 0 0.00 0 801 1 0 0
v_max_id_clo_res 928 928 0 0.00 0 1614 10 0 0
v_max_id_ord_sup 5230 5230 0 0.00 0 4488 4 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 606 2 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 5513 4 0 0
v_min_id_clo_ord 5204 5204 0 0.00 0 5345 10 0 0
v_min_id_clo_res 928 928 0 0.00 0 4240 19 0 0
v_min_id_ord_sup 5230 5230 0 0.00 0 6401 10 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 10894 24 0 0
v_min_non_paid_realizn 6671 6671 0 0.00 0 10048 28 0 0
v_our_firm 9654 9654 0 0.00 0 14 1 0 0
v_reserve_write_off 19332 19332 0 0.00 0 8850 9 0 0
x_lock_depdocs_on_canc_invoice 12201 12201 0 0.00 0 27791 135 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 24964 543 0 0
x_make_qstorno_1000_1200 5204 5204 0 0.00 1 50575 1287 0 0
x_make_qstorno_1200_2000 5232 5232 0 0.00 3 94399 3696 0 0
x_make_qstorno_2100_3300 432281 432281 0 0.00 0 31588 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 16:39:22 to 12-09-2021 16:39:26.

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:54 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:55 24757 8192000000 10374606416 10694475776 1423908672 14.807 100 88 176 0 1457966944 5637936 28420336
14:10:25 19240 8192000000 10442467760 10773577728 1497610768 15.455 100 91 182 0 1534913056 8426256 28876032
14:11:49 16583 8192000000 10488588368 10830991360 1536900720 15.797 100 75 150 1 1569336528 9322704 23113104
14:13:12 11868 8192000000 10568956464 10926514176 1627682944 16.575 100 74 148 0 1661351504 11412672 22255888
14:14:26 14887 8192000000 10638277312 11006337024 1701001040 17.193 100 84 168 1 1737570480 12024112 24545328
14:15:44 5645 8192000000 10692425504 11066195968 1754796080 17.641 100 78 156 0 1789124464 11782160 22546224
14:16:54 7791 8192000000 10733433840 11121328128 1800441360 18.018 100 78 156 0 1835780432 13435280 21903792
14:18:04 1764 8192000000 10796930144 11181563904 1853298096 18.449 100 94 189 1 1893306784 13726784 26281904
14:19:07 1856 8192000000 10820180560 11206234112 1873868880 18.616 100 95 190 4 1913988128 12131968 27987280
14:20:10 1197 8192000000 10824453840 11219828736 1882700432 18.687 100 90 183 3 1919469776 10077760 26691584
14:21:12 6252 8192000000 10794357856 11222577152 1890375328 18.749 100 40 81 1 1908643472 6639984 11628160
14:22:23 7044 8192000000 10811833440 11242024960 1902771056 18.849 100 20 41 0 1914871360 6008496 6091808
14:23:32 54302 8192000000 10837553360 11255070720 1913342800 18.933 100 47 93 2 1933323808 6089296 13891712
14:25:30 5235 8192000000 10830124912 11266576384 1916063632 18.955 100 29 58 0 1930615712 6082368 8469712
14:26:38 9606 8192000000 10839002528 11275972608 1923972960 19.019 100 20 40 0 1935316992 5877488 5466544
14:27:49 6519 8192000000 10819992192 11262631936 1914359216 18.942 100 32 65 0 1930286224 6316624 9610384
14:28:56 18092 8192000000 10809374768 11220353024 1834599648 18.297 97 50 101 1 1856040496 7209680 14231168
14:30:18 10585 8192000000 10695455312 11106459648 1715900416 17.318 94 19 38 0 1726554768 4725680 5928672
14:31:31 10413 8192000000 10688225648 11100753920 1674662384 16.972 93 21 40 3 1689608048 9298384 5647280
14:32:44 12038 8192000000 10700890128 11120017408 1771927184 17.783 99 10 20 0 1779899136 5342752 2629200
14:33:57 22476 8192000000 10721894672 11147079680 1816791040 18.151 100 43 86 1 1836394080 7045952 12557088
14:35:23 7574 8192000000 10753307456 11181252608 1844562784 18.378 100 30 58 0 1859138528 5630896 8944848
14:36:32 11436 8192000000 10784779664 11207528448 1857450976 18.483 100 18 36 1 1867997072 5157104 5388992
14:37:44 7736 8192000000 10808669920 11236737024 1891454672 18.758 100 13 26 0 1899922272 5252800 3214800
14:39:04 39958 8192000000 10847456400 11273347072 1918892704 18.978 100 45 90 1 1939714784 8431504 12390576
14:40:53 11663 8192000000 10874195200 11300544512 1941571056 19.159 100 62 123 0 1971881344 12877264 17433024
14:42:09 17058 8192000000 10893369344 11322241024 1957014544 19.282 100 39 78 0 1980156048 10934880 12206624
14:43:33 16606 8192000000 10913105600 11346903040 1981983424 19.480 100 48 96 1 2009128096 13321728 13822944
14:44:56 6521 8192000000 10930714336 11358998528 1986580464 19.517 100 53 105 0 2018972352 16467440 15924448
14:46:05 27528 8192000000 10935388736 11379220480 1995034320 19.584 100 35 70 1 2028972640 22717776 11220544
14:47:37 5776 8192000000 10964538288 11397648384 2011931248 19.717 100 60 120 1 2055740432 25780400 18028784
14:48:48 6729 8192000000 10984972800 11414253568 2026077664 19.828 100 55 111 0 2068738128 26119408 16541056
14:50:01 12928 8192000000 10961534080 11392630784 1999922240 19.622 100 63 126 1 2048764448 29847008 18995200
14:51:21 11282 8192000000 10961398080 11383009280 1989160608 19.537 100 71 142 4 2042640304 32011728 21467968
14:53:00 2896 8192000000 10981022784 11390398464 1991281744 19.554 100 88 173 2 2057820096 40314496 26223856
14:54:11 5981 8192000000 10972422224 11392700416 1981328032 19.475 99 76 152 2 2052484192 46964080 24192080
14:55:23 3903 8192000000 10935284832 11351400448 1949151456 19.220 99 74 149 2 2016835488 44171504 23512528
14:56:31 2046 8192000000 10947137856 11348971520 1949815920 19.225 100 90 180 1 2024634960 45910448 28908592
14:57:39 1157 8192000000 10954032448 11357802496 1944459344 19.182 99 91 183 0 2021879216 49114880 28304992
14:58:47 2628 8192000000 10978460832 11394244608 1982149440 19.482 100 80 157 1 2057837008 50663776 25023792
14:59:57 7481 8192000000 10968923120 11401482240 1968866432 19.376 99 55 111 0 2040731632 53280896 18584304
15:01:09 2275 8192000000 10987475264 11397623808 1977165520 19.442 100 84 168 1 2062300560 58454576 26680464
15:02:17 6013 8192000000 10982039520 11407818752 1983319952 19.491 100 68 136 2 2062786400 57243024 22223424
15:03:28 17252 8192000000 10973656784 11416928256 1977011472 19.441 100 34 68 3 2055104544 65458704 12634368
15:05:03 7913 8192000000 10967329328 11412647936 1939344880 19.142 99 37 74 1 2037405856 83549696 14511280
15:06:22 11214 8192000000 10963674704 11418886144 1965013440 19.346 100 35 72 2 2047091712 69505840 12572432
15:07:33 28447 8192000000 10939827504 11402780672 1943475808 19.174 99 21 42 1 1998847392 48133040 7238544
15:09:03 63707 8192000000 10890841136 11338162176 1952018576 19.243 100 22 44 1 1969760880 11208320 6533984
15:11:09 19263 8192000000 10893552640 11356598272 1964572960 19.342 100 14 28 0 1977821616 9376080 3872576
15:12:41 89864 8192000000 10894892448 11347640320 1954842048 19.265 100 15 30 1 1969014144 10099664 4072432
15:15:27 49222 8192000000 10908750976 11348725760 1964765696 19.344 100 40 82 0 1985893888 10062240 11065952
15:17:19 87109 8192000000 10910028112 11375886336 1952040528 19.243 99 14 28 0 1972251664 16587760 3623376
15:19:49 89845 8192000000 10924199408 11388063744 1971789952 19.400 99 15 30 0 1990754528 14805328 4159248
15:22:19 70301 8192000000 10928365536 11403902976 2002950608 19.646 100 30 60 1 2029643536 18122400 8570528
15:24:31 22850 8192000000 10928438576 11393536000 1998727008 19.613 100 19 37 2 2013690144 9624864 5338272
15:25:55 28627 8192000000 10925597056 11384987648 1978835296 19.455 99 41 78 3 2002639104 11531584 12272224
15:27:28 67055 8192000000 10889149696 11374571520 1937883840 19.130 98 7 14 0 1957522320 17342000 2296480
15:29:36 76124 8192000000 10893699360 11377274880 1970407904 19.389 100 13 26 1 1984283024 9650416 4224704
15:32:56 51227 8192000000 10899745136 11365318656 1930422432 19.070 97 23 46 2 1953143920 15645680 7075808
15:34:56 83884 8192000000 10859285216 11341656064 1859577488 18.500 94 9 18 0 1870371168 8183760 2609920
15:38:12 35386 8192000000 10896814960 11360362496 1962097024 19.323 99 23 46 0 1977705600 8717840 6890736
15:39:50 57881 8192000000 10906764704 11377242112 1969143312 19.379 99 16 31 0 1985678272 12492784 4042176
15:41:56 81944 8192000000 10908440832 11388657664 1934121200 19.100 97 13 26 0 1951700784 14017360 3562224
15:44:24 37820 8192000000 10903999568 11379245056 1946918800 19.202 98 26 52 0 1965360608 10950592 7491216
15:46:10 19652 8192000000 10910388800 11369811968 1958158976 19.291 99 34 67 0 1977012192 9468800 9384416
15:47:30 122624 8192000000 10892712288 11369734144 1862249456 18.522 94 13 26 1 1879199600 13661888 3288256
15:50:46 50648 8192000000 10859782864 11317178368 1821831344 18.193 92 16 32 0 1837887904 11612256 4444304
15:52:40 95227 8192000000 10898058448 11347542016 1924567664 19.023 98 41 82 1 1945103840 8077456 12458720
15:55:39 59904 8192000000 10865396992 11342389248 1915828144 18.953 98 21 42 0 1930368432 8843344 5696944
15:57:54 60313 8192000000 10862621936 11346448384 1928911184 19.058 99 7 14 1 1939314400 8685472 1717744
16:00:05 31743 8192000000 10856556400 11325026304 1912217552 18.924 98 19 38 0 1929560480 11834368 5508560
16:01:38 64656 8192000000 10864212496 11351805952 1927783888 19.049 98 12 23 0 1941474368 10035008 3655472
16:03:43 75395 8192000000 10878384048 11356090368 1914872848 18.946 98 23 46 2 1948441936 26422576 7146512
16:06:06 104289 8192000000 10870838816 11342442496 1870906128 18.592 96 10 20 1 1902132432 28258080 2968224
16:08:50 54794 8192000000 10869875904 11352035328 1922030352 19.003 98 15 30 0 1939867456 13845008 3992096
16:10:56 40026 8192000000 10896787072 11374804992 1965518096 19.350 100 10 20 0 1985205216 16628816 3058304
16:12:58 66774 8192000000 10917338768 11376807936 1933248720 19.093 97 24 48 1 1949414832 9940000 6226112
16:15:07 34788 8192000000 10908030688 11372335104 1941101072 19.156 98 26 52 1 1958871360 10721120 7049168
16:16:47 63358 8192000000 10898037920 11384643584 1969154816 19.379 100 11 22 0 1986334784 13758816 3421152
16:18:53 47970 8192000000 10897358368 11378610176 1952366080 19.245 99 26 52 2 1974775680 14975456 7434144
16:20:42 71972 8192000000 10892566064 11367985152 1966995520 19.362 100 19 38 1 1988160464 15219312 5945632
16:23:11 37645 8192000000 10883061136 11366965248 1969392784 19.381 100 9 18 0 1983381952 11430608 2558560
16:24:49 61521 8192000000 10878142608 11355947008 1943777120 19.177 99 9 18 1 1954661632 8454960 2429552
16:26:52 82324 8192000000 10873212144 11357036544 1919044672 18.979 98 20 40 1 1944029152 18501120 6483360
16:29:19 76366 8192000000 10886642640 11371151360 1963881424 19.337 100 13 26 1 1981400400 13982896 3536080
16:31:40 66754 8192000000 10865941728 11337957376 1916010048 18.955 97 19 38 0 1931966304 10043696 5912560
16:33:55 55086 8192000000 10855331408 11334627328 1911655968 18.920 98 12 24 2 1930148880 14671216 3821696
16:35:50 62216 8192000000 10869354768 11342729216 1901174864 18.836 97 7 14 0 1921739568 18544240 2020464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-09-2021 16:39:28 to 12-09-2021 16:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 16:39:44 to 12-09-2021 16:39:46.

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          230635
MON$OLDEST_ACTIVE               232099
MON$OLDEST_SNAPSHOT             232099
MON$NEXT_TRANSACTION            232099
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                       2055392
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Sun Sep 12 16:37:52 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Sep 12 16:37:55 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Sun Sep 12 16:37:55 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Sep 12 16:38:00 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sun Sep 12 16:38:00 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
--- end of diff output ---

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

12.09.21 16:39:47. 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-09-12 16:39:49 No severe PSQL-related problems occured
12.09.21 16:39:50. End of report.