Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2021 13: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 16195352 kB
MemAvailable 31038568 kB
Buffers 0 kB
Cached 15538364 kB
SwapCached 2608 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-18 13:37:06
DTS_END                         2021-08-18 13:37:06.5940
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(68:17)
IP                              ::1/53890
TRN_ID                          239011
ATT_ID                          660
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: 9097.08 21060 1091649
customer order: creation 177.32 386 21278
customer order: refuse 14.45 355 1734
order to supplier: creation 47.22 1343 5666
order to supplier: removal 3.44 102923 413
invoice (draft): creation 46.38 3694 5566
invoice (draft): removal 50.93 860 6112
invoice accept: apply 41.40 19098 4968
invoice accept: cancel 45.68 775 5481
customer reserve: creation 3802.17 191 456260
customer reserve: removal 4574.85 60 548982
realization accept: apply 86.10 202 10332
realization accept: cancel 76.91 130 9229
payment from customer: creation 60.81 112 7297
payment from customer: removal 5.54 72 665
payment to supplier: creation 49.17 123 5900
payment to supplier: removal 3.33 86 399
service: total inventory turnovers 4.18 3026 501
service: total monetary turnovers 6.28 495 754
service: aggregate perf. data 0.90 15643 108
service: refresh index statistics 0.03 271632 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-08-2021 13:39:21 to 18-08-2021 13:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4960
TEST_TIME 2 3698
TEST_TIME 3 3983
TEST_TIME 4 4638
TEST_TIME 5 5112
TEST_TIME 6 5564
TEST_TIME 7 5583
TEST_TIME 8 5900
TEST_TIME 9 6304
TEST_TIME 10 6415
TEST_TIME 11 6914
TEST_TIME 12 6789
TEST_TIME 13 7016
TEST_TIME 14 7367
TEST_TIME 15 7671
TEST_TIME 16 8034
TEST_TIME 17 8413
TEST_TIME 18 8195
TEST_TIME 19 8396
TEST_TIME 20 8578
TEST_TIME 21 8675
TEST_TIME 22 9125
TEST_TIME 23 9081
TEST_TIME 24 9434
TEST_TIME 25 9493
TEST_TIME 26 9878
TEST_TIME 27 9976
TEST_TIME 28 10633
TEST_TIME 29 10686
TEST_TIME 30 10397
TEST_TIME 31 10430
TEST_TIME 32 10238
TEST_TIME 33 9988
TEST_TIME 34 9898
TEST_TIME 35 9805
TEST_TIME 36 9640
TEST_TIME 37 9799
TEST_TIME 38 9683
TEST_TIME 39 9727
TEST_TIME 40 9717
TEST_TIME 41 9753
TEST_TIME 42 9671
TEST_TIME 43 9607
TEST_TIME 44 9610
TEST_TIME 45 9577
TEST_TIME 46 9509
TEST_TIME 47 9501
TEST_TIME 48 9535
TEST_TIME 49 9494
TEST_TIME 50 9579
TEST_TIME 51 9754
TEST_TIME 52 9745
TEST_TIME 53 9784
TEST_TIME 54 9843
TEST_TIME 55 9793
TEST_TIME 56 9801
TEST_TIME 57 9881
TEST_TIME 58 9734
TEST_TIME 59 9659
TEST_TIME 60 9581
TEST_TIME 61 9605
TEST_TIME 62 9570
TEST_TIME 63 9477
TEST_TIME 64 9401
TEST_TIME 65 9357
TEST_TIME 66 9361
TEST_TIME 67 9390
TEST_TIME 68 9500
TEST_TIME 69 9517
TEST_TIME 70 9482
TEST_TIME 71 9485
TEST_TIME 72 9452
TEST_TIME 73 9553
TEST_TIME 74 9517
TEST_TIME 75 9470
TEST_TIME 76 9586
TEST_TIME 77 9487
TEST_TIME 78 9386
TEST_TIME 79 9314
TEST_TIME 80 9337
TEST_TIME 81 9293
TEST_TIME 82 9238
TEST_TIME 83 9208
TEST_TIME 84 9308
TEST_TIME 85 9264
TEST_TIME 86 9281
TEST_TIME 87 9273
TEST_TIME 88 9226
TEST_TIME 89 9191
TEST_TIME 90 9315
TEST_TIME 91 9281
TEST_TIME 92 9349
TEST_TIME 93 9367
TEST_TIME 94 9375
TEST_TIME 95 9453
TEST_TIME 96 9361
TEST_TIME 97 9297
TEST_TIME 98 9281
TEST_TIME 99 9248
TEST_TIME 100 9217
TEST_TIME 101 9166
TEST_TIME 102 9175
TEST_TIME 103 9126
TEST_TIME 104 9125
TEST_TIME 105 9117
TEST_TIME 106 9164
TEST_TIME 107 9131
TEST_TIME 108 9135
TEST_TIME 109 9196
TEST_TIME 110 9186
TEST_TIME 111 9178
TEST_TIME 112 9190
TEST_TIME 113 9245
TEST_TIME 114 9247
TEST_TIME 115 9290
TEST_TIME 116 9292
TEST_TIME 117 9239
TEST_TIME 118 9221
TEST_TIME 119 9171
TEST_TIME 120 9099

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-08-2021 13:39:25 to 18-08-2021 13:39:28.

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 1515452 1515452 0 0.00 0 24628 7 0 0
doc_list_biud 1515452 1515452 0 0.00 -3949 56047 28 0 0
sp_add_invoice_to_stock 5099 4968 131 2.56 32 307017 19099 0 131
sp_cancel_adding_invoice 5481 5481 0 0.00 -3933 95999 775 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 26995 355 0 0
sp_cancel_customer_reserve 548982 548982 0 0.00 -1986 56050 61 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 5628 72 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 8673 87 0 0
sp_cancel_supplier_invoice 6120 6112 8 0.13 2 41709 861 0 8
sp_cancel_supplier_order 413 413 0 0.00 9 876505 102923 0 0
sp_cancel_write_off 9229 9229 0 0.00 1 17580 130 0 0
sp_client_order 21278 21278 0 0.00 -1894 33690 387 0 0
sp_customer_reserve 456260 456260 0 0.00 -3557 281100 191 0 0
sp_fill_shopping_cart 32502 32502 0 0.00 1 22860 42 0 0
sp_fill_shopping_cart_clo_res 456255 456255 0 0.00 -2475 12164 13 0 0
sp_get_clo_for_invoice 4963 4963 0 0.00 0 36024 310 0 0
sp_kill_cost_storno 15774 15774 0 0.00 0 15492 59 0 0
sp_kill_qstorno_ret_qs2qd 1113718 1113718 0 0.00 -1992 56042 33 0 0
sp_kill_qty_storno 587246 587246 0 0.00 -3950 56047 72 0 0
sp_lock_selected_doc 20946 20946 0 0.00 0 8425 5 0 0
sp_make_cost_storno 28492 28492 0 0.00 0 17483 53 0 0
sp_multiply_rows_for_pdistr 28492 28492 0 0.00 0 17086 33 0 0
sp_multiply_rows_for_qdistr 21278 21278 0 0.00 -2038 11817 179 0 0
sp_pay_from_customer 7297 7297 0 0.00 4 12469 112 0 0
sp_pay_to_supplier 5900 5900 0 0.00 5 15284 124 0 0
sp_payment_common 13197 13197 0 0.00 1 15050 19 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 26988 329 0 0
sp_qd_handle_on_invoice_upd_sts 10444 10444 0 0.00 -3950 31077 349 0 0
sp_qd_handle_on_reserve_upd_sts 10332 10332 0 0.00 0 26950 73 0 0
sp_reserve_write_off 10332 10332 0 0.00 7 28250 202 0 0
sp_supplier_invoice 5566 5566 0 0.00 6 114510 3694 0 0
sp_supplier_order 5666 5666 0 0.00 6 49281 1343 0 0
srv_aggregate_perf_data 108 108 0 0.00 2433 335278 15644 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 851 197474 47627 0 0
srv_find_qd_qs_mism 1044225 1044225 0 0.00 0 24628 7 0 0
srv_make_invnt_saldo 501 501 0 0.00 53 91980 3027 0 0
srv_make_money_saldo 754 754 0 0.00 1 13965 496 0 0
srv_recalc_idx_stat 4 4 0 0.00 243174 349879 271632 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 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 4968 4968 0 0.00 0 27392 182 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 8288 106 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 5707 17 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 22597 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 712 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 2555 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 6983 29 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 25549 1429 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 4726 8 0 0
t$perf-norm:sp_client_order 21278 21278 0 0.00 0 26307 20 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 4638 12 0 0
t$perf-norm:sp_pay_from_customer 7297 7297 0 0.00 0 4872 7 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 12156 12 0 0
t$perf-norm:sp_reserve_write_off 10332 10332 0 0.00 0 23190 11 0 0
t$perf-norm:sp_supplier_invoice 5566 5566 0 0.00 0 7514 21 0 0
t$perf-norm:sp_supplier_order 5666 5666 0 0.00 0 19062 23 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 97 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1616 28 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 461 5 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 1904 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10188 9926 262 2.57 0 5017 20 0 262
v_all_customers 38536 38536 0 0.00 0 21 1 0 0
v_all_suppliers 31020 31020 0 0.00 0 24 1 0 0
v_all_wares 42556 42556 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 3769 15 0 0
v_cancel_client_order 3468 3468 0 0.00 0 4006 6 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 1448 5 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 6564 12 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 5308 13 0 0
v_cancel_supplier_invoice 782 766 16 2.04 0 908 12 0 16
v_cancel_supplier_order 826 826 0 0.00 0 3796 12 0 0
v_cancel_write_off 1924 1924 0 0.00 0 3822 18 0 0
v_max_id_clo_ord 5666 5666 0 0.00 0 5406 3 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 5928 20 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 6677 5 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 93 2 0 0
v_max_non_paid_realizn 3963 3963 0 0.00 0 7465 4 0 0
v_min_id_clo_ord 5666 5666 0 0.00 0 5081 10 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 3569 11 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 9293 10 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 15038 13 0 0
v_min_non_paid_realizn 7297 7297 0 0.00 0 7132 13 0 0
v_our_firm 10688 10688 0 0.00 0 26 1 0 0
v_reserve_write_off 20664 20664 0 0.00 0 5497 6 0 0
x_lock_depdocs_on_canc_invoice 11210 11210 0 0.00 0 21585 111 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 9832 351 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 2 49273 1255 0 0
x_make_qstorno_1200_2000 5566 5566 0 0.00 2 112180 3544 0 0
x_make_qstorno_2100_3300 456260 456260 0 0.00 -3559 281089 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 13:39:29 to 18-08-2021 13:39:33.

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
11:07:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 29990 8192000000 9724998528 10024210432 798274160 8.879 100 94 188 0 829726224 8097056 23355008
11:09:44 20870 8192000000 9764141168 10071425024 836338720 9.263 100 94 188 0 872376432 13634688 22403024
11:11:15 17290 8192000000 9807093008 10128510976 871923232 9.619 100 81 162 0 916125584 25489216 18713136
11:12:39 11612 8192000000 9870445952 10204016640 925002288 10.145 100 80 160 0 979360384 37324800 17033296
11:13:54 7879 8192000000 9927476976 10275385344 961353984 10.502 100 77 153 0 1050025632 72642720 16028928
11:15:05 5257 8192000000 9963826784 10325303296 996750304 10.847 100 72 144 2 1080935024 69809264 14375456
11:16:13 4898 8192000000 9984061344 10350673920 1031029104 11.178 100 84 165 3 1103203264 55266368 16907792
11:17:19 1745 8192000000 10018891392 10390540288 1053629136 11.395 100 85 170 0 1135494704 64196640 17668928
11:18:23 2407 8192000000 10038022848 10410381312 1065688080 11.511 100 92 183 0 1147639184 62313520 19637584
11:19:27 6203 8192000000 10021106256 10399150080 1070331696 11.555 100 69 138 2 1129607104 45791200 13484208
11:20:35 4662 8192000000 10038602064 10431492096 1073188816 11.583 100 50 100 2 1151174144 67582448 10402880
11:21:41 2718 8192000000 9990420592 10385125376 1078854656 11.637 100 50 99 1 1114115056 23587056 11673344
11:22:46 9673 8192000000 9981339488 10381455360 1082346736 11.670 100 23 45 2 1115293936 28209408 4737792
11:23:56 49609 8192000000 9998967664 10396786688 1087808784 11.722 100 39 78 0 1106473632 10016048 8648800
11:25:47 7655 8192000000 10012471872 10420228096 1094917856 11.789 100 31 62 2 1130935696 29643184 6374656
11:26:55 4316 8192000000 10020552416 10423754752 1098797264 11.826 100 40 81 2 1141832944 34853872 8181808
11:28:06 4970 8192000000 9983073920 10371973120 1039882256 11.264 97 41 83 1 1076765408 26868128 10015024
11:29:12 33674 8192000000 9947929104 10348892160 983264768 10.716 92 32 63 2 1012265664 22014144 6986752
11:30:48 8678 8192000000 9960341872 10349826048 989397840 10.776 95 43 87 2 1015961648 17645024 8918784
11:31:58 4951 8192000000 9920394288 10289152000 980861696 10.693 96 45 88 1 996132608 5160976 10109936
11:33:03 56213 8192000000 9956725488 10347589632 1029416080 11.163 99 40 81 0 1057763616 19877120 8470416
11:35:01 6539 8192000000 9987898160 10385973248 1054578736 11.405 100 50 100 2 1097079392 31890560 10610096
11:36:11 7073 8192000000 9981903632 10372313088 1062936400 11.485 100 45 92 3 1087789584 14089408 10763776
11:37:20 3243 8192000000 10004828464 10392858624 1084345136 11.689 100 68 132 1 1110367968 12161744 13861088
11:38:31 125309 8192000000 10040750880 10456596480 1104873248 11.884 100 37 74 0 1156766256 43836416 8056592
11:41:42 9307 8192000000 10106434080 10502316032 1126945872 12.093 100 60 120 0 1206632016 67590448 12095696
11:43:02 11879 8192000000 10146120544 10548834304 1135578320 12.174 100 74 147 2 1254919760 104069808 15271632
11:44:19 7400 8192000000 10142471344 10555994112 1149685264 12.307 100 60 120 3 1267401264 105168704 12547296
11:45:31 9583 8192000000 10187207056 10603106304 1152624416 12.334 100 57 113 3 1300564528 135899440 12040672
11:46:46 3615 8192000000 10193820080 10601738240 1151643552 12.325 99 66 132 1 1287036176 120776880 14615744
11:47:53 3666 8192000000 10251813392 10653454336 1154728384 12.354 99 76 149 1 1340844944 170049376 16067184
11:49:00 6515 8192000000 10227092416 10640326656 1155019712 12.357 99 54 108 0 1326096208 159189168 11887328
11:50:10 4169 8192000000 10297870352 10708783104 1170052656 12.497 100 71 142 0 1398291968 212100016 16139296
11:51:19 3176 8192000000 10296670368 10711695360 1169965744 12.497 100 81 162 0 1396298480 206955376 19377360
11:52:26 3816 8192000000 10277581616 10680754176 1144740336 12.260 100 81 162 0 1378210896 214790448 18680112
11:53:34 1044 8192000000 10283491760 10682339328 1136743712 12.185 100 82 159 3 1379760160 224614704 18401744
11:54:42 850 8192000000 10269167776 10680500224 1149419872 12.304 100 74 147 4 1371785568 205722032 16643664
11:55:46 3753 8192000000 10286125536 10690207744 1146945568 12.281 100 73 144 0 1386275808 222828368 16501872
11:56:56 3976 8192000000 10321146784 10752651264 1154497648 12.352 100 53 106 1 1423263584 255827488 12938448
11:58:03 8540 8192000000 10293086976 10735362048 1136943536 12.187 100 31 62 0 1404200496 259081600 8175360
11:59:16 7525 8192000000 10284020304 10704400384 1144982304 12.262 100 38 77 0 1383751152 229407824 9361024
12:00:26 17647 8192000000 10260777024 10696507392 1106661888 11.901 98 24 48 0 1341899776 228470880 6767008
12:01:46 18324 8192000000 10253813152 10679033856 1112143616 11.953 99 24 48 0 1351097296 233121184 5832496
12:03:07 33834 8192000000 10221145760 10680737792 1124232400 12.067 100 21 42 0 1385888912 256211232 5445280
12:05:16 21546 8192000000 10074269424 10572541952 1099971824 11.837 99 13 24 1 1170573680 67972944 2628912
12:06:44 82885 8192000000 10059417168 10504704000 1087707680 11.721 97 23 46 1 1147013056 54715424 4589952
12:09:07 120747 8192000000 10068915552 10512502784 1129061488 12.113 100 18 36 0 1162125792 28893584 4170720
12:12:09 36033 8192000000 10113993152 10550472704 1122933472 12.055 98 32 63 2 1188571680 58391600 7246608
12:13:48 197474 8192000000 10116098832 10587185152 1113697296 11.967 97 42 82 2 1237323744 114533040 9093408
12:18:06 77652 8192000000 10094449984 10591047680 1129384464 12.116 99 18 36 0 1178042432 44515888 4142080
12:20:40 48359 8192000000 10126538352 10590150656 1146478400 12.276 100 22 44 0 1212301728 60662080 5161248
12:22:38 32169 8192000000 10069148448 10534080512 1123181152 12.057 98 4 8 1 1167582128 43534640 866336
12:24:20 49905 8192000000 10134746672 10594332672 1156137088 12.367 100 16 32 0 1235479904 75675680 3667136
12:26:15 60698 8192000000 10202090608 10652348416 1153841424 12.346 99 34 68 1 1289257744 128094976 7321344
12:28:20 32940 8192000000 10083906608 10531958784 1130811920 12.129 98 30 60 3 1167442464 30766400 5864144
12:30:06 24484 8192000000 10132417104 10578710528 1143187120 12.246 99 41 82 2 1214907264 62785408 8934736
12:31:38 51106 8192000000 10092468256 10557865984 1118503472 12.013 97 21 42 1 1140649696 17691504 4454720
12:33:30 42491 8192000000 10090451648 10530836480 1124640848 12.071 98 37 74 0 1181766336 49185008 7940480
12:35:12 95069 8192000000 10066642064 10526109696 1126082848 12.084 99 22 44 0 1160819344 30121984 4614512
12:37:49 41034 8192000000 10094406624 10543087616 1104926144 11.884 98 42 84 0 1178561920 64713824 8921952
12:41:13 76308 8192000000 10091792960 10540421120 1087812528 11.722 96 10 19 0 1137802448 48180816 1809104
12:43:30 35751 8192000000 10097219600 10528026624 1070346800 11.555 95 21 43 2 1161167808 85259696 5561312
12:45:09 50286 8192000000 10058192864 10535174144 1093649440 11.777 97 15 30 0 1161180736 64510496 3020800
12:47:01 25119 8192000000 10055742288 10476879872 1091677728 11.759 98 42 84 0 1133751696 33221648 8852320
12:48:27 49163 8192000000 10069529184 10493452288 1111095520 11.943 100 34 68 0 1161740576 43449904 7195152
12:50:20 60615 8192000000 10036730144 10490044416 1095092624 11.791 98 31 62 1 1156294352 54717200 6484528
12:52:25 33093 8192000000 10141099824 10567413760 1125271184 12.077 100 44 88 0 1211382224 76928592 9182448
12:54:03 129959 8192000000 10082556192 10519453696 1103155600 11.868 98 25 50 1 1147054272 38587376 5311296
12:57:15 70752 8192000000 10112562528 10614763520 1130425952 12.125 100 12 24 0 1230046352 97072832 2547568
12:59:37 57470 8192000000 10115737440 10559717376 1106386992 11.898 98 14 28 1 1197958400 88155856 3415552
13:01:35 101870 8192000000 10066133104 10539536384 1090826448 11.751 97 22 44 2 1159820048 64294176 4699424
13:04:19 89248 8192000000 10103244032 10536648704 1094825872 11.789 97 17 34 0 1141085792 42891264 3368656
13:06:50 65903 8192000000 10064699936 10543812608 1118383184 12.012 99 21 42 1 1197559152 74817776 4358192
13:08:56 42031 8192000000 10093222400 10605502464 1142825152 12.242 100 24 48 0 1243377616 95794576 4757888
13:10:39 138965 8192000000 10087739456 10618052608 1133940352 12.158 99 12 24 0 1231078256 94635728 2502176
13:14:03 80289 8192000000 10109413632 10559143936 1137072704 12.188 99 31 62 1 1187655344 43998256 6584384
13:16:24 57325 8192000000 10075632032 10566242304 1137173504 12.189 99 13 26 0 1197580816 57352112 3055200
13:19:42 66632 8192000000 10072018448 10560802816 1127728288 12.100 99 12 24 0 1181978656 51684272 2566096
13:21:59 63747 8192000000 10116456640 10590388224 1126065008 12.084 98 28 56 1 1197035024 65327872 5642144
13:24:04 25720 8192000000 10071123536 10512629760 1117859760 12.007 98 47 91 2 1155396112 28086384 9449968
13:25:44 66753 8192000000 10084036608 10605199360 1142005248 12.234 100 17 34 1 1191529312 45601952 3922112
13:27:55 37605 8192000000 10088054448 10540912640 1110038704 11.933 97 18 36 2 1161623264 47568832 4015728
13:29:33 38432 8192000000 10109300192 10562502656 1106172880 11.896 97 32 62 0 1172562912 59404800 6985232
13:31:15 71766 8192000000 10125619936 10591150080 1091660080 11.758 96 21 42 0 1153856976 57446672 4750224
13:33:34 118633 8192000000 10037602768 10490499072 1076898624 11.618 96 22 44 0 1122705968 40371856 5435488
13:36:34 45269 8192000000 9412764896 9767301120 542693168 6.213 50 1 2 0 559210000 16481424 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-08-2021 13:39:35 to 18-08-2021 13:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 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 18-08-2021 13:39:51 to 18-08-2021 13:39:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239242
MON$OLDEST_ACTIVE               239243
MON$OLDEST_SNAPSHOT             239243
MON$NEXT_TRANSACTION            239243
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-30 07:30:08.6030 GMT
MON$PAGES                       2048672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Wed Aug 18 13:37:44 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:235: 
:236: 
:237: mscs-test-161.budzdorov.ru    Wed Aug 18 13:37:44 2021
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
--- end of diff output ---

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

18.08.21 13:39:54. There are 3 logs in /var/tmp/logs-oltp40 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-18 13:39:57 No severe PSQL-related problems occured
18.08.21 13:39:58. End of report.