Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2021 01: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 15973660 kB
MemAvailable 30679220 kB
Buffers 0 kB
Cached 15695124 kB
SwapCached 4800 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-10-07 01:37:53
DTS_END                         2021-10-07 01:37:53.4240
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/34804
TRN_ID                          245606
ATT_ID                          657
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: 9230.59 18707 1107671
customer order: creation 184.10 351 22092
customer order: refuse 15.08 334 1809
order to supplier: creation 48.48 1234 5817
order to supplier: removal 3.49 96043 419
invoice (draft): creation 46.86 3491 5623
invoice (draft): removal 50.28 813 6033
invoice accept: apply 43.40 18515 5208
invoice accept: cancel 45.33 817 5440
customer reserve: creation 4016.69 183 482003
customer reserve: removal 4477.08 59 537249
realization accept: apply 85.95 194 10314
realization accept: cancel 78.58 110 9429
payment from customer: creation 61.97 143 7436
payment from customer: removal 6.13 93 736
payment to supplier: creation 51.18 145 6141
payment to supplier: removal 3.33 85 399
service: total inventory turnovers 4.99 976 599
service: total monetary turnovers 6.75 24 810
service: aggregate perf. data 0.92 21220 110
service: refresh index statistics 0.03 229318 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2021 01:39:10 to 07-10-2021 01:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2144
TEST_TIME 2 3398
TEST_TIME 3 2960
TEST_TIME 4 3772
TEST_TIME 5 3871
TEST_TIME 6 4129
TEST_TIME 7 4570
TEST_TIME 8 4935
TEST_TIME 9 5174
TEST_TIME 10 5380
TEST_TIME 11 5459
TEST_TIME 12 5434
TEST_TIME 13 5634
TEST_TIME 14 5985
TEST_TIME 15 6080
TEST_TIME 16 6412
TEST_TIME 17 6551
TEST_TIME 18 6985
TEST_TIME 19 7366
TEST_TIME 20 7549
TEST_TIME 21 7810
TEST_TIME 22 8107
TEST_TIME 23 8136
TEST_TIME 24 8384
TEST_TIME 25 8288
TEST_TIME 26 8921
TEST_TIME 27 8987
TEST_TIME 28 9338
TEST_TIME 29 10153
TEST_TIME 30 10125
TEST_TIME 31 10232
TEST_TIME 32 10162
TEST_TIME 33 10114
TEST_TIME 34 9877
TEST_TIME 35 9878
TEST_TIME 36 9811
TEST_TIME 37 9867
TEST_TIME 38 9693
TEST_TIME 39 9718
TEST_TIME 40 9665
TEST_TIME 41 9607
TEST_TIME 42 9592
TEST_TIME 43 9617
TEST_TIME 44 9492
TEST_TIME 45 9463
TEST_TIME 46 9466
TEST_TIME 47 9374
TEST_TIME 48 9365
TEST_TIME 49 9397
TEST_TIME 50 9381
TEST_TIME 51 9465
TEST_TIME 52 9461
TEST_TIME 53 9532
TEST_TIME 54 9455
TEST_TIME 55 9407
TEST_TIME 56 9559
TEST_TIME 57 9573
TEST_TIME 58 9655
TEST_TIME 59 9684
TEST_TIME 60 9758
TEST_TIME 61 9736
TEST_TIME 62 9754
TEST_TIME 63 9685
TEST_TIME 64 9580
TEST_TIME 65 9629
TEST_TIME 66 9549
TEST_TIME 67 9625
TEST_TIME 68 9514
TEST_TIME 69 9441
TEST_TIME 70 9541
TEST_TIME 71 9447
TEST_TIME 72 9398
TEST_TIME 73 9374
TEST_TIME 74 9261
TEST_TIME 75 9248
TEST_TIME 76 9276
TEST_TIME 77 9284
TEST_TIME 78 9290
TEST_TIME 79 9271
TEST_TIME 80 9275
TEST_TIME 81 9317
TEST_TIME 82 9332
TEST_TIME 83 9410
TEST_TIME 84 9407
TEST_TIME 85 9436
TEST_TIME 86 9487
TEST_TIME 87 9489
TEST_TIME 88 9476
TEST_TIME 89 9393
TEST_TIME 90 9392
TEST_TIME 91 9363
TEST_TIME 92 9330
TEST_TIME 93 9278
TEST_TIME 94 9310
TEST_TIME 95 9302
TEST_TIME 96 9330
TEST_TIME 97 9319
TEST_TIME 98 9357
TEST_TIME 99 9329
TEST_TIME 100 9369
TEST_TIME 101 9370
TEST_TIME 102 9382
TEST_TIME 103 9392
TEST_TIME 104 9363
TEST_TIME 105 9373
TEST_TIME 106 9491
TEST_TIME 107 9470
TEST_TIME 108 9470
TEST_TIME 109 9415
TEST_TIME 110 9356
TEST_TIME 111 9350
TEST_TIME 112 9330
TEST_TIME 113 9283
TEST_TIME 114 9246
TEST_TIME 115 9292
TEST_TIME 116 9276
TEST_TIME 117 9249
TEST_TIME 118 9277
TEST_TIME 119 9248
TEST_TIME 120 9233

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2021 01:39:14 to 07-10-2021 01:39:17.

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 1553392 1553392 0 0.00 0 21207 7 0 0
doc_list_biud 1553380 1553380 0 0.00 0 89999 26 0 0
sp_add_invoice_to_stock 5332 5208 124 2.32 25 160429 18516 0 124
sp_cancel_adding_invoice 5440 5440 0 0.00 5 48931 818 0 0
sp_cancel_client_order 1809 1809 0 0.00 5 15334 335 0 0
sp_cancel_customer_reserve 537249 537249 0 0.00 1 90001 59 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 6648 93 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 3333 85 0 0
sp_cancel_supplier_invoice 6041 6033 8 0.13 1 14810 814 0 8
sp_cancel_supplier_order 419 419 0 0.00 7 950891 96044 0 0
sp_cancel_write_off 9429 9429 0 0.00 1 13523 110 0 0
sp_client_order 22092 22092 0 0.00 3 88354 352 0 0
sp_customer_reserve 482003 482003 0 0.00 0 41801 183 0 0
sp_fill_shopping_cart 33529 33529 0 0.00 1 10011 37 0 0
sp_fill_shopping_cart_clo_res 482000 482000 0 0.00 0 10154 14 0 0
sp_get_clo_for_invoice 5205 5205 0 0.00 0 27905 289 0 0
sp_kill_cost_storno 16004 16004 0 0.00 0 14579 55 0 0
sp_kill_qstorno_ret_qs2qd 1090379 1090379 0 0.00 0 89987 32 0 0
sp_kill_qty_storno 575887 575887 0 0.00 0 89999 70 0 0
sp_lock_selected_doc 21497 21497 0 0.00 0 6669 5 0 0
sp_make_cost_storno 29096 29096 0 0.00 0 20380 73 0 0
sp_multiply_rows_for_pdistr 29091 29091 0 0.00 0 7487 30 0 0
sp_multiply_rows_for_qdistr 22092 22092 0 0.00 0 88347 160 0 0
sp_pay_from_customer 7436 7436 0 0.00 4 21168 143 0 0
sp_pay_to_supplier 6141 6141 0 0.00 5 16483 145 0 0
sp_payment_common 13576 13576 0 0.00 1 20904 36 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 15320 305 0 0
sp_qd_handle_on_invoice_upd_sts 10639 10639 0 0.00 0 43458 342 0 0
sp_qd_handle_on_reserve_upd_sts 10309 10309 0 0.00 0 14914 60 0 0
sp_reserve_write_off 10314 10314 0 0.00 6 21262 194 0 0
sp_supplier_invoice 5623 5623 0 0.00 7 80058 3492 0 0
sp_supplier_order 5817 5817 0 0.00 6 30893 1235 0 0
srv_aggregate_perf_data 110 110 0 0.00 2785 232349 21221 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2225 179489 41892 0 0
srv_find_qd_qs_mism 1057280 1057280 0 0.00 0 17862 6 0 0
srv_make_invnt_saldo 599 599 0 0.00 31 63314 976 0 0
srv_make_money_saldo 810 810 0 0.00 0 1788 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 78266 386445 229319 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5208 5208 0 0.00 0 15035 166 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 8392 107 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 1586 10 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1430 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 795 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 210 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 975 17 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 18342 1496 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 1409 7 0 0
t$perf-norm:sp_client_order 22092 22092 0 0.00 0 8560 17 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 4009 13 0 0
t$perf-norm:sp_pay_from_customer 7436 7436 0 0.00 0 3193 8 0 0
t$perf-norm:sp_pay_to_supplier 6141 6141 0 0.00 0 10095 14 0 0
t$perf-norm:sp_reserve_write_off 10314 10314 0 0.00 0 5494 11 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 7354 17 0 0
t$perf-norm:sp_supplier_order 5817 5817 0 0.00 0 4699 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1328 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 6393 99 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 871 22 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 2581 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10638 10390 248 2.33 0 8356 21 0 248
v_all_customers 39864 39864 0 0.00 0 18 1 0 0
v_all_suppliers 31808 31808 0 0.00 0 20 1 0 0
v_all_wares 44184 44184 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1501 8 0 0
v_cancel_client_order 3618 3618 0 0.00 0 4703 8 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 5014 14 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2146 5 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 736 4 0 0
v_cancel_supplier_invoice 910 894 16 1.75 0 1310 18 0 16
v_cancel_supplier_order 838 838 0 0.00 0 4056 14 0 0
v_cancel_write_off 2070 2070 0 0.00 0 2347 15 0 0
v_max_id_clo_ord 5817 5817 0 0.00 0 262 1 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 8118 30 0 0
v_max_id_ord_sup 5620 5620 0 0.00 0 4104 4 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 885 4 0 0
v_max_non_paid_realizn 4105 4105 0 0.00 0 18071 9 0 0
v_min_id_clo_ord 5817 5817 0 0.00 0 5985 9 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 2201 5 0 0
v_min_id_ord_sup 5620 5620 0 0.00 0 7257 8 0 0
v_min_non_paid_invoice 6140 6140 0 0.00 0 7044 19 0 0
v_min_non_paid_realizn 7436 7436 0 0.00 0 7603 17 0 0
v_our_firm 10982 10982 0 0.00 0 17 1 0 0
v_reserve_write_off 20618 20618 0 0.00 0 3967 7 0 0
x_lock_depdocs_on_canc_invoice 11026 11026 0 0.00 0 16785 125 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 24671 403 0 0
x_make_qstorno_1000_1200 5817 5817 0 0.00 2 30875 1152 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 2 75793 3358 0 0
x_make_qstorno_2100_3300 482003 482003 0 0.00 0 41800 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2021 01:39:18 to 07-10-2021 01:39:22.

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
23:07:57 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:58 23040 8192000000 10378639808 10696880128 1420045936 14.773 100 87 175 0 1454209184 5539136 28624112
23:10:23 20337 8192000000 10412361760 10739113984 1469763472 15.212 100 89 178 0 1506408096 7823872 28820752
23:11:51 13523 8192000000 10465152128 10803957760 1526020064 15.702 100 91 181 0 1564362544 9528208 28814272
23:13:11 13812 8192000000 10548881808 10902376448 1601602640 16.353 100 79 158 1 1635763824 10559680 23601504
23:14:29 9560 8192000000 10629103440 10995265536 1690023984 17.102 100 84 168 1 1727318704 12748528 24546192
23:15:42 7485 8192000000 10687872352 11061342208 1748296624 17.587 100 80 161 1 1782642848 12136128 22210096
23:16:52 3443 8192000000 10735362480 11115524096 1795224032 17.975 100 89 178 0 1831767616 12407648 24135936
23:17:57 3139 8192000000 10776834912 11162841088 1840009088 18.341 100 84 166 0 1876908272 14238096 22661088
23:19:02 1559 8192000000 10824287152 11213537280 1883305760 18.692 100 91 182 1 1921910768 13780240 24824768
23:20:04 3070 8192000000 10798300352 11219824640 1881805920 18.680 100 36 72 1 1903509392 11355600 10347872
23:21:11 9213 8192000000 10799863264 11234402304 1899397936 18.821 100 20 42 0 1910285760 5076672 5811152
23:22:20 7863 8192000000 10822534864 11250323456 1906533216 18.879 100 23 46 0 1918907632 5853280 6521136
23:23:34 61655 8192000000 10821059936 11256102912 1906432112 18.878 100 16 32 0 1916714208 5515424 4766672
23:25:37 5476 8192000000 10847660320 11273560064 1923543136 19.015 100 32 61 3 1939780848 7189152 9048560
23:26:43 5026 8192000000 10833280304 11267203072 1918175280 18.972 100 37 74 1 1935787360 7373088 10238992
23:27:48 12330 8192000000 10816755744 11250577408 1889679968 18.743 99 23 47 0 1902291888 6084832 6527088
23:29:01 44440 8192000000 10781789504 11201204224 1752013888 17.618 92 30 60 2 1765904976 5125472 8765616
23:30:50 8038 8192000000 10707966752 11118546944 1732377600 17.455 96 23 45 1 1744428272 6127072 5923600
23:31:59 3928 8192000000 10724064576 11131830272 1774672000 17.806 98 31 64 0 1790890384 7164336 9054048
23:33:05 70646 8192000000 10740115968 11168595968 1814121776 18.130 99 30 60 5 1829021968 6683616 8216576
23:35:16 6728 8192000000 10772023312 11196297216 1856433216 18.474 100 29 56 0 1870610256 6079040 8098000
23:36:23 7799 8192000000 10795649888 11214585856 1871461248 18.596 100 24 48 1 1884581872 6497008 6623616
23:37:35 10587 8192000000 10813606736 11247104000 1901708880 18.840 100 16 32 2 1910435488 4449424 4277184
23:38:50 92950 8192000000 10855752512 11291504640 1933991312 19.099 100 39 78 0 1952313456 6523488 11798656
23:41:28 33564 8192000000 10885498688 11322159104 1954263456 19.260 100 34 66 1 1976051680 11896496 9891728
23:43:10 9721 8192000000 10913620592 11344855040 1976878256 19.440 100 53 105 2 2005357040 13000224 15478560
23:44:24 18009 8192000000 10940307472 11378343936 1997332208 19.602 100 55 110 1 2031696592 17376128 16988256
23:45:46 17880 8192000000 10945700624 11388305408 2003990720 19.654 100 45 88 1 2034998240 17550064 13457456
23:47:08 11547 8192000000 10929348880 11374850048 1995444784 19.587 100 44 88 0 2026477616 18253152 12779680
23:48:26 10788 8192000000 10974920288 11406471168 2017243824 19.758 100 62 124 0 2060390416 25149456 17997136
23:49:43 7051 8192000000 10976020992 11405037568 2008494448 19.690 99 55 110 4 2056654368 31532576 16627344
23:50:54 3729 8192000000 10984332400 11416002560 2022035632 19.796 100 61 120 1 2072942240 31282192 19624416
23:52:01 2809 8192000000 10993858800 11418275840 2020238928 19.782 100 75 150 2 2076771440 33151632 23380880
23:53:09 3188 8192000000 10979747888 11394777088 1997596320 19.604 100 90 179 3 2061917376 35753456 28567600
23:54:15 3327 8192000000 10962457888 11390730240 1990240480 19.546 100 59 118 4 2046934592 38266768 18427344
23:55:24 7284 8192000000 10950613328 11363184640 1946069040 19.195 99 75 150 1 2016821168 46947216 23804912
23:56:35 2222 8192000000 10948071568 11355860992 1950871744 19.233 100 85 171 1 2024466240 46287472 27307024
23:57:44 4992 8192000000 10927127872 11353214976 1947750576 19.209 100 70 138 2 2009683168 39982448 21950144
23:58:55 8666 8192000000 10952611808 11387408384 1976508176 19.437 100 50 100 0 2038991968 46492576 15991216
00:00:07 21895 8192000000 10919111696 11361468416 1940420064 19.150 99 35 70 2 1991934464 39861232 11653168
00:01:42 8442 8192000000 10942894288 11368517632 1951653888 19.240 100 47 94 2 2013879792 47059424 15166480
00:02:56 10164 8192000000 10909676016 11344318464 1924308208 19.021 99 33 66 0 1986170160 50531440 11330512
00:04:07 20592 8192000000 10885727104 11334111232 1928280464 19.053 100 18 37 2 1968159312 33986544 5892304
00:06:19 25105 8192000000 10833886480 11283259392 1879213184 18.659 99 22 44 0 1916495280 30018560 7263536
00:07:45 44148 8192000000 10878466768 11333791744 1936734832 19.121 100 10 20 2 1956952688 17470800 2747056
00:09:31 94415 8192000000 10869861968 11327664128 1921632160 19.000 99 17 34 1 1940633760 14527616 4473984
00:12:47 51305 8192000000 10887620672 11330580480 1905077120 18.867 97 19 38 0 1919656880 9539168 5040592
00:14:44 9492 8192000000 10883117072 11343667200 1964950544 19.345 100 34 68 1 1982707744 8015984 9741216
00:16:00 4743 8192000000 10914088672 11358584832 1972182816 19.403 100 32 64 2 1988985552 7687264 9115472
00:17:05 26538 8192000000 10898286992 11355336704 1970263456 19.388 100 27 53 1 1987112560 9247040 7602064
00:18:44 29834 8192000000 10909200016 11365265408 1982107248 19.481 100 44 87 0 2006913440 12283632 12522560
00:20:26 29160 8192000000 10900329808 11373170688 1981788240 19.479 100 12 24 2 2000712976 15017296 3907440
00:22:00 45918 8192000000 10907816240 11372146688 1971392528 19.396 99 10 20 0 1985424944 11377568 2654848
00:23:47 82040 8192000000 10929271696 11382181888 1997017248 19.599 100 35 70 1 2016315744 9659952 9638544
00:26:10 69695 8192000000 10926503696 11420106752 1999883104 19.622 100 7 15 0 2021172256 19733104 1556048
00:28:20 77888 8192000000 10914334736 11386499072 1953493040 19.254 98 11 21 0 1966202640 9613008 3096592
00:30:38 25551 8192000000 10916463120 11366129664 1945298128 19.189 98 39 78 1 1963011664 7058224 10655312
00:32:03 57602 8192000000 10914900096 11390386176 1970225584 19.387 99 24 48 0 1990346352 13319200 6801568
00:34:09 36331 8192000000 10940448224 11395944448 1971628944 19.398 99 36 72 1 2005846720 23956128 10261648
00:35:47 57706 8192000000 10890793536 11360264192 1935537648 19.111 99 11 22 1 1953888768 15042720 3308400
00:37:51 42390 8192000000 10912900224 11354828800 1931840944 19.082 98 33 65 0 1949958768 9568096 8549728
00:39:33 129830 8192000000 10872550416 11336957952 1853942560 18.454 94 26 52 2 1876950048 15679600 7327888
00:43:07 36277 8192000000 10884361312 11339870208 1896263536 18.796 97 37 74 1 1919260608 12248880 10748192
00:44:50 48414 8192000000 10876195344 11363717120 1953270240 19.253 100 5 10 0 1967959840 13305904 1383696
00:46:39 63752 8192000000 10885746864 11377119232 1952073808 19.243 99 12 24 1 1973989312 18282400 3633104
00:48:56 15127 8192000000 10879623648 11347816448 1917174608 18.964 97 33 66 0 1937590928 10489504 9926816
00:50:11 66337 8192000000 10843111216 11322322944 1905584832 18.871 98 19 38 1 1919357328 8295648 5476848
00:52:23 79293 8192000000 10872876544 11356864512 1915391504 18.950 98 8 16 0 1927367616 9696016 2280096
00:54:43 34462 8192000000 10884013552 11359072256 1947988192 19.210 99 18 36 0 1962687008 9850688 4848128
00:56:18 45984 8192000000 10898860912 11367649280 1938574240 19.135 98 22 44 0 1956778064 11776272 6427552
00:58:14 57115 8192000000 10903751184 11371687936 1920511552 18.991 97 25 50 1 1941546288 13474128 7560608
01:00:15 31738 8192000000 10867911552 11346866176 1925348304 19.030 98 15 30 0 1941629968 12070016 4211648
01:01:47 20191 8192000000 10883808576 11336605696 1932150800 19.084 99 43 86 0 1952989472 8816192 12022480
01:03:07 116622 8192000000 10891010784 11358384128 1960149216 19.307 100 24 48 1 1982888400 16022432 6716752
01:06:04 94692 8192000000 10879001632 11341746176 1886999552 18.722 96 26 52 0 1903888816 9618192 7271072
01:08:41 34033 8192000000 10883739520 11358531584 1945087568 19.187 99 16 32 0 1963335408 13826464 4421376
01:10:15 62640 8192000000 10892806768 11370536960 1940796480 19.153 98 11 22 0 1954681872 10819664 3065728
01:12:18 75294 8192000000 10917019024 11396644864 1989814000 19.542 100 12 24 0 2001523616 8219328 3490288
01:14:37 66081 8192000000 10898926864 11373838336 1931633680 19.080 97 29 58 1 1951424496 11243984 8546832
01:16:54 21325 8192000000 10926006464 11362402304 1939576768 19.143 98 38 76 1 1959175056 9150496 10447792
01:18:18 25889 8192000000 10897776160 11354365952 1952315344 19.245 99 31 62 1 1967311696 6976992 8019360
01:19:47 42451 8192000000 10903667776 11372744704 1963077584 19.330 99 32 64 0 1982786112 10988736 8719792
01:21:34 27398 8192000000 10868535008 11315732480 1806977088 18.071 92 48 94 0 1830795104 11191104 12626912
01:23:01 74453 8192000000 10854625792 11336163328 1898372880 18.813 98 8 16 0 1919468288 18827600 2267808
01:25:17 179489 8192000000 10861900672 11324989440 1908338624 18.893 98 23 46 1 1926846000 11986448 6520928
01:29:19 53208 8192000000 10856017904 11333013504 1924645264 19.024 99 9 18 0 1938322528 11170000 2507264
01:31:22 67697 8192000000 10853340704 11334017024 1914170672 18.940 99 12 23 1 1935666816 17999392 3496752
01:33:32 46738 8192000000 10845314288 11316842496 1874822096 18.623 97 19 38 1 1887110144 6366720 5921328
01:35:39 49104 8192000000 10880964640 11335483392 1890617616 18.751 97 29 58 2 1914709328 16219712 7872000
01:37:29 32375 8192000000 9840668256 10203799552 964014704 10.528 50 21 41 1 987188960 17161712 6012544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2021 01:39:24 to 07-10-2021 01:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 07-10-2021 01:39:40 to 07-10-2021 01:39:42.

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          245605
MON$OLDEST_ACTIVE               248183
MON$OLDEST_SNAPSHOT             248183
MON$NEXT_TRANSACTION            248183
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                       2025504
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:341: 
:342: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:08 2021
:343:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:344: 
:345: 
:346: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:08 2021
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:348: 
:349: 
:350: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:10 2021
:351:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:352: 
:353: 
:354: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:10 2021
:355:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:356: 
:357: 
:358: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:10 2021
:359:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:360: 
:361: 
:362: mscs-test-161.budzdorov.ru    Thu Oct  7 01:38:12 2021
:363:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:364: 
--- end of diff output ---

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

07.10.21 01:39:43. 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-10-07 01:39:46 No severe PSQL-related problems occured
07.10.21 01:39:47. End of report.