Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.08.2021 19: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 16009284 kB
MemAvailable 30884260 kB
Buffers 0 kB
Cached 15640088 kB
SwapCached 324 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-30 19:37:47
DTS_END                         2021-08-30 19:37:47.0530
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/44208
TRN_ID                          246994
ATT_ID                          697
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: 9380.42 18099 1125650
customer order: creation 183.87 340 22064
customer order: refuse 15.15 313 1818
order to supplier: creation 47.90 1189 5748
order to supplier: removal 3.50 90485 420
invoice (draft): creation 47.63 3229 5715
invoice (draft): removal 52.55 727 6306
invoice accept: apply 43.64 17586 5237
invoice accept: cancel 47.63 809 5715
customer reserve: creation 3982.13 177 477856
customer reserve: removal 4652.49 53 558299
realization accept: apply 87.07 173 10448
realization accept: cancel 81.43 106 9772
payment from customer: creation 61.97 119 7436
payment from customer: removal 5.97 108 716
payment to supplier: creation 51.71 132 6205
payment to supplier: removal 3.63 115 435
service: total inventory turnovers 4.94 1220 593
service: total monetary turnovers 6.42 42 770
service: aggregate perf. data 0.78 23424 93
service: refresh index statistics 0.03 221639 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-08-2021 19:38:41 to 30-08-2021 19:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2667
TEST_TIME 2 3515
TEST_TIME 3 3038
TEST_TIME 4 4022
TEST_TIME 5 4027
TEST_TIME 6 4338
TEST_TIME 7 4507
TEST_TIME 8 5058
TEST_TIME 9 5243
TEST_TIME 10 5406
TEST_TIME 11 5353
TEST_TIME 12 5773
TEST_TIME 13 5806
TEST_TIME 14 5973
TEST_TIME 15 6198
TEST_TIME 16 6397
TEST_TIME 17 6418
TEST_TIME 18 6695
TEST_TIME 19 7211
TEST_TIME 20 7584
TEST_TIME 21 7968
TEST_TIME 22 7903
TEST_TIME 23 8096
TEST_TIME 24 8522
TEST_TIME 25 8803
TEST_TIME 26 9020
TEST_TIME 27 9159
TEST_TIME 28 9993
TEST_TIME 29 10214
TEST_TIME 30 10029
TEST_TIME 31 10097
TEST_TIME 32 10235
TEST_TIME 33 10065
TEST_TIME 34 9962
TEST_TIME 35 10023
TEST_TIME 36 9842
TEST_TIME 37 9896
TEST_TIME 38 9947
TEST_TIME 39 9882
TEST_TIME 40 9751
TEST_TIME 41 9825
TEST_TIME 42 9845
TEST_TIME 43 9971
TEST_TIME 44 9902
TEST_TIME 45 9990
TEST_TIME 46 9968
TEST_TIME 47 9821
TEST_TIME 48 9734
TEST_TIME 49 9704
TEST_TIME 50 9779
TEST_TIME 51 9649
TEST_TIME 52 9628
TEST_TIME 53 9563
TEST_TIME 54 9556
TEST_TIME 55 9601
TEST_TIME 56 9699
TEST_TIME 57 9626
TEST_TIME 58 9600
TEST_TIME 59 9527
TEST_TIME 60 9450
TEST_TIME 61 9497
TEST_TIME 62 9445
TEST_TIME 63 9450
TEST_TIME 64 9444
TEST_TIME 65 9492
TEST_TIME 66 9458
TEST_TIME 67 9512
TEST_TIME 68 9439
TEST_TIME 69 9582
TEST_TIME 70 9547
TEST_TIME 71 9612
TEST_TIME 72 9588
TEST_TIME 73 9649
TEST_TIME 74 9648
TEST_TIME 75 9691
TEST_TIME 76 9625
TEST_TIME 77 9527
TEST_TIME 78 9554
TEST_TIME 79 9525
TEST_TIME 80 9464
TEST_TIME 81 9437
TEST_TIME 82 9391
TEST_TIME 83 9323
TEST_TIME 84 9314
TEST_TIME 85 9295
TEST_TIME 86 9348
TEST_TIME 87 9367
TEST_TIME 88 9352
TEST_TIME 89 9354
TEST_TIME 90 9336
TEST_TIME 91 9360
TEST_TIME 92 9359
TEST_TIME 93 9470
TEST_TIME 94 9473
TEST_TIME 95 9443
TEST_TIME 96 9445
TEST_TIME 97 9448
TEST_TIME 98 9464
TEST_TIME 99 9496
TEST_TIME 100 9526
TEST_TIME 101 9528
TEST_TIME 102 9462
TEST_TIME 103 9442
TEST_TIME 104 9410
TEST_TIME 105 9369
TEST_TIME 106 9366
TEST_TIME 107 9347
TEST_TIME 108 9311
TEST_TIME 109 9303
TEST_TIME 110 9286
TEST_TIME 111 9289
TEST_TIME 112 9361
TEST_TIME 113 9337
TEST_TIME 114 9321
TEST_TIME 115 9302
TEST_TIME 116 9336
TEST_TIME 117 9396
TEST_TIME 118 9391
TEST_TIME 119 9413
TEST_TIME 120 9383

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-08-2021 19:38:45 to 30-08-2021 19:38:48.

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 1569522 1569522 0 0.00 0 21061 6 0 0
doc_list_biud 1569522 1569522 0 0.00 0 17850 24 0 0
sp_add_invoice_to_stock 5397 5237 160 2.96 32 200913 17587 0 160
sp_cancel_adding_invoice 5715 5715 0 0.00 6 58275 810 0 0
sp_cancel_client_order 1818 1818 0 0.00 4 8761 314 0 0
sp_cancel_customer_reserve 558299 558299 0 0.00 1 17856 54 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 7958 109 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 8326 116 0 0
sp_cancel_supplier_invoice 6318 6306 12 0.18 1 12403 727 0 12
sp_cancel_supplier_order 420 420 0 0.00 10 1188734 90485 0 0
sp_cancel_write_off 9772 9772 0 0.00 1 9504 106 0 0
sp_client_order 22064 22064 0 0.00 4 22664 341 0 0
sp_customer_reserve 477856 477856 0 0.00 0 99724 177 0 0
sp_fill_shopping_cart 33510 33510 0 0.00 1 11429 36 0 0
sp_fill_shopping_cart_clo_res 477850 477850 0 0.00 0 20845 13 0 0
sp_get_clo_for_invoice 5231 5231 0 0.00 0 23907 248 0 0
sp_kill_cost_storno 16638 16638 0 0.00 0 7953 54 0 0
sp_kill_qstorno_ret_qs2qd 1133096 1133096 0 0.00 0 17835 29 0 0
sp_kill_qty_storno 598009 598009 0 0.00 0 17848 63 0 0
sp_lock_selected_doc 21740 21740 0 0.00 0 8624 6 0 0
sp_make_cost_storno 29320 29320 0 0.00 0 13425 52 0 0
sp_multiply_rows_for_pdistr 29320 29320 0 0.00 0 6692 27 0 0
sp_multiply_rows_for_qdistr 22064 22064 0 0.00 0 13531 146 0 0
sp_pay_from_customer 7436 7436 0 0.00 4 19462 120 0 0
sp_pay_to_supplier 6205 6205 0 0.00 5 19042 133 0 0
sp_payment_common 13641 13641 0 0.00 2 12866 26 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 8756 288 0 0
sp_qd_handle_on_invoice_upd_sts 10946 10946 0 0.00 0 10077 281 0 0
sp_qd_handle_on_reserve_upd_sts 10448 10448 0 0.00 0 17607 49 0 0
sp_reserve_write_off 10448 10448 0 0.00 6 17652 174 0 0
sp_supplier_invoice 5715 5715 0 0.00 6 75954 3230 0 0
sp_supplier_order 5748 5748 0 0.00 6 29463 1189 0 0
srv_aggregate_perf_data 93 93 0 0.00 2824 333006 23425 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2586 123079 46297 0 0
srv_find_qd_qs_mism 1076381 1076381 0 0.00 0 21057 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 40 134365 1221 0 0
srv_make_money_saldo 770 770 0 0.00 1 10909 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 122951 309312 221639 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 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 5238 5238 0 0.00 0 14768 161 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 11445 137 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 1955 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 3624 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 1426 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 923 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 787 15 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 40256 1579 0 0
t$perf-norm:sp_cancel_write_off 1101 1101 0 0.00 0 1534 5 0 0
t$perf-norm:sp_client_order 22064 22064 0 0.00 0 10651 19 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 916 3 0 0
t$perf-norm:sp_pay_from_customer 7436 7436 0 0.00 0 10657 11 0 0
t$perf-norm:sp_pay_to_supplier 6205 6205 0 0.00 0 6438 11 0 0
t$perf-norm:sp_reserve_write_off 10448 10448 0 0.00 0 11820 14 0 0
t$perf-norm:sp_supplier_invoice 5715 5715 0 0.00 0 6739 16 0 0
t$perf-norm:sp_supplier_order 5748 5748 0 0.00 0 4532 19 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 492 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 236 4 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 3612 32 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 3165 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10782 10462 320 2.96 0 13022 23 0 320
v_all_customers 40028 40028 0 0.00 0 222 1 0 0
v_all_suppliers 31918 31918 0 0.00 0 221 1 0 0
v_all_wares 44128 44128 0 0.00 0 224 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 988 7 0 0
v_cancel_client_order 3636 3636 0 0.00 0 1323 4 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 4716 15 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1351 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 4595 17 0 0
v_cancel_supplier_invoice 830 806 24 2.89 0 4594 24 0 24
v_cancel_supplier_order 840 840 0 0.00 0 12151 20 0 0
v_cancel_write_off 2202 2202 0 0.00 0 2479 15 0 0
v_max_id_clo_ord 5748 5748 0 0.00 0 2365 2 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 1714 15 0 0
v_max_id_ord_sup 5698 5698 0 0.00 0 10182 9 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 346 2 0 0
v_max_non_paid_realizn 3926 3926 0 0.00 0 592 2 0 0
v_min_id_clo_ord 5748 5748 0 0.00 0 7400 6 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 6167 12 0 0
v_min_id_ord_sup 5698 5698 0 0.00 0 6096 7 0 0
v_min_non_paid_invoice 6205 6205 0 0.00 0 5786 15 0 0
v_min_non_paid_realizn 7436 7436 0 0.00 0 12858 14 0 0
v_our_firm 11120 11120 0 0.00 0 15 1 0 0
v_reserve_write_off 20896 20896 0 0.00 0 13063 9 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 35985 122 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 7097 311 0 0
x_make_qstorno_1000_1200 5748 5748 0 0.00 2 29449 1109 0 0
x_make_qstorno_1200_2000 5715 5715 0 0.00 3 75945 3103 0 0
x_make_qstorno_2100_3300 477856 477856 0 0.00 0 99721 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-08-2021 19:38:49 to 30-08-2021 19:38:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 29361 8192000000 10395240544 10722041856 1445615952 14.999 100 82 164 0 1477772848 5909840 26247056
17:10:26 30466 8192000000 10450008176 10786054144 1504750768 15.518 100 86 172 0 1540693264 8575392 27367104
17:12:04 18328 8192000000 10506241360 10852245504 1561933840 16.013 100 81 163 0 1597263936 10030304 25299792
17:13:25 13786 8192000000 10587538976 10950430720 1640377808 16.683 100 74 148 0 1673109344 10971408 21760128
17:14:43 7364 8192000000 10662887392 11039748096 1724302784 17.388 100 70 140 1 1756309552 11927920 20078848
17:15:52 5910 8192000000 10703242576 11090870272 1777973920 17.833 100 76 151 3 1811317376 12240640 21102816
17:17:00 2397 8192000000 10780304480 11168362496 1838505984 18.329 100 78 156 0 1872880064 12328704 22045376
17:18:04 2306 8192000000 10804364512 11194183680 1863903632 18.535 100 87 173 2 1901213152 13215248 24094272
17:19:09 1540 8192000000 10833905344 11224752128 1890186784 18.747 100 93 185 3 1929842800 14095008 25561008
17:20:11 2493 8192000000 10809811488 11227410432 1889248944 18.740 100 41 83 1 1909457984 9152928 11056112
17:21:16 10917 8192000000 10809476528 11238776832 1904243168 18.860 100 24 48 1 1916758176 6431968 6083040
17:22:29 21221 8192000000 10831700528 11248713728 1904614624 18.863 100 27 53 3 1917056304 5623632 6818048
17:23:52 5747 8192000000 10830413728 11261517824 1917323376 18.965 100 31 61 2 1930446960 5050864 8072720
17:24:59 6135 8192000000 10837426704 11267801088 1919953584 18.986 100 26 52 2 1933724032 6318272 7452176
17:26:07 7470 8192000000 10815541888 11240259584 1850881584 18.429 96 49 99 1 1870835408 5446928 14506896
17:27:16 1565 8192000000 10860938688 11258368000 1884355104 18.700 98 94 188 3 1921028480 9134480 27538896
17:28:18 8112 8192000000 10760827744 11182055424 1790330000 17.934 96 20 41 1 1802213840 5534208 6349632
17:29:31 19668 8192000000 10698422912 11113316352 1636829824 16.653 89 27 54 4 1651779536 5825232 9124480
17:30:56 7612 8192000000 10758403024 11168108544 1811314672 18.107 99 34 68 2 1827718048 7048224 9355152
17:32:04 9186 8192000000 10733960544 11156590592 1812933776 18.120 99 44 88 2 1831812880 5157232 13721872
17:33:14 7572 8192000000 10743340208 11159490560 1820061120 18.178 100 24 49 1 1833064720 5694976 7308624
17:34:24 45195 8192000000 10752463872 11181641728 1839121856 18.334 100 25 49 1 1852484752 5532576 7830320
17:36:14 8896 8192000000 10787954080 11208785920 1863865616 18.535 100 29 58 2 1878922320 6128096 8928608
17:37:24 11708 8192000000 10817993616 11246694400 1900464736 18.830 100 17 34 1 1910409552 4893232 5051584
17:38:46 15979 8192000000 10854839184 11271475200 1923269984 19.013 100 46 92 0 1947427376 11205600 12951792
17:40:07 48877 8192000000 10881883968 11301761024 1947298992 19.205 100 67 133 0 1979143056 12084064 19760000
17:42:00 54536 8192000000 10894392608 11330998272 1945199104 19.188 100 23 46 1 1965042320 13307328 6535888
17:44:00 12921 8192000000 10918962864 11347677184 1972738448 19.407 100 46 92 0 2005649024 19614752 13295824
17:45:19 2585 8192000000 10954320080 11376545792 1993315632 19.570 100 63 128 1 2037574432 25829888 18428912
17:46:25 17579 8192000000 10916789328 11361161216 1963197696 19.331 99 36 72 1 2001200336 27023920 10978720
17:47:47 2707 8192000000 10934411232 11361599488 1980904976 19.472 100 78 152 2 2033577600 28490016 24182608
17:48:56 4233 8192000000 10946398416 11369017344 1976445856 19.437 99 62 124 1 2029875040 34261344 19167840
17:50:04 4771 8192000000 10942705072 11372445696 1979717296 19.462 100 63 126 0 2032249552 33242896 19289360
17:51:14 4591 8192000000 10931107744 11351175168 1932412032 19.086 98 65 130 1 1989909136 38036112 19460992
17:52:33 5382 8192000000 10919896256 11343663104 1948075184 19.211 100 61 121 1 2007035616 39724208 19236224
17:53:46 5234 8192000000 10936418688 11344887808 1922612528 19.008 98 73 143 1 1987295696 42728896 21954272
17:54:56 4459 8192000000 10901486704 11318312960 1904806880 18.865 97 62 122 0 1972706592 49064288 18835424
17:56:10 4134 8192000000 10941612400 11355308032 1948964720 19.218 100 71 140 2 2024206720 52812688 22429312
17:57:20 5076 8192000000 10945082288 11374309376 1973118448 19.410 100 62 126 1 2033404832 40819392 19466992
17:58:30 12291 8192000000 10931006176 11376664576 1971791248 19.400 100 46 92 0 2023981952 37559136 14631568
18:00:06 7955 8192000000 10945779824 11373199360 1963651072 19.335 100 52 105 1 2023528016 42532928 17344016
18:01:22 29360 8192000000 10913400528 11360751616 1949471984 19.222 100 32 64 0 2000341408 39661040 11208384
18:02:55 22761 8192000000 10902659232 11355914240 1931621920 19.080 99 27 54 2 1979335616 38845760 8867936
18:04:18 27346 8192000000 10894773664 11354787840 1932066192 19.083 100 18 37 1 1978978272 40074608 6837472
18:06:05 49911 8192000000 10887287216 11331837952 1934681744 19.104 99 29 59 1 1957641904 14681744 8278416
18:07:59 52900 8192000000 10861406432 11328290816 1901758608 18.840 98 14 29 1 1926841744 20867888 4215248
18:10:02 86032 8192000000 10866323872 11314479104 1869286032 18.578 96 15 31 0 1885906944 13045856 3575056
18:12:35 47426 8192000000 10905108128 11344076800 1929308144 19.061 99 11 22 0 1945535824 12978672 3249008
18:14:27 103682 8192000000 10888083728 11347881984 1939074944 19.139 99 10 20 1 1961452656 19418112 2959600
18:17:17 32756 8192000000 10930976832 11374112768 1960034208 19.306 99 31 60 1 1983023696 14336256 8653232
18:19:07 92898 8192000000 10922346912 11410620416 1962778832 19.328 99 4 8 0 1994706000 30909360 1017808
18:21:46 39058 8192000000 10929461888 11385511936 1976220640 19.435 100 17 35 1 1994919168 12827872 5870656
18:23:28 101448 8192000000 10924074688 11391922176 1984784592 19.503 100 16 32 1 2004926976 16000304 4142080
18:26:10 61542 8192000000 10938704432 11402039296 1979386288 19.460 99 40 80 2 2003355664 12218560 11750816
18:28:20 49661 8192000000 10932846864 11361206272 1906966720 18.882 95 71 141 5 1936421840 9531984 19923136
18:30:13 42794 8192000000 10917714352 11384565760 1961493120 19.318 99 25 50 3 1983871344 14875088 7503136
18:32:10 63719 8192000000 10911804704 11412049920 1969888240 19.385 99 21 42 1 2003528592 26843472 6796880
18:34:15 88323 8192000000 10882542864 11358851072 1876450800 18.636 95 13 26 0 1893850016 13812784 3586432
18:36:46 104395 8192000000 10891643808 11371315200 1897603984 18.807 96 23 46 0 1917195440 13758448 5833008
18:39:32 48097 8192000000 10877005600 11347771392 1919013808 18.979 98 10 19 0 1929013328 7527360 2472160
18:41:30 53217 8192000000 10861940944 11342401536 1880313760 18.668 96 12 25 0 1894533328 10664832 3554736
18:43:23 49639 8192000000 10860413552 11331006464 1906340448 18.877 98 24 48 0 1926902016 14213360 6348208
18:45:14 39874 8192000000 10870049616 11330367488 1900420896 18.830 98 21 41 1 1918923376 12292416 6210064
18:46:54 63283 8192000000 10893529184 11348078592 1924655888 19.024 99 29 58 1 1947283264 13694512 8932864
18:49:29 27401 8192000000 10878858944 11345104896 1930210464 19.069 99 17 34 2 1947710672 12373008 5127200
18:50:59 25933 8192000000 10901070240 11344257024 1911757360 18.921 98 38 76 0 1932373680 9205760 11410560
18:52:29 36509 8192000000 10868627632 11340050432 1912214400 18.924 98 27 54 1 1928933552 8900432 7818720
18:54:13 55239 8192000000 10893158512 11356921856 1959570000 19.303 100 45 89 2 1980193440 8276208 12347232
18:56:09 76963 8192000000 10887854464 11347656704 1921010608 18.995 98 23 46 1 1937904672 10883360 6010704
18:58:26 59465 8192000000 10860580016 11341697024 1887204640 18.723 97 14 28 0 1903762032 12681888 3875504
19:00:26 80544 8192000000 10885005584 11357802496 1951427328 19.238 100 19 38 1 1969089872 11778224 5884320
19:03:47 81060 8192000000 10871271120 11352084480 1849665136 18.419 95 25 48 3 1872220480 16662336 5893008
19:06:13 42703 8192000000 10873510912 11339345920 1925968784 19.035 99 29 58 0 1946731376 12484176 8278416
19:08:01 70365 8192000000 10909623872 11375722496 1952416608 19.246 99 20 40 1 1968211104 10311040 5483456
19:10:13 123079 8192000000 10906264512 11381530624 1944592208 19.183 98 15 30 1 1963668864 15052160 4024496
19:13:17 25511 8192000000 10918216624 11380740096 1960764928 19.312 99 30 60 0 1977916208 8890112 8261168
19:14:44 33738 8192000000 10932636720 11392733184 1985131744 19.505 100 36 70 0 2005215872 10564560 9519568
19:16:24 42054 8192000000 10898137792 11367944192 1917965136 18.971 97 24 48 1 1938116848 13445856 6705856
19:18:08 102517 8192000000 10883832928 11340730368 1865974576 18.552 95 33 66 1 1883404272 7912128 9517568
19:21:14 60461 8192000000 10866614896 11347263488 1932561696 19.087 99 8 15 0 1943893152 8973216 2358240
19:23:15 41618 8192000000 10863647968 11346169856 1943822400 19.177 100 22 44 0 1959636128 9544560 6269168
19:25:00 83055 8192000000 10863235872 11336118272 1849875760 18.421 94 16 32 0 1865363632 11012256 4475616
19:27:51 57523 8192000000 10853150656 11338182656 1883159104 18.691 97 11 22 1 1905350016 18853888 3337024
19:29:52 74925 8192000000 10870218784 11347709952 1904469952 18.862 98 9 18 0 1915877376 8949344 2458080
19:32:08 44305 8192000000 10894645696 11352231936 1913625120 18.936 98 38 75 1 1939658128 15686352 10346656
19:34:03 64470 8192000000 10870879744 11360514048 1921141664 18.996 99 14 28 0 1950847136 25615744 4089728
19:36:11 43732 8192000000 10884472352 11344609280 1928833584 19.058 99 22 45 1 1942842864 8162144 5847136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-08-2021 19:38:55 to 30-08-2021 19:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 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:01, from 30-08-2021 19:39:11 to 30-08-2021 19:39:12.

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          245901
MON$OLDEST_ACTIVE               248113
MON$OLDEST_SNAPSHOT             248113
MON$NEXT_TRANSACTION            248113
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                       2048608
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:763: 
:764: mscs-test-161.budzdorov.ru    Mon Aug 30 19:37:54 2021
:765:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:766: 
:767: 
:768: mscs-test-161.budzdorov.ru    Mon Aug 30 19:37:54 2021
:769:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:770: 
:771: 
:772: mscs-test-161.budzdorov.ru    Mon Aug 30 19:37:54 2021
:773:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:774: 
--- end of diff output ---

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

30.08.21 19:39:13. 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-30 19:39:16 No severe PSQL-related problems occured
30.08.21 19:39:17. End of report.