Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.2021 10: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 15830980 kB
MemAvailable 30949232 kB
Buffers 0 kB
Cached 15892616 kB
SwapCached 336 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-09-03 10:37:06
DTS_END                         2021-09-03 10:37:06.1730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51192
TRN_ID                          234133
ATT_ID                          698
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: 9213.13 20425 1105575
customer order: creation 173.75 387 20850
customer order: refuse 14.50 397 1740
order to supplier: creation 46.12 1475 5534
order to supplier: removal 3.40 127993 408
invoice (draft): creation 46.21 4164 5545
invoice (draft): removal 52.18 1011 6261
invoice accept: apply 42.48 19957 5098
invoice accept: cancel 47.30 972 5676
customer reserve: creation 3958.04 194 474965
customer reserve: removal 4522.38 75 542685
realization accept: apply 83.61 207 10033
realization accept: cancel 93.78 139 11253
payment from customer: creation 59.68 126 7161
payment from customer: removal 5.77 143 692
payment to supplier: creation 49.46 136 5935
payment to supplier: removal 3.56 75 427
service: total inventory turnovers 3.71 4252 445
service: total monetary turnovers 6.43 645 771
service: aggregate perf. data 0.77 24375 92
service: refresh index statistics 0.03 221783 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 10:38:55 to 03-09-2021 10:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7355
TEST_TIME 2 5452
TEST_TIME 3 5432
TEST_TIME 4 4996
TEST_TIME 5 4740
TEST_TIME 6 4534
TEST_TIME 7 4995
TEST_TIME 8 4764
TEST_TIME 9 5194
TEST_TIME 10 5326
TEST_TIME 11 5854
TEST_TIME 12 5812
TEST_TIME 13 6077
TEST_TIME 14 6368
TEST_TIME 15 6495
TEST_TIME 16 7002
TEST_TIME 17 7256
TEST_TIME 18 7653
TEST_TIME 19 7595
TEST_TIME 20 7679
TEST_TIME 21 7762
TEST_TIME 22 7888
TEST_TIME 23 7962
TEST_TIME 24 7904
TEST_TIME 25 8640
TEST_TIME 26 8811
TEST_TIME 27 9073
TEST_TIME 28 8909
TEST_TIME 29 9213
TEST_TIME 30 9408
TEST_TIME 31 10340
TEST_TIME 32 10987
TEST_TIME 33 11059
TEST_TIME 34 10846
TEST_TIME 35 11061
TEST_TIME 36 10947
TEST_TIME 37 10934
TEST_TIME 38 10783
TEST_TIME 39 10760
TEST_TIME 40 10657
TEST_TIME 41 10542
TEST_TIME 42 10428
TEST_TIME 43 10253
TEST_TIME 44 10214
TEST_TIME 45 10125
TEST_TIME 46 10102
TEST_TIME 47 10100
TEST_TIME 48 10021
TEST_TIME 49 10066
TEST_TIME 50 10093
TEST_TIME 51 10016
TEST_TIME 52 9965
TEST_TIME 53 9883
TEST_TIME 54 9768
TEST_TIME 55 9735
TEST_TIME 56 9618
TEST_TIME 57 9637
TEST_TIME 58 9697
TEST_TIME 59 9648
TEST_TIME 60 9670
TEST_TIME 61 9666
TEST_TIME 62 9795
TEST_TIME 63 9762
TEST_TIME 64 9800
TEST_TIME 65 9816
TEST_TIME 66 9921
TEST_TIME 67 9894
TEST_TIME 68 9855
TEST_TIME 69 9932
TEST_TIME 70 9927
TEST_TIME 71 9902
TEST_TIME 72 9968
TEST_TIME 73 9856
TEST_TIME 74 9795
TEST_TIME 75 9718
TEST_TIME 76 9681
TEST_TIME 77 9685
TEST_TIME 78 9611
TEST_TIME 79 9587
TEST_TIME 80 9541
TEST_TIME 81 9483
TEST_TIME 82 9562
TEST_TIME 83 9534
TEST_TIME 84 9503
TEST_TIME 85 9534
TEST_TIME 86 9544
TEST_TIME 87 9528
TEST_TIME 88 9582
TEST_TIME 89 9645
TEST_TIME 90 9663
TEST_TIME 91 9677
TEST_TIME 92 9632
TEST_TIME 93 9715
TEST_TIME 94 9726
TEST_TIME 95 9637
TEST_TIME 96 9575
TEST_TIME 97 9548
TEST_TIME 98 9516
TEST_TIME 99 9474
TEST_TIME 100 9434
TEST_TIME 101 9387
TEST_TIME 102 9427
TEST_TIME 103 9439
TEST_TIME 104 9424
TEST_TIME 105 9400
TEST_TIME 106 9419
TEST_TIME 107 9388
TEST_TIME 108 9414
TEST_TIME 109 9450
TEST_TIME 110 9479
TEST_TIME 111 9497
TEST_TIME 112 9478
TEST_TIME 113 9465
TEST_TIME 114 9391
TEST_TIME 115 9339
TEST_TIME 116 9348
TEST_TIME 117 9298
TEST_TIME 118 9280
TEST_TIME 119 9290
TEST_TIME 120 9216

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-09-2021 10:38:59 to 03-09-2021 10:39:02.

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 1544069 1544069 0 0.00 0 17764 8 0 0
doc_list_biud 1544069 1544069 0 0.00 0 29807 34 0 0
sp_add_invoice_to_stock 5208 5098 110 2.11 19 165749 19957 0 110
sp_cancel_adding_invoice 5676 5676 0 0.00 6 69936 972 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 9494 397 0 0
sp_cancel_customer_reserve 542685 542685 0 0.00 1 20236 75 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 14869 143 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 3148 75 0 0
sp_cancel_supplier_invoice 6268 6261 7 0.11 3 29855 1012 0 7
sp_cancel_supplier_order 408 408 0 0.00 6 1357603 127994 0 0
sp_cancel_write_off 11253 11253 0 0.00 1 13920 140 0 0
sp_client_order 20850 20850 0 0.00 4 40647 387 0 0
sp_customer_reserve 474965 474965 0 0.00 0 89756 195 0 0
sp_fill_shopping_cart 31902 31902 0 0.00 1 31357 47 0 0
sp_fill_shopping_cart_clo_res 474956 474956 0 0.00 0 28474 14 0 0
sp_get_clo_for_invoice 5089 5089 0 0.00 0 29784 346 0 0
sp_kill_cost_storno 18048 18048 0 0.00 0 9781 70 0 0
sp_kill_qstorno_ret_qs2qd 1103292 1103292 0 0.00 0 29806 41 0 0
sp_kill_qty_storno 583145 583145 0 0.00 0 29807 88 0 0
sp_lock_selected_doc 20962 20962 0 0.00 0 14862 6 0 0
sp_make_cost_storno 28218 28218 0 0.00 0 17098 62 0 0
sp_multiply_rows_for_pdistr 28218 28218 0 0.00 0 12620 34 0 0
sp_multiply_rows_for_qdistr 20850 20850 0 0.00 0 11135 181 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 9936 126 0 0
sp_pay_to_supplier 5935 5935 0 0.00 5 18078 136 0 0
sp_payment_common 13096 13096 0 0.00 1 12593 23 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 9488 371 0 0
sp_qd_handle_on_invoice_upd_sts 10765 10765 0 0.00 0 14099 418 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 18269 68 0 0
sp_reserve_write_off 10033 10033 0 0.00 6 21741 207 0 0
sp_supplier_invoice 5545 5545 0 0.00 6 91107 4164 0 0
sp_supplier_order 5534 5534 0 0.00 6 23457 1476 0 0
srv_aggregate_perf_data 92 92 0 0.00 2905 385373 24375 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1364 153256 43636 0 0
srv_find_qd_qs_mism 1056515 1056515 0 0.00 0 17091 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 74 103245 4252 0 0
srv_make_money_saldo 771 771 0 0.00 1 16083 645 0 0
srv_recalc_idx_stat 4 4 0 0.00 151664 396692 221783 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5098 5098 0 0.00 0 17893 182 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 5666 106 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 3081 13 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1957 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 476 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 674 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2493 25 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 23163 1788 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 7609 16 0 0
t$perf-norm:sp_client_order 20850 20850 0 0.00 0 6754 22 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 5289 19 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 14967 12 0 0
t$perf-norm:sp_pay_to_supplier 5935 5935 0 0.00 0 15380 14 0 0
t$perf-norm:sp_reserve_write_off 10033 10033 0 0.00 0 11044 12 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 5394 20 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 2505 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 765 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2908 45 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 1470 17 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 3221 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 13 7 0 0
v_add_invoice_to_stock 10398 10178 220 2.11 0 5424 19 0 220
v_all_customers 38368 38368 0 0.00 0 22 1 0 0
v_all_suppliers 30694 30694 0 0.00 0 19 1 0 0
v_all_wares 41700 41700 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2645 13 0 0
v_cancel_client_order 3480 3480 0 0.00 0 2949 5 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1822 6 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1696 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 2020 6 0 0
v_cancel_supplier_invoice 858 844 14 1.63 0 2949 24 0 14
v_cancel_supplier_order 816 816 0 0.00 0 1730 8 0 0
v_cancel_write_off 2106 2106 0 0.00 0 3683 16 0 0
v_max_id_clo_ord 5520 5520 0 0.00 0 742 2 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 4595 21 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 8490 7 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 70 2 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 3461 5 0 0
v_min_id_clo_ord 5520 5520 0 0.00 0 3143 7 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 7826 19 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 7624 17 0 0
v_min_non_paid_invoice 5935 5935 0 0.00 0 8097 15 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 7898 15 0 0
v_our_firm 10496 10496 0 0.00 0 15 1 0 0
v_reserve_write_off 20066 20066 0 0.00 0 8510 8 0 0
x_lock_depdocs_on_canc_invoice 11515 11515 0 0.00 0 33599 128 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 7830 374 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 23428 1381 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 2 89996 3991 0 0
x_make_qstorno_2100_3300 474965 474965 0 0.00 0 89754 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-09-2021 10:39:03 to 03-09-2021 10:39:07.

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
08:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 44995 8192000000 9734118400 10034237440 808084448 8.978 100 88 176 1 837356240 8080304 21191488
08:09:58 18354 8192000000 9761419472 10067259392 836338688 9.263 100 89 178 1 870722064 13140240 21243136
08:11:32 15078 8192000000 9804882528 10122653696 869201440 9.592 100 85 169 0 910615504 22458144 18955920
08:12:54 10082 8192000000 9867966496 10204790784 910179984 9.999 100 73 146 0 988644800 62974496 15490320
08:14:09 9382 8192000000 9929801168 10273275904 963461536 10.523 100 79 156 1 1033386896 53920224 16005136
08:15:20 3658 8192000000 9970441168 10323664896 996180528 10.841 100 73 146 0 1081662160 70808176 14673456
08:16:25 5980 8192000000 9966516592 10334343168 1023000512 11.101 100 69 138 2 1092576656 56395072 13181072
08:17:35 1609 8192000000 10024594592 10397851648 1053594688 11.395 100 87 174 0 1143722176 72434960 17692528
08:18:38 2894 8192000000 10043333312 10417856512 1070653264 11.558 100 83 167 0 1154931456 67320112 16958080
08:19:42 3076 8192000000 10036491232 10430042112 1076337440 11.613 100 53 106 0 1161704704 75087536 10279728
08:20:47 30028 8192000000 9998987760 10380103680 1081372080 11.661 100 65 131 1 1105706880 9134368 15200432
08:22:17 10286 8192000000 10002664816 10403684352 1088104528 11.725 100 42 85 2 1120020496 23654992 8260976
08:23:28 5456 8192000000 10004930032 10407387136 1096158880 11.801 100 45 88 1 1123992144 18484880 9348384
08:24:36 5122 8192000000 10001490144 10402525184 1098336912 11.822 100 33 67 0 1115174800 9391472 7446416
08:25:42 43019 8192000000 10026328672 10412822528 1100110688 11.839 100 70 141 1 1124547312 8506816 15929808
08:27:26 9303 8192000000 10029890592 10444320768 1097675088 11.816 100 32 64 0 1140641696 36385328 6581280
08:28:37 8236 8192000000 9990366496 10391683072 1042645824 11.290 97 45 88 2 1065293760 13155824 9492112
08:29:49 6791 8192000000 9942929184 10334375936 1023452464 11.105 97 39 78 0 1047761280 15337936 8970880
08:30:57 26823 8192000000 9944349632 10337808384 912218448 10.019 88 28 54 0 934385072 16373920 5792704
08:32:26 7973 8192000000 9916157360 10301083648 966789216 10.555 94 36 72 0 990853696 15844448 8220032
08:33:34 6122 8192000000 9947093872 10333896704 1013513856 11.009 98 51 99 0 1032375408 7768736 11092816
08:34:43 59035 8192000000 9957313440 10369097728 1043489184 11.298 100 23 46 1 1070841088 22785248 4566656
08:36:43 4920 8192000000 9988590672 10377072640 1058872736 11.446 100 47 93 3 1078329072 9219392 10236944
08:37:54 23815 8192000000 10028788480 10420719616 1090710640 11.749 100 48 95 2 1119255120 19363808 9180672
08:39:25 39043 8192000000 10069633872 10469904384 1109337376 11.926 100 56 113 2 1177660128 56575952 11746800
08:41:07 104431 8192000000 10134390144 10551422976 1125943840 12.083 100 42 83 1 1236307408 100610752 9752816
08:44:13 28130 8192000000 10150688432 10585591808 1140510944 12.220 100 40 79 1 1267972400 118717600 8743856
08:45:45 29244 8192000000 10192798960 10606125056 1151714464 12.326 100 55 110 1 1298648880 134651824 12282592
08:47:19 8893 8192000000 10213678336 10633895936 1157598736 12.381 100 52 104 0 1320880432 151568656 11713040
08:48:36 1540 8192000000 10262812336 10673954816 1173383824 12.528 100 86 173 0 1362960112 171553184 18023104
08:49:41 3945 8192000000 10300402816 10716078080 1186286048 12.649 100 72 144 0 1401016592 198654096 16076448
08:50:51 2369 8192000000 10279783616 10690981888 1175481152 12.548 100 70 135 1 1382317328 191634256 15201920
08:52:00 11714 8192000000 10320523888 10741731328 1176826528 12.561 100 60 118 0 1423840304 233683264 13330512
08:53:16 4275 8192000000 10351186288 10779529216 1172347728 12.519 100 81 162 2 1451242432 260907600 17987104
08:54:26 3081 8192000000 10343643120 10761764864 1164180416 12.442 100 70 135 1 1444243776 264371696 15691664
08:55:37 2078 8192000000 10296406336 10726346752 1163222672 12.433 100 83 162 2 1399279872 217570496 18486704
08:56:47 2856 8192000000 10366542608 10771587072 1164801184 12.448 100 81 162 0 1458883040 275942944 18138912
08:57:56 3470 8192000000 10374603312 10779172864 1143966112 12.253 100 78 157 0 1470291488 308315344 18010032
08:59:04 1363 8192000000 10448588512 10858668032 1160608800 12.409 100 75 150 2 1546698336 368246144 17843392
09:00:11 2095 8192000000 10487082048 10893787136 1169325264 12.491 100 82 164 2 1582524944 393230960 19968720
09:01:21 6398 8192000000 10516398752 10922627072 1157354320 12.378 99 85 169 1 1603292176 424925088 21012768
09:02:32 7522 8192000000 10442571216 10893152256 1160104032 12.404 99 44 88 0 1532744848 361196656 11444160
09:03:43 11884 8192000000 10467795232 10924367872 1178502704 12.576 100 42 84 0 1554047808 364297216 11247888
09:05:08 16863 8192000000 10468917088 10919018496 1180384144 12.594 100 31 62 2 1567145008 378808176 7952688
09:07:15 12400 8192000000 10456573376 10937962496 1145151632 12.264 100 27 54 3 1562786048 410375488 7258928
09:08:32 20907 8192000000 10265949872 10787635200 1153558496 12.343 100 17 34 0 1400323504 242017968 4747040
09:09:54 47463 8192000000 10096636832 10554748928 1101692832 11.854 96 17 34 1 1158891408 53393888 3804688
09:11:45 98282 8192000000 10053762272 10502488064 1096754096 11.807 96 17 35 0 1155827040 55609248 3463696
09:14:25 67411 8192000000 10065334624 10510544896 1098911824 11.827 96 16 32 0 1130427008 28690640 2824544
09:16:39 30653 8192000000 10070506320 10504433664 1123080976 12.056 99 19 38 0 1162590080 35594400 3914704
09:18:13 60007 8192000000 10092731584 10541858816 1122984432 12.055 99 24 48 1 1177550080 49137488 5428160
09:21:16 112210 8192000000 10085760768 10522796032 1119831600 12.025 98 48 95 2 1164236704 33919488 10485616
09:24:09 41837 8192000000 10090008624 10546937856 1119769552 12.025 98 33 66 2 1168608320 42099120 6739648
09:25:53 56122 8192000000 10124391184 10612649984 1133248704 12.152 99 32 64 2 1194760752 54656352 6855696
09:28:16 56893 8192000000 10059919952 10523992064 1131907600 12.139 99 22 44 1 1173104848 36620704 4576544
09:30:21 58417 8192000000 10097810752 10551009280 1121281760 12.039 98 19 38 1 1175503600 49798336 4423504
09:32:25 81353 8192000000 10121469728 10566123520 1121209632 12.038 98 27 54 2 1197325824 70411744 5704448
09:35:14 56053 8192000000 10071776720 10519715840 1110863616 11.941 98 10 20 0 1156470192 43343136 2263440
09:37:14 53789 8192000000 10091270960 10585128960 1092784352 11.769 96 13 26 2 1185255808 89739872 2731584
09:39:09 42990 8192000000 10070117168 10553765888 1121171280 12.038 99 23 46 0 1192311168 65692880 5447008
09:40:58 15880 8192000000 10114067696 10552070144 1121442816 12.041 99 26 51 2 1185469152 58715200 5311136
09:42:14 56296 8192000000 10096739264 10556203008 1127927552 12.102 99 34 67 1 1210918144 76362224 6628368
09:44:11 35219 8192000000 10053401296 10475372544 1065673040 11.511 95 30 60 0 1097514880 26261104 5580736
09:45:48 46071 8192000000 10061910176 10503684096 1113536480 11.966 100 28 56 2 1171118992 51904784 5677728
09:47:41 30028 8192000000 10092835456 10520932352 1128233088 12.105 99 32 64 0 1175233232 39734048 7266096
09:49:12 71211 8192000000 10057834480 10534543360 1094101136 11.782 96 14 28 1 1144654544 47789440 2763968
09:51:25 54067 8192000000 10083353216 10523627520 1112698016 11.958 98 25 50 1 1141323232 23336448 5288768
09:53:21 1966 8192000000 10095267408 10532454400 1137258208 12.190 100 39 78 0 1194707008 48950544 8498256
09:54:23 57875 8192000000 10080437584 10550296576 1089200784 11.735 96 18 36 2 1172156032 79081728 3873520
09:56:22 28939 8192000000 10091074640 10559123456 1122266480 12.048 100 29 56 1 1186479488 58009184 6203824
09:57:56 83957 8192000000 10113579856 10600808448 1129353392 12.115 100 15 30 2 1215521664 82657920 3510352
10:00:40 75477 8192000000 10078854096 10514980864 1060342928 11.460 94 23 46 0 1108417712 42972144 5102640
10:02:56 74226 8192000000 10086864864 10563366912 1100251680 11.840 98 10 20 0 1145791312 43629552 1910080
10:05:16 50242 8192000000 10124436016 10617491456 1121435584 12.041 99 17 34 3 1269289088 143882192 3971312
10:07:07 52179 8192000000 10090688096 10548035584 1124761968 12.072 99 32 62 0 1179161568 48328048 6071552
10:09:00 31772 8192000000 10102802672 10533847040 1134031488 12.159 99 42 84 0 1181034064 39144400 7858176
10:10:32 54786 8192000000 10109267808 10638962688 1142709856 12.241 99 22 44 2 1239863376 92938000 4215520
10:12:43 69284 8192000000 10080202992 10517499904 1129482016 12.116 98 29 58 1 1160829136 25344368 6002752
10:14:53 70445 8192000000 10059815552 10509606912 1096418448 11.804 96 17 34 1 1156079872 56050608 3610816
10:17:04 57846 8192000000 10097351648 10559438848 1110214256 11.934 97 16 32 1 1173131184 59415312 3501616
10:19:42 47506 8192000000 10086075664 10539548672 1105100032 11.886 98 15 28 0 1161023056 53058512 2864512
10:21:30 35963 8192000000 10084748720 10543280128 1132425744 12.144 100 32 64 2 1188123840 49552384 6145712
10:23:17 48948 8192000000 10096930880 10550882304 1138369808 12.200 100 25 50 1 1196903392 52743824 5789760
10:25:09 116651 8192000000 10114825424 10570760192 1092090512 11.763 96 22 44 1 1152485184 55683840 4710832
10:28:09 153256 8192000000 10061426864 10566033408 1033429184 11.201 90 10 20 0 1101890528 66359232 2102112
10:31:42 65171 8192000000 10085964176 10525384704 1116287424 11.992 98 34 67 2 1166234880 43004896 6942560
10:33:48 77197 8192000000 10093528080 10557513728 1107967008 11.913 99 10 20 0 1189611744 79314800 2329936
10:36:05 62030 8192000000 9917138368 10380980224 1001617456 10.894 89 25 50 1 1062377616 55284720 5475440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-09-2021 10:39:25 to 03-09-2021 10:39:27.

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          233411
MON$OLDEST_ACTIVE               234366
MON$OLDEST_SNAPSHOT             234366
MON$NEXT_TRANSACTION            234366
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                       2079264
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Fri Sep  3 10:37:22 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

03.09.21 10:39:28. 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-09-03 10:39:31 No severe PSQL-related problems occured
03.09.21 10:39:32. End of report.