Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2021 04:37
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 16828172 kB
MemAvailable 31583148 kB
Buffers 20 kB
Cached 14785964 kB
SwapCached 0 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-11-13 04:37:08
DTS_END                         2021-11-13 04:37:08.8920
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/50112
TRN_ID                          232295
ATT_ID                          691
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: 8652.32 25477 1038278
customer order: creation 163.74 426 19649
customer order: refuse 13.68 326 1642
order to supplier: creation 44.34 1326 5321
order to supplier: removal 3.42 117579 410
invoice (draft): creation 44.34 3811 5321
invoice (draft): removal 49.15 950 5898
invoice accept: apply 39.37 18145 4724
invoice accept: cancel 43.98 866 5278
customer reserve: creation 3616.53 178 433983
customer reserve: removal 4351.94 73 522233
realization accept: apply 80.66 201 9679
realization accept: cancel 77.28 136 9273
payment from customer: creation 56.37 104 6764
payment from customer: removal 5.67 79 680
payment to supplier: creation 47.97 128 5756
payment to supplier: removal 3.26 104 391
service: total inventory turnovers 3.77 3250 452
service: total monetary turnovers 5.93 489 712
service: aggregate perf. data 0.89 13280 107
service: refresh index statistics 0.04 348092 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2021 04:37:53 to 13-11-2021 04:37:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4096
TEST_TIME 2 3340
TEST_TIME 3 3540
TEST_TIME 4 4054
TEST_TIME 5 3798
TEST_TIME 6 4227
TEST_TIME 7 4250
TEST_TIME 8 4753
TEST_TIME 9 4859
TEST_TIME 10 5130
TEST_TIME 11 5510
TEST_TIME 12 5776
TEST_TIME 13 6124
TEST_TIME 14 6363
TEST_TIME 15 6650
TEST_TIME 16 7027
TEST_TIME 17 7556
TEST_TIME 18 7899
TEST_TIME 19 8112
TEST_TIME 20 8347
TEST_TIME 21 8675
TEST_TIME 22 8661
TEST_TIME 23 9109
TEST_TIME 24 9109
TEST_TIME 25 11425
TEST_TIME 26 11197
TEST_TIME 27 10791
TEST_TIME 28 10763
TEST_TIME 29 10877
TEST_TIME 30 10612
TEST_TIME 31 10464
TEST_TIME 32 10238
TEST_TIME 33 10211
TEST_TIME 34 10013
TEST_TIME 35 9847
TEST_TIME 36 10051
TEST_TIME 37 9851
TEST_TIME 38 9772
TEST_TIME 39 9735
TEST_TIME 40 9635
TEST_TIME 41 9597
TEST_TIME 42 9620
TEST_TIME 43 9528
TEST_TIME 44 9429
TEST_TIME 45 9334
TEST_TIME 46 9247
TEST_TIME 47 9080
TEST_TIME 48 8991
TEST_TIME 49 9011
TEST_TIME 50 8981
TEST_TIME 51 9031
TEST_TIME 52 8928
TEST_TIME 53 8892
TEST_TIME 54 8888
TEST_TIME 55 8825
TEST_TIME 56 8822
TEST_TIME 57 8829
TEST_TIME 58 8839
TEST_TIME 59 8908
TEST_TIME 60 8897
TEST_TIME 61 8939
TEST_TIME 62 8961
TEST_TIME 63 8988
TEST_TIME 64 9030
TEST_TIME 65 8908
TEST_TIME 66 8805
TEST_TIME 67 8829
TEST_TIME 68 8752
TEST_TIME 69 8708
TEST_TIME 70 8700
TEST_TIME 71 8732
TEST_TIME 72 8666
TEST_TIME 73 8674
TEST_TIME 74 8709
TEST_TIME 75 8688
TEST_TIME 76 8823
TEST_TIME 77 8804
TEST_TIME 78 8781
TEST_TIME 79 8833
TEST_TIME 80 8821
TEST_TIME 81 8870
TEST_TIME 82 8887
TEST_TIME 83 8787
TEST_TIME 84 8741
TEST_TIME 85 8716
TEST_TIME 86 8679
TEST_TIME 87 8614
TEST_TIME 88 8587
TEST_TIME 89 8552
TEST_TIME 90 8567
TEST_TIME 91 8573
TEST_TIME 92 8595
TEST_TIME 93 8583
TEST_TIME 94 8548
TEST_TIME 95 8614
TEST_TIME 96 8686
TEST_TIME 97 8755
TEST_TIME 98 8738
TEST_TIME 99 8781
TEST_TIME 100 8769
TEST_TIME 101 8828
TEST_TIME 102 8840
TEST_TIME 103 8792
TEST_TIME 104 8748
TEST_TIME 105 8723
TEST_TIME 106 8681
TEST_TIME 107 8653
TEST_TIME 108 8611
TEST_TIME 109 8589
TEST_TIME 110 8550
TEST_TIME 111 8577
TEST_TIME 112 8588
TEST_TIME 113 8573
TEST_TIME 114 8568
TEST_TIME 115 8706
TEST_TIME 116 8682
TEST_TIME 117 8686
TEST_TIME 118 8660
TEST_TIME 119 8702
TEST_TIME 120 8653

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2021 04:37:56 to 13-11-2021 04:38:00.

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 1440768 1440768 0 0.00 0 20902 7 0 0
doc_list_biud 1440768 1440768 0 0.00 0 72804 33 0 0
sp_add_invoice_to_stock 4845 4724 121 2.49 39 232818 18146 0 121
sp_cancel_adding_invoice 5278 5278 0 0.00 5 76868 867 0 0
sp_cancel_client_order 1642 1642 0 0.00 4 18009 326 0 0
sp_cancel_customer_reserve 522233 522233 0 0.00 1 72811 74 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 4018 79 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 2 4118 105 0 0
sp_cancel_supplier_invoice 5907 5898 9 0.15 2 31025 950 0 9
sp_cancel_supplier_order 410 410 0 0.00 8 1205794 117580 0 0
sp_cancel_write_off 9273 9273 0 0.00 1 13008 136 0 0
sp_client_order 19649 19649 0 0.00 4 28819 426 0 0
sp_customer_reserve 433983 433983 0 0.00 0 41364 179 0 0
sp_fill_shopping_cart 30277 30277 0 0.00 1 18769 51 0 0
sp_fill_shopping_cart_clo_res 433980 433980 0 0.00 0 16799 18 0 0
sp_get_clo_for_invoice 4721 4721 0 0.00 0 37222 519 0 0
sp_kill_cost_storno 15622 15622 0 0.00 0 12998 63 0 0
sp_kill_qstorno_ret_qs2qd 1060047 1060047 0 0.00 0 72751 40 0 0
sp_kill_qty_storno 559134 559134 0 0.00 0 72804 85 0 0
sp_lock_selected_doc 19915 19915 0 0.00 0 7444 7 0 0
sp_make_cost_storno 26920 26920 0 0.00 0 14499 58 0 0
sp_multiply_rows_for_pdistr 26920 26920 0 0.00 0 8597 30 0 0
sp_multiply_rows_for_qdistr 19649 19649 0 0.00 0 26569 240 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 12783 104 0 0
sp_pay_to_supplier 5756 5756 0 0.00 5 19719 128 0 0
sp_payment_common 12520 12520 0 0.00 1 11650 24 0 0
sp_qd_handle_on_cancel_clo 1642 1642 0 0.00 0 18004 300 0 0
sp_qd_handle_on_invoice_upd_sts 9999 9999 0 0.00 0 58512 356 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 13159 68 0 0
sp_reserve_write_off 9679 9679 0 0.00 7 20932 201 0 0
sp_supplier_invoice 5321 5321 0 0.00 7 88163 3812 0 0
sp_supplier_order 5321 5321 0 0.00 6 81317 1327 0 0
srv_aggregate_perf_data 107 107 0 0.00 2561 120311 13281 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1189 166502 48277 0 0
srv_find_qd_qs_mism 993162 993162 0 0.00 0 20879 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 49 93602 3251 0 0
srv_make_money_saldo 712 712 0 0.00 1 15853 489 0 0
srv_recalc_idx_stat 5 5 0 0.00 257620 481249 348092 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4724 4724 0 0.00 0 18441 153 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 8745 142 0 0
t$perf-norm:sp_cancel_client_order 1642 1642 0 0.00 0 4388 17 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 800 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 356 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 884 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 538 13 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 16335 1337 0 0
t$perf-norm:sp_cancel_write_off 932 932 0 0.00 0 1931 6 0 0
t$perf-norm:sp_client_order 19649 19649 0 0.00 0 15642 20 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 9585 14 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 14362 17 0 0
t$perf-norm:sp_pay_to_supplier 5756 5756 0 0.00 0 6268 9 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 11024 11 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 5644 16 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 12742 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 181 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 171 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 694 12 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 8340 21 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 18 6 0 0
v_add_invoice_to_stock 9684 9442 242 2.49 0 3507 14 0 242
v_all_customers 35660 35660 0 0.00 0 97 1 0 0
v_all_suppliers 29732 29732 0 0.00 0 16 1 0 0
v_all_wares 39298 39298 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 425 6 0 0
v_cancel_client_order 3284 3284 0 0.00 0 1869 6 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1224 7 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 506 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1744 8 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 692 13 0 18
v_cancel_supplier_order 820 820 0 0.00 0 434 3 0 0
v_cancel_write_off 1864 1864 0 0.00 0 2007 12 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 4078 3 0 0
v_max_id_clo_res 937 937 0 0.00 0 4541 18 0 0
v_max_id_ord_sup 5307 5307 0 0.00 0 3050 6 0 0
v_max_non_paid_invoice 1518 1518 0 0.00 0 209 2 0 0
v_max_non_paid_realizn 3674 3674 0 0.00 0 7401 5 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 2417 6 0 0
v_min_id_clo_res 937 937 0 0.00 0 7453 31 0 0
v_min_id_ord_sup 5307 5307 0 0.00 0 8510 9 0 0
v_min_non_paid_invoice 5756 5756 0 0.00 0 9443 13 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 11646 18 0 0
v_our_firm 9818 9818 0 0.00 0 17 1 0 0
v_reserve_write_off 19358 19358 0 0.00 0 3658 4 0 0
x_lock_depdocs_on_canc_invoice 10780 10780 0 0.00 0 16900 127 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5272 323 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 2 81220 1254 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 4 88105 3606 0 0
x_make_qstorno_2100_3300 433983 433983 0 0.00 0 38666 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2021 04:38:01 to 13-11-2021 04:38:05.

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
02:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 35129 8192000000 9725611104 10024275968 796022656 8.856 100 89 178 0 825922752 7904560 21995536
02:10:08 9422 8192000000 9760765472 10065440768 830421152 9.203 100 89 178 0 866504896 14216976 21866768
02:11:23 6263 8192000000 9797221904 10115780608 867065120 9.571 100 83 166 0 910960048 25170928 18724000
02:12:33 10569 8192000000 9882687024 10215100416 916488432 10.061 100 78 156 1 992021952 58309632 17223888
02:13:51 8192 8192000000 9923541712 10269147136 959732448 10.486 100 79 158 1 1033171792 56770800 16668544
02:15:04 4466 8192000000 9954333168 10306174976 993443408 10.815 100 78 156 0 1063137376 54231952 15462016
02:16:10 3314 8192000000 10016860336 10376355840 1020510608 11.077 100 94 184 0 1127912496 88326480 19075408
02:17:15 1501 8192000000 10000372768 10367406080 1048276096 11.344 100 90 180 2 1115206240 48685824 18244320
02:18:19 1219 8192000000 10045848864 10416906240 1069553952 11.548 100 97 194 0 1168681776 78904128 20223696
02:19:21 8799 8192000000 9992442000 10390102016 1065823936 11.512 100 39 78 0 1117388416 42967344 8597136
02:20:34 5111 8192000000 9992996112 10397777920 1076749520 11.616 100 31 63 1 1124771296 41156464 6865312
02:21:41 8429 8192000000 10013146688 10403020800 1083847200 11.684 100 31 62 0 1123613456 33025264 6740992
02:22:50 60925 8192000000 9983783472 10390048768 1086958928 11.714 100 30 60 1 1109652800 14826624 7867248
02:24:51 6369 8192000000 10022456688 10426634240 1092275296 11.764 100 41 79 1 1116697856 15877904 8544656
02:26:01 10833 8192000000 9984602576 10387279872 1074020176 11.590 98 27 52 2 1099010800 18832576 6158048
02:27:12 5255 8192000000 10002250016 10393440256 1079508960 11.643 99 53 104 1 1100199440 9378816 11311664
02:28:21 32149 8192000000 9982730592 10393063424 1050603584 11.366 97 30 60 0 1091308464 34000864 6704016
02:29:56 2324 8192000000 9939355424 10310795264 1013106160 11.005 98 52 105 0 1050264464 23905152 13253152
02:30:59 7219 8192000000 9966759536 10352861184 1034207184 11.209 100 49 98 1 1063966496 18172672 11586640
02:32:08 5467 8192000000 9950079520 10343940096 1038980336 11.255 100 36 71 0 1068512288 20819856 8712096
02:33:18 33387 8192000000 9966639168 10369671168 1041344064 11.278 100 41 81 1 1082455280 33539552 7571664
02:34:53 5109 8192000000 9975667584 10368753664 1054928896 11.408 100 42 84 2 1081595680 16940752 9726032
02:36:01 6703 8192000000 9985502224 10381905920 1065310880 11.507 100 54 109 5 1088449616 11415312 11723424
02:37:15 44074 8192000000 10001390048 10399436800 1085957168 11.704 100 36 72 0 1108137440 14166176 8014096
02:39:13 108392 8192000000 10072528192 10491600896 1117889632 12.007 100 34 68 1 1185425088 60077600 7457856
02:42:12 34398 8192000000 10168145808 10577895424 1148137904 12.292 100 49 97 0 1262978400 103906304 10934192
02:43:53 29906 8192000000 10203442240 10623553536 1156862208 12.374 100 42 82 0 1309242880 142783024 9597648
02:45:26 9159 8192000000 10235289600 10649382912 1170906528 12.505 100 46 92 0 1345163328 163883376 10373424
02:46:43 5633 8192000000 10295490032 10702815232 1180334592 12.593 100 65 130 0 1400460848 205661504 14464752
02:47:51 3948 8192000000 10314908080 10726461440 1176094112 12.554 100 74 145 0 1425864304 232965824 16804368
02:49:00 1184 8192000000 10323295856 10730688512 1167096080 12.470 99 65 129 1 1415452688 233334368 15022240
02:50:05 1853 8192000000 10386088432 10790850560 1176260368 12.555 100 84 167 0 1485881232 290177328 19443536
02:51:10 2734 8192000000 10382822880 10782916608 1137825328 12.195 97 77 152 2 1461541392 305144704 18571360
02:52:17 1884 8192000000 10434284400 10833186816 1159237024 12.396 100 84 165 0 1529336848 350833728 19266096
02:53:23 3334 8192000000 10426599712 10843668480 1155673056 12.363 100 84 165 1 1532898512 357489344 19736112
02:54:31 7250 8192000000 10431099232 10843586560 1172453456 12.520 100 71 142 0 1526007312 336200544 17353312
02:55:43 17779 8192000000 10448417728 10879684608 1141412768 12.229 97 45 90 0 1530810864 377344160 12053936
02:57:08 9283 8192000000 10470841824 10919460864 1141304032 12.228 98 37 73 0 1554299488 403242128 9753328
02:58:36 7031 8192000000 10433138896 10894180352 1147416528 12.285 99 30 59 2 1522681072 367774928 7489616
02:59:45 36264 8192000000 10480244528 10957651968 1125060352 12.075 97 30 60 0 1579425712 446241376 8123984
03:01:25 22036 8192000000 10445039984 10889404416 1114777472 11.978 100 23 44 1 1530830016 409397472 6655072
03:02:48 17865 8192000000 10061856096 10498424832 1092336384 11.765 97 23 46 1 1147113408 50542640 4234384
03:04:10 88613 8192000000 10113688384 10570461184 1112394400 11.955 99 44 87 1 1214387952 92008576 9984976
03:06:42 21915 8192000000 10037549664 10496462848 1089115360 11.734 99 22 44 0 1125105664 31398688 4591616
03:08:14 50229 8192000000 10046943664 10496204800 1084656544 11.692 98 15 30 0 1136878432 49322288 2899600
03:10:08 61279 8192000000 10061398560 10519089152 1121753184 12.044 100 17 33 2 1171779232 46233200 3792848
03:12:35 166502 8192000000 10083015008 10527670272 1115279600 11.982 98 28 55 0 1188972528 68291504 5401424
03:16:26 40297 8192000000 10090130736 10554560512 1134630528 12.165 100 12 24 2 1181155536 44527936 1997072
03:18:08 33701 8192000000 10073850800 10505252864 1116458384 11.994 98 25 47 1 1169137232 47290336 5388512
03:19:42 23976 8192000000 10072726032 10524073984 1139435040 12.210 100 27 54 0 1187186704 42196640 5555024
03:21:18 56469 8192000000 10032009888 10482552832 1110101600 11.933 98 9 18 0 1137299648 25152672 2045376
03:23:18 63208 8192000000 10060668096 10523783168 1124930304 12.074 99 11 22 0 1155719344 28401152 2387888
03:25:21 33275 8192000000 10113068032 10563092480 1141572960 12.230 100 23 46 0 1222171232 75360096 5238176
03:26:57 77300 8192000000 10086290448 10564595712 1146041632 12.272 100 13 26 0 1222844528 74318960 2483936
03:29:17 50814 8192000000 10098341264 10540965888 1137817264 12.195 99 20 40 0 1193159776 50894176 4448336
03:31:09 59140 8192000000 10086709104 10541969408 1136109248 12.179 99 12 24 1 1201000848 62336352 2555248
03:33:09 79924 8192000000 10084231440 10543628288 1113734848 11.968 97 28 56 2 1175851456 55914640 6201968
03:35:33 35627 8192000000 10087362752 10527133696 1119829168 12.025 98 33 66 1 1191248496 64845472 6573856
03:37:11 24751 8192000000 10088959568 10516709376 1125503296 12.079 98 31 62 0 1160193664 27844480 6845888
03:38:39 79457 8192000000 10096384416 10570027008 1119737408 12.025 98 24 47 1 1167438480 43103520 4597552
03:40:59 87960 8192000000 10051423568 10482982912 1096849664 11.808 96 18 36 0 1154105888 53336400 3919824
03:43:45 27176 8192000000 10104330704 10533281792 1112285168 11.954 99 49 97 0 1170030928 47486080 10259680
03:45:12 69585 8192000000 10076875936 10542870528 1117206736 12.001 99 10 20 1 1183062736 63884416 1971584
03:47:22 58077 8192000000 10058499584 10518429696 1118133696 12.009 99 11 22 0 1161029072 40673456 2221920
03:49:27 30826 8192000000 10089062224 10514571264 1092311312 11.765 97 33 66 2 1148606224 49143744 7151168
03:51:03 58999 8192000000 10124732000 10575015936 1133603808 12.155 100 19 38 0 1210238960 72334080 4301072
03:53:02 51568 8192000000 10057940576 10566467584 1077444848 11.623 97 20 40 1 1186140784 104268208 4427728
03:54:54 56337 8192000000 10071276432 10486292480 1080500848 11.652 97 39 78 0 1125613376 37679264 7433264
03:56:54 60493 8192000000 10092935040 10540498944 1115536560 11.985 100 28 54 0 1181499904 60705152 5258192
03:59:00 98898 8192000000 10036814768 10478260224 1074172720 11.592 96 16 32 0 1111350512 33954048 3223744
04:01:44 24976 8192000000 10041267488 10475741184 1103551920 11.871 99 39 78 1 1136013584 23376128 9085536
04:03:11 41910 8192000000 10031516576 10476326912 1099098880 11.829 98 9 18 0 1131637424 30795472 1743072
04:04:54 57557 8192000000 10070320576 10530746368 1130387520 12.125 100 12 24 1 1170778640 37849152 2541968
04:06:53 69335 8192000000 10097699424 10568650752 1121250480 12.039 99 16 32 0 1165120720 40384336 3485904
04:09:05 26630 8192000000 10079740640 10527600640 1133985264 12.159 100 26 51 1 1175618192 35990576 5642352
04:10:32 45133 8192000000 10132948928 10563555328 1145392928 12.266 100 25 50 1 1204978736 54594160 4991648
04:12:28 74319 8192000000 10107097376 10615316480 1127365936 12.097 98 20 39 0 1240834080 109037152 4430992
04:14:44 55974 8192000000 10066102736 10499522560 1105056240 11.886 97 15 30 0 1141585008 33545856 2982912
04:16:48 39687 8192000000 10064703168 10494881792 1119882864 12.026 99 21 41 1 1152122736 27898544 4341328
04:18:28 120744 8192000000 10116681744 10605867008 1121830544 12.044 98 17 34 0 1223602384 98402080 3369760
04:23:58 76210 8192000000 10058132768 10503286784 1114830784 11.978 98 12 24 0 1145276528 27377648 3068096
04:26:14 72917 8192000000 10087690448 10555797504 1127780192 12.100 99 9 18 0 1176732496 47041472 1910832
04:28:27 56242 8192000000 10080380672 10552635392 1125310096 12.077 99 18 35 0 1189353664 59645824 4397744
04:30:24 54537 8192000000 10148339408 10588651520 1152398272 12.332 100 25 50 0 1252777888 94920816 5458800
04:32:34 134615 8192000000 10060192128 10509893632 1083742496 11.683 95 6 12 0 1110823872 26099552 981824
04:35:57 80164 8192000000 9260622304 9555349504 362704416 4.239 33 13 26 0 390648112 25364464 2579232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-11-2021 04:38:07 to 13-11-2021 04:38:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-11-2021 04:38:22 to 13-11-2021 04:38:24.

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          232294
MON$OLDEST_ACTIVE               232852
MON$OLDEST_SNAPSHOT             232852
MON$NEXT_TRANSACTION            232852
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                       2033568
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2658 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2658 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2658 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2658 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 ---
:109: 
:110: mscs-test-161.budzdorov.ru    Sat Nov 13 04:37:25 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Sat Nov 13 04:37:26 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
--- end of diff output ---

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

13.11.21 04:38:25. 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-11-13 04:38:27 No severe PSQL-related problems occured
13.11.21 04:38:29. End of report.