Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 16:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16739672 kB
MemAvailable 31395564 kB
Buffers 0 kB
Cached 14965464 kB
SwapCached 436 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.9
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-12-27 16:38:03
DTS_END                         2021-12-27 16:38:03.4230
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/54530
TRN_ID                          241239
ATT_ID                          725
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: 9106.93 21046 1092832
customer order: creation 177.00 348 21240
customer order: refuse 15.78 339 1893
order to supplier: creation 46.65 1259 5598
order to supplier: removal 3.69 91144 443
invoice (draft): creation 46.68 3148 5601
invoice (draft): removal 51.00 726 6120
invoice accept: apply 42.77 18363 5132
invoice accept: cancel 45.74 788 5489
customer reserve: creation 3933.13 183 471975
customer reserve: removal 4445.84 61 533501
realization accept: apply 84.87 179 10184
realization accept: cancel 81.30 118 9756
payment from customer: creation 60.48 99 7258
payment from customer: removal 5.78 72 694
payment to supplier: creation 49.66 124 5959
payment to supplier: removal 3.88 119 466
service: total inventory turnovers 4.97 1182 597
service: total monetary turnovers 6.73 39 807
service: aggregate perf. data 0.96 15087 115
service: refresh index statistics 0.03 287539 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7653
TEST_TIME 2 5993
TEST_TIME 3 5111
TEST_TIME 4 5737
TEST_TIME 5 5771
TEST_TIME 6 5628
TEST_TIME 7 5563
TEST_TIME 8 5537
TEST_TIME 9 5411
TEST_TIME 10 5722
TEST_TIME 11 5772
TEST_TIME 12 6200
TEST_TIME 13 6471
TEST_TIME 14 6695
TEST_TIME 15 7579
TEST_TIME 16 7527
TEST_TIME 17 7517
TEST_TIME 18 8126
TEST_TIME 19 8303
TEST_TIME 20 8891
TEST_TIME 21 9014
TEST_TIME 22 9491
TEST_TIME 23 9922
TEST_TIME 24 10279
TEST_TIME 25 10806
TEST_TIME 26 11151
TEST_TIME 27 11097
TEST_TIME 28 10851
TEST_TIME 29 10845
TEST_TIME 30 10680
TEST_TIME 31 10707
TEST_TIME 32 10596
TEST_TIME 33 10516
TEST_TIME 34 10427
TEST_TIME 35 10457
TEST_TIME 36 10466
TEST_TIME 37 10302
TEST_TIME 38 10299
TEST_TIME 39 10204
TEST_TIME 40 10119
TEST_TIME 41 10066
TEST_TIME 42 9996
TEST_TIME 43 9880
TEST_TIME 44 9931
TEST_TIME 45 9882
TEST_TIME 46 9870
TEST_TIME 47 9825
TEST_TIME 48 9740
TEST_TIME 49 9747
TEST_TIME 50 9764
TEST_TIME 51 9696
TEST_TIME 52 9749
TEST_TIME 53 9798
TEST_TIME 54 9778
TEST_TIME 55 9842
TEST_TIME 56 9845
TEST_TIME 57 9804
TEST_TIME 58 9874
TEST_TIME 59 9922
TEST_TIME 60 9923
TEST_TIME 61 9843
TEST_TIME 62 9759
TEST_TIME 63 9724
TEST_TIME 64 9637
TEST_TIME 65 9685
TEST_TIME 66 9650
TEST_TIME 67 9587
TEST_TIME 68 9569
TEST_TIME 69 9488
TEST_TIME 70 9496
TEST_TIME 71 9426
TEST_TIME 72 9358
TEST_TIME 73 9359
TEST_TIME 74 9390
TEST_TIME 75 9320
TEST_TIME 76 9409
TEST_TIME 77 9392
TEST_TIME 78 9422
TEST_TIME 79 9454
TEST_TIME 80 9418
TEST_TIME 81 9394
TEST_TIME 82 9427
TEST_TIME 83 9520
TEST_TIME 84 9549
TEST_TIME 85 9602
TEST_TIME 86 9561
TEST_TIME 87 9491
TEST_TIME 88 9454
TEST_TIME 89 9441
TEST_TIME 90 9416
TEST_TIME 91 9410
TEST_TIME 92 9331
TEST_TIME 93 9355
TEST_TIME 94 9368
TEST_TIME 95 9442
TEST_TIME 96 9408
TEST_TIME 97 9424
TEST_TIME 98 9423
TEST_TIME 99 9444
TEST_TIME 100 9469
TEST_TIME 101 9490
TEST_TIME 102 9503
TEST_TIME 103 9465
TEST_TIME 104 9538
TEST_TIME 105 9495
TEST_TIME 106 9548
TEST_TIME 107 9527
TEST_TIME 108 9443
TEST_TIME 109 9440
TEST_TIME 110 9419
TEST_TIME 111 9396
TEST_TIME 112 9358
TEST_TIME 113 9326
TEST_TIME 114 9312
TEST_TIME 115 9269
TEST_TIME 116 9234
TEST_TIME 117 9202
TEST_TIME 118 9219
TEST_TIME 119 9184
TEST_TIME 120 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 16:39:43 to 27-12-2021 16:39:46.

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 1530500 1530500 0 0.00 0 31264 6 0 0
doc_list_biud 1530500 1530500 0 0.00 0 50818 27 0 0
sp_add_invoice_to_stock 5269 5132 137 2.60 47 176073 18363 0 137
sp_cancel_adding_invoice 5489 5489 0 0.00 5 103639 789 0 0
sp_cancel_client_order 1893 1893 0 0.00 4 14495 339 0 0
sp_cancel_customer_reserve 533501 533501 0 0.00 1 50820 61 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 2 4565 72 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 9532 120 0 0
sp_cancel_supplier_invoice 6133 6120 13 0.21 1 22391 726 0 13
sp_cancel_supplier_order 443 443 0 0.00 4 1096117 91144 0 0
sp_cancel_write_off 9756 9756 0 0.00 1 12461 119 0 0
sp_client_order 21240 21240 0 0.00 3 34820 348 0 0
sp_customer_reserve 471975 471975 0 0.00 0 148443 183 0 0
sp_fill_shopping_cart 32424 32424 0 0.00 1 13587 37 0 0
sp_fill_shopping_cart_clo_res 471960 471960 0 0.00 0 16782 14 0 0
sp_get_clo_for_invoice 5117 5117 0 0.00 0 25064 307 0 0
sp_kill_cost_storno 16405 16405 0 0.00 0 8561 57 0 0
sp_kill_qstorno_ret_qs2qd 1083321 1083321 0 0.00 0 50816 33 0 0
sp_kill_qty_storno 572503 572503 0 0.00 0 50818 71 0 0
sp_lock_selected_doc 21391 21391 0 0.00 0 8301 7 0 0
sp_make_cost_storno 28518 28518 0 0.00 0 31261 53 0 0
sp_multiply_rows_for_pdistr 28518 28518 0 0.00 0 6750 25 0 0
sp_multiply_rows_for_qdistr 21240 21240 0 0.00 0 20481 166 0 0
sp_pay_from_customer 7258 7258 0 0.00 4 11058 100 0 0
sp_pay_to_supplier 5959 5959 0 0.00 5 12449 124 0 0
sp_payment_common 13217 13217 0 0.00 2 11005 29 0 0
sp_qd_handle_on_cancel_clo 1893 1893 0 0.00 0 14488 310 0 0
sp_qd_handle_on_invoice_upd_sts 10606 10606 0 0.00 0 14274 316 0 0
sp_qd_handle_on_reserve_upd_sts 10184 10184 0 0.00 0 15206 65 0 0
sp_reserve_write_off 10184 10184 0 0.00 6 31271 180 0 0
sp_supplier_invoice 5601 5601 0 0.00 6 90974 3149 0 0
sp_supplier_order 5598 5598 0 0.00 5 36776 1259 0 0
srv_aggregate_perf_data 116 115 1 0.86 2447 107048 15088 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 2089 136984 51664 0 0
srv_find_qd_qs_mism 1044316 1044316 0 0.00 0 20647 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 26 91742 1182 0 0
srv_make_money_saldo 807 807 0 0.00 0 7537 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 178123 368843 287540 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5132 5132 0 0.00 0 10032 169 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 7897 135 0 0
t$perf-norm:sp_cancel_client_order 1893 1893 0 0.00 0 11789 19 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 4944 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3584 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 5587 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 7690 31 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 26182 1454 0 0
t$perf-norm:sp_cancel_write_off 1091 1091 0 0.00 0 2306 6 0 0
t$perf-norm:sp_client_order 21240 21240 0 0.00 0 14244 20 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 927 5 0 0
t$perf-norm:sp_pay_from_customer 7258 7258 0 0.00 0 9998 11 0 0
t$perf-norm:sp_pay_to_supplier 5959 5959 0 0.00 0 17318 16 0 0
t$perf-norm:sp_reserve_write_off 10184 10184 0 0.00 0 9070 12 0 0
t$perf-norm:sp_supplier_invoice 5601 5601 0 0.00 0 6784 21 0 0
t$perf-norm:sp_supplier_order 5598 5598 0 0.00 0 6509 19 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 500 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 866 19 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 4392 27 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 4438 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2965 774 0 0
v_add_invoice_to_stock 10508 10234 274 2.60 0 10282 20 0 274
v_all_customers 38578 38578 0 0.00 0 19 0 0 0
v_all_suppliers 30770 30770 0 0.00 0 109 1 0 0
v_all_wares 42480 42480 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 286 6 0 0
v_cancel_client_order 3786 3786 0 0.00 0 6323 7 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 4527 8 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 3042 9 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 4808 14 0 0
v_cancel_supplier_invoice 866 840 26 3.00 0 3777 21 0 26
v_cancel_supplier_order 886 886 0 0.00 0 822 4 0 0
v_cancel_write_off 2182 2182 0 0.00 0 3014 15 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 1205 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4470 17 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 6232 3 0 0
v_max_non_paid_invoice 1758 1758 0 0.00 0 454 2 0 0
v_max_non_paid_realizn 3927 3927 0 0.00 0 2384 3 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 3621 5 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 1893 8 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 2009 3 0 0
v_min_non_paid_invoice 5959 5959 0 0.00 0 9695 21 0 0
v_min_non_paid_realizn 7258 7258 0 0.00 0 10998 23 0 0
v_our_firm 10564 10564 0 0.00 0 19 1 0 0
v_reserve_write_off 20368 20368 0 0.00 0 9491 6 0 0
x_lock_depdocs_on_canc_invoice 11189 11189 0 0.00 0 19888 115 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 6072 294 0 0
x_make_qstorno_1000_1200 5598 5598 0 0.00 1 36756 1185 0 0
x_make_qstorno_1200_2000 5601 5601 0 0.00 2 90929 3012 0 0
x_make_qstorno_2100_3300 471975 471975 0 0.00 0 148441 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 16:39:47 to 27-12-2021 16:39:51.

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:08:07 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:08 19606 8192000000 9890720672 10203635712 965594960 10.544 100 85 170 0 993161264 5523472 22042832
14:10:34 10208 8192000000 9941567872 10264182784 1024682192 11.117 100 89 177 1 1055782656 8084016 23016448
14:11:49 18702 8192000000 9975998400 10306187264 1055439280 11.413 100 86 172 1 1086505696 9493776 21572640
14:13:12 11169 8192000000 10024377504 10371575808 1110280384 11.935 100 77 152 1 1138859888 10664608 17914896
14:14:39 8937 8192000000 10082318544 10439929856 1165003904 12.450 100 84 168 0 1197990912 13833184 19153824
14:15:50 6758 8192000000 10111302784 10480140288 1199975664 12.776 100 88 175 2 1233783872 13479120 20329088
14:16:59 4882 8192000000 10151043104 10526097408 1236328576 13.112 100 88 176 1 1269694960 13682432 19683952
14:18:05 2302 8192000000 10190385408 10569895936 1268310480 13.406 100 90 180 0 1302585520 14048528 20226512
14:19:11 2037 8192000000 10206664816 10589270016 1289489216 13.600 100 92 182 0 1324233776 13877072 20867488
14:20:16 15359 8192000000 10209428112 10604175360 1299431232 13.690 100 63 125 2 1321481664 6527168 15523264
14:21:32 7584 8192000000 10197891312 10611011584 1307420896 13.763 100 24 49 0 1323222240 10460672 5340672
14:22:41 53928 8192000000 10211823280 10627289088 1319907200 13.876 100 41 80 1 1333762080 3899408 9955472
14:24:37 9529 8192000000 10223605264 10641645568 1329683296 13.964 100 27 54 2 1342233840 5948240 6602304
14:25:47 10307 8192000000 10232377808 10650345472 1337192576 14.032 100 28 54 2 1349071616 6069520 5809520
14:27:01 8394 8192000000 10225479584 10645127168 1333054448 13.995 100 19 37 1 1342685200 5764064 3866688
14:28:10 23899 8192000000 10220588192 10646806528 1326669824 13.937 99 19 38 1 1336528272 5613152 4245296
14:29:40 9072 8192000000 10184888000 10596974592 1270207104 13.424 97 26 52 2 1282384560 5992384 6185072
14:30:54 8987 8192000000 10135651520 10540773376 1182457824 12.613 93 15 30 1 1190949680 5044496 3447360
14:32:05 16272 8192000000 10141922576 10545598464 1234370960 13.094 98 23 46 0 1246359456 6110864 5877632
14:33:44 11917 8192000000 10151950944 10554994688 1247736240 13.217 99 18 36 0 1262042976 10245520 4061216
14:34:57 5385 8192000000 10176931920 10587832320 1286826160 13.575 100 33 66 2 1301758960 7577472 7355328
14:36:03 13397 8192000000 10185967376 10599559168 1294386224 13.644 100 36 73 0 1308982848 5465744 9130880
14:37:17 73689 8192000000 10219052128 10632589312 1307372736 13.762 100 25 50 0 1318423696 5430608 5620352
14:39:38 6004 8192000000 10267491136 10671931392 1348625840 14.135 100 74 149 2 1375649088 9824352 17198896
14:40:49 31050 8192000000 10278530208 10695081984 1364992144 14.282 100 36 72 0 1387598416 14559248 8047024
14:42:28 3392 8192000000 10300930880 10716815360 1381126912 14.427 100 48 94 2 1411241392 19044688 11069792
14:43:37 12086 8192000000 10310553056 10734956544 1395117424 14.552 100 40 80 1 1425212944 20737584 9357936
14:44:55 6577 8192000000 10345016160 10764648448 1413388368 14.714 100 55 109 0 1455389328 29004384 12996576
14:46:03 32447 8192000000 10346039248 10772971520 1403684016 14.628 99 43 86 3 1445183792 31137600 10362176
14:47:40 5669 8192000000 10372607488 10788859904 1423324480 14.802 100 73 146 0 1478627344 37732544 17570320
14:48:49 3328 8192000000 10355746368 10783150080 1418959600 14.763 100 49 98 0 1468023536 36172288 12891648
14:49:56 2086 8192000000 10382976096 10803195904 1427515136 14.839 100 73 143 2 1489745456 43840896 18389424
14:51:04 5413 8192000000 10396024976 10815094784 1438974624 14.941 100 85 171 0 1508642384 48916256 20751504
14:52:15 4844 8192000000 10400582240 10824388608 1436632992 14.920 100 70 140 0 1507350320 52963536 17753792
14:53:24 2651 8192000000 10392860640 10811293696 1407289568 14.660 98 83 167 1 1481191696 52735328 21166800
14:54:34 2725 8192000000 10391739184 10807791616 1420274080 14.775 100 81 161 0 1496389984 55962464 20153440
14:55:41 3769 8192000000 10349569312 10757070848 1361006576 14.246 97 73 144 1 1444031888 63121152 19904160
14:56:51 16050 8192000000 10360845680 10787237888 1384735520 14.459 99 44 88 4 1466815648 69667600 12412528
14:58:10 20076 8192000000 10340592624 10765492224 1382017472 14.435 100 40 80 2 1443814208 50847984 10948752
14:59:32 19427 8192000000 10334211616 10770812928 1364608336 14.279 99 34 68 1 1436928672 62181456 10138880
15:00:56 33722 8192000000 10321180144 10762092544 1383564272 14.448 100 28 55 0 1439385056 47855344 7965440
15:02:51 55264 8192000000 10291930112 10735808512 1345065104 14.103 99 15 30 0 1398856880 49220992 4570784
15:04:47 96459 8192000000 10252392064 10701946880 1317110752 13.851 97 28 54 2 1340507280 16520000 6876528
15:07:25 80345 8192000000 10264188576 10706980864 1316275200 13.843 97 40 80 0 1339918000 15035424 8607376
15:09:54 54084 8192000000 10247059808 10686500864 1324119904 13.914 98 19 38 0 1337269424 8983632 4165888
15:12:25 58399 8192000000 10278752928 10727571456 1372651088 14.351 100 19 38 0 1388856144 12302832 3902224
15:14:25 73307 8192000000 10281401456 10727710720 1379790208 14.415 100 27 52 3 1397985888 12141520 6054160
15:16:39 101657 8192000000 10279487424 10735276032 1347390752 14.124 97 13 25 1 1362014304 11383824 3239728
15:19:26 95426 8192000000 10288223104 10759286784 1390185152 14.508 100 11 22 0 1404701344 12003888 2512304
15:22:45 62556 8192000000 10292105232 10764046336 1393908960 14.541 100 13 27 0 1413310720 16396304 3005456
15:24:47 64586 8192000000 10282036032 10742337536 1353049968 14.175 97 10 21 0 1366409312 10914880 2444464
15:26:57 39153 8192000000 10298333424 10761158656 1397947424 14.577 100 18 36 1 1411722608 9362448 4412736
15:28:37 62153 8192000000 10320155744 10770006016 1405118288 14.641 100 18 35 1 1421711440 12748160 3844992
15:30:48 57677 8192000000 10304326768 10771578880 1379853664 14.415 98 14 28 1 1395920464 13175760 2891040
15:32:52 51449 8192000000 10302476192 10764296192 1375513472 14.376 98 21 42 1 1388781184 9128880 4138832
15:34:51 30483 8192000000 10304158000 10745729024 1374837872 14.370 99 27 54 0 1392644224 11324688 6481664
15:36:32 58723 8192000000 10263856160 10717556736 1309241984 13.779 95 9 18 1 1324702656 13346544 2114128
15:38:31 84718 8192000000 10270399424 10728562688 1359007264 14.228 99 24 48 0 1387941760 24105520 4828976
15:41:32 46754 8192000000 10263892688 10700828672 1315368752 13.835 96 24 47 1 1327619968 7086208 5165008
15:43:20 64903 8192000000 10238623312 10700226560 1317113488 13.851 97 17 34 0 1332390848 11618304 3659056
15:45:26 71056 8192000000 10222661968 10676637696 1269676176 13.419 94 14 28 0 1280124880 7473344 2975360
15:47:45 31877 8192000000 10248628656 10700939264 1350420032 14.151 100 12 24 0 1363575008 10122864 3032112
15:49:30 88741 8192000000 10257229600 10706087936 1327524432 13.945 97 15 30 0 1340599168 10015776 3058960
15:52:09 75441 8192000000 10272505568 10730500096 1351123488 14.158 100 20 40 1 1371362272 15491424 4747360
15:54:35 39375 8192000000 10263523504 10709774336 1357391904 14.214 100 30 61 3 1371716736 7504960 6819872
15:56:19 49227 8192000000 10261378432 10703224832 1319481680 13.872 97 32 64 0 1338597872 11927120 7189072
15:58:18 30113 8192000000 10269851472 10701758464 1327556624 13.945 98 45 90 0 1347661440 9163424 10941392
15:59:50 74923 8192000000 10259451504 10703912960 1322666544 13.901 98 24 48 0 1344597696 16841712 5089440
16:02:37 110771 8192000000 10282247904 10733215744 1370837648 14.335 100 9 18 0 1391909936 19117088 1955200
16:05:28 136983 8192000000 10288217008 10746404864 1362095472 14.256 98 30 60 0 1380641904 11618608 6927824
16:09:11 41299 8192000000 10282506240 10746720256 1364111968 14.274 98 12 24 0 1379514928 13120080 2282880
16:10:53 50077 8192000000 10281083040 10745274368 1345626064 14.108 97 10 20 1 1362054016 14365088 2062864
16:12:44 81727 8192000000 10297108416 10753708032 1363418832 14.268 98 18 34 1 1378693664 11427184 3847648
16:15:07 67124 8192000000 10299104592 10764304384 1384013936 14.452 99 22 44 1 1403155136 14132592 5008608
16:17:17 80185 8192000000 10303145264 10751819776 1369940032 14.327 98 35 70 2 1392079536 14509728 7629776
16:19:46 58479 8192000000 10300396880 10758795264 1368522416 14.314 98 10 20 1 1389316144 18661552 2132176
16:21:53 135699 8192000000 10297902000 10747256832 1294946752 13.649 93 22 44 2 1313101984 13367728 4787504
16:25:09 88379 8192000000 10288174128 10734321664 1326878432 13.939 96 14 28 1 1348788624 18956368 2953824
16:28:00 86704 8192000000 10254394960 10707099648 1327887968 13.948 97 9 17 1 1338504048 8233888 2382192
16:30:31 69292 8192000000 10231706352 10681737216 1311544992 13.800 96 5 10 0 1318148832 5597760 1006080
16:32:43 16255 8192000000 10250775024 10701000704 1347023920 14.121 99 27 52 0 1358914080 4943808 6946352
16:34:00 86724 8192000000 10243239632 10686386176 1299980064 13.695 95 25 50 1 1317222240 11482384 5759792
16:36:28 57794 8192000000 10248427440 10702168064 1313311584 13.816 97 7 14 0 1333385744 18341840 1732320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2021 16:39:53 to 27-12-2021 16:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 137 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2021 16:40:09 to 27-12-2021 16:40:11.

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          238550
MON$OLDEST_ACTIVE               241840
MON$OLDEST_SNAPSHOT             241840
MON$NEXT_TRANSACTION            241840
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                       2011520
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:222: 
:223: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:06 2021
:224:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:225: 
:226: 
:227: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:07 2021
:228:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:229: 
:230: 
:231: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:07 2021
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
:234: 
:235: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:07 2021
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
:238: 
:239: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:08 2021
:240:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:241: 
:242: 
:243: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:11 2021
:244:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:245: 
:246: 
:247: mscs-test-161.budzdorov.ru    Mon Dec 27 16:38:12 2021
:248:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:249: 
--- end of diff output ---

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

27.12.21 16:40:12. 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-12-27 16:40:14 No severe PSQL-related problems occured
27.12.21 16:40:15. End of report.