Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.2021 07: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 16095540 kB
MemAvailable 30867572 kB
Buffers 0 kB
Cached 15612468 kB
SwapCached 388 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-12 07:37:04
DTS_END                         2021-09-12 07:37:04.7080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50020
TRN_ID                          231590
ATT_ID                          634
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: 9055.89 24382 1086707
customer order: creation 165.23 439 19828
customer order: refuse 14.73 326 1767
order to supplier: creation 44.56 1393 5347
order to supplier: removal 3.38 117690 406
invoice (draft): creation 44.91 3256 5389
invoice (draft): removal 53.16 876 6379
invoice accept: apply 40.05 19857 4806
invoice accept: cancel 47.74 835 5729
customer reserve: creation 3678.38 198 441405
customer reserve: removal 4673.43 67 560812
realization accept: apply 80.08 184 9610
realization accept: cancel 87.57 126 10508
payment from customer: creation 56.41 115 6769
payment from customer: removal 5.29 83 635
payment to supplier: creation 46.59 139 5591
payment to supplier: removal 3.42 142 410
service: total inventory turnovers 3.83 3328 460
service: total monetary turnovers 6.15 526 738
service: aggregate perf. data 0.94 13516 113
service: refresh index statistics 0.04 324543 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-09-2021 07:38:30 to 12-09-2021 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6086
TEST_TIME 2 6354
TEST_TIME 3 6167
TEST_TIME 4 5819
TEST_TIME 5 5905
TEST_TIME 6 5531
TEST_TIME 7 5601
TEST_TIME 8 5775
TEST_TIME 9 5863
TEST_TIME 10 6676
TEST_TIME 11 6598
TEST_TIME 12 6659
TEST_TIME 13 6677
TEST_TIME 14 6870
TEST_TIME 15 7279
TEST_TIME 16 7620
TEST_TIME 17 8336
TEST_TIME 18 8162
TEST_TIME 19 8516
TEST_TIME 20 8885
TEST_TIME 21 9087
TEST_TIME 22 9136
TEST_TIME 23 9438
TEST_TIME 24 9679
TEST_TIME 25 9705
TEST_TIME 26 10356
TEST_TIME 27 11375
TEST_TIME 28 11035
TEST_TIME 29 10893
TEST_TIME 30 10667
TEST_TIME 31 10576
TEST_TIME 32 10432
TEST_TIME 33 10332
TEST_TIME 34 10238
TEST_TIME 35 10072
TEST_TIME 36 9937
TEST_TIME 37 9889
TEST_TIME 38 9786
TEST_TIME 39 9810
TEST_TIME 40 9691
TEST_TIME 41 9619
TEST_TIME 42 9499
TEST_TIME 43 9357
TEST_TIME 44 9288
TEST_TIME 45 9480
TEST_TIME 46 9475
TEST_TIME 47 9419
TEST_TIME 48 9377
TEST_TIME 49 9295
TEST_TIME 50 9523
TEST_TIME 51 9443
TEST_TIME 52 9335
TEST_TIME 53 9524
TEST_TIME 54 9486
TEST_TIME 55 9608
TEST_TIME 56 9588
TEST_TIME 57 9480
TEST_TIME 58 9374
TEST_TIME 59 9302
TEST_TIME 60 9280
TEST_TIME 61 9306
TEST_TIME 62 9220
TEST_TIME 63 9193
TEST_TIME 64 9160
TEST_TIME 65 9134
TEST_TIME 66 9091
TEST_TIME 67 9104
TEST_TIME 68 9060
TEST_TIME 69 9225
TEST_TIME 70 9242
TEST_TIME 71 9293
TEST_TIME 72 9381
TEST_TIME 73 9327
TEST_TIME 74 9394
TEST_TIME 75 9391
TEST_TIME 76 9398
TEST_TIME 77 9423
TEST_TIME 78 9380
TEST_TIME 79 9269
TEST_TIME 80 9209
TEST_TIME 81 9239
TEST_TIME 82 9207
TEST_TIME 83 9133
TEST_TIME 84 9134
TEST_TIME 85 9069
TEST_TIME 86 9006
TEST_TIME 87 8986
TEST_TIME 88 9021
TEST_TIME 89 9009
TEST_TIME 90 9031
TEST_TIME 91 8998
TEST_TIME 92 9023
TEST_TIME 93 9025
TEST_TIME 94 9070
TEST_TIME 95 9197
TEST_TIME 96 9164
TEST_TIME 97 9130
TEST_TIME 98 9299
TEST_TIME 99 9264
TEST_TIME 100 9244
TEST_TIME 101 9175
TEST_TIME 102 9138
TEST_TIME 103 9111
TEST_TIME 104 9121
TEST_TIME 105 9085
TEST_TIME 106 9064
TEST_TIME 107 9012
TEST_TIME 108 9027
TEST_TIME 109 9008
TEST_TIME 110 8985
TEST_TIME 111 8968
TEST_TIME 112 8933
TEST_TIME 113 8922
TEST_TIME 114 9037
TEST_TIME 115 9060
TEST_TIME 116 9132
TEST_TIME 117 9113
TEST_TIME 118 9095
TEST_TIME 119 9113
TEST_TIME 120 9059

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-09-2021 07:38:33 to 12-09-2021 07:38:36.

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 1496983 1496983 0 0.00 0 18648 7 0 0
doc_list_biud 1496982 1496982 0 0.00 0 57561 32 0 0
sp_add_invoice_to_stock 4939 4806 133 2.69 27 185384 19858 0 133
sp_cancel_adding_invoice 5729 5729 0 0.00 5 55259 836 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 9647 327 0 0
sp_cancel_customer_reserve 560812 560812 0 0.00 1 57562 68 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 4111 83 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6759 143 0 0
sp_cancel_supplier_invoice 6389 6379 10 0.15 2 33704 877 0 10
sp_cancel_supplier_order 406 406 0 0.00 13 1016197 117691 0 0
sp_cancel_write_off 10508 10508 0 0.00 1 15279 126 0 0
sp_client_order 19828 19828 0 0.00 4 30668 439 0 0
sp_customer_reserve 441405 441405 0 0.00 0 108018 199 0 0
sp_fill_shopping_cart 30554 30554 0 0.00 1 33841 46 0 0
sp_fill_shopping_cart_clo_res 441397 441397 0 0.00 0 15469 17 0 0
sp_get_clo_for_invoice 4798 4798 0 0.00 0 33868 364 0 0
sp_kill_cost_storno 17282 17282 0 0.00 0 7754 57 0 0
sp_kill_qstorno_ret_qs2qd 1138917 1138917 0 0.00 0 57556 36 0 0
sp_kill_qty_storno 600008 600008 0 0.00 0 57560 78 0 0
sp_lock_selected_doc 20064 20064 0 0.00 0 8810 7 0 0
sp_make_cost_storno 26768 26768 0 0.00 0 12199 53 0 0
sp_multiply_rows_for_pdistr 26767 26767 0 0.00 0 5377 29 0 0
sp_multiply_rows_for_qdistr 19828 19828 0 0.00 0 14915 234 0 0
sp_pay_from_customer 6769 6769 0 0.00 4 10195 116 0 0
sp_pay_to_supplier 5591 5591 0 0.00 4 11891 140 0 0
sp_payment_common 12360 12360 0 0.00 1 11865 20 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 9640 291 0 0
sp_qd_handle_on_invoice_upd_sts 10527 10527 0 0.00 0 31257 344 0 0
sp_qd_handle_on_reserve_upd_sts 9609 9609 0 0.00 0 21665 55 0 0
sp_reserve_write_off 9610 9610 0 0.00 7 21676 184 0 0
sp_supplier_invoice 5389 5389 0 0.00 6 154033 3257 0 0
sp_supplier_order 5347 5347 0 0.00 5 38793 1394 0 0
srv_aggregate_perf_data 113 113 0 0.00 2726 76831 13516 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2260 137922 46751 0 0
srv_find_qd_qs_mism 1041797 1041797 0 0.00 0 18647 7 0 0
srv_make_invnt_saldo 460 460 0 0.00 74 127393 3329 0 0
srv_make_money_saldo 738 738 0 0.00 1 16381 526 0 0
srv_recalc_idx_stat 5 5 0 0.00 145845 524723 324544 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4806 4806 0 0.00 0 12084 148 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 7403 133 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4969 19 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 2775 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 1095 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 241 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 559 13 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 20287 1339 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 854 3 0 0
t$perf-norm:sp_client_order 19828 19828 0 0.00 0 16918 20 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 1521 6 0 0
t$perf-norm:sp_pay_from_customer 6769 6769 0 0.00 0 6289 9 0 0
t$perf-norm:sp_pay_to_supplier 5591 5591 0 0.00 0 3277 8 0 0
t$perf-norm:sp_reserve_write_off 9610 9610 0 0.00 0 7001 10 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 11721 21 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 4562 16 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 4 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 170 3 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 645 11 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 3207 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 75 20 0 0
v_add_invoice_to_stock 9856 9590 266 2.69 0 7382 20 0 266
v_all_customers 36048 36048 0 0.00 0 18 1 0 0
v_all_suppliers 29692 29692 0 0.00 0 37 1 0 0
v_all_wares 39654 39654 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 4549 15 0 0
v_cancel_client_order 3534 3534 0 0.00 0 6459 6 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1300 6 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1183 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 5418 20 0 0
v_cancel_supplier_invoice 764 744 20 2.61 0 6421 30 0 20
v_cancel_supplier_order 812 812 0 0.00 0 8078 14 0 0
v_cancel_write_off 1976 1976 0 0.00 0 7885 24 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 671 1 0 0
v_max_id_clo_res 926 926 0 0.00 0 8201 22 0 0
v_max_id_ord_sup 5381 5381 0 0.00 0 4178 4 0 0
v_max_non_paid_invoice 1470 1470 0 0.00 0 105 2 0 0
v_max_non_paid_realizn 3628 3628 0 0.00 0 2705 3 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 3758 6 0 0
v_min_id_clo_res 926 926 0 0.00 0 2258 7 0 0
v_min_id_ord_sup 5381 5381 0 0.00 0 5791 8 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 11863 13 0 0
v_min_non_paid_realizn 6768 6768 0 0.00 0 2993 12 0 0
v_our_firm 9888 9888 0 0.00 0 95 1 0 0
v_reserve_write_off 19218 19218 0 0.00 0 9901 8 0 0
x_lock_depdocs_on_canc_invoice 11736 11736 0 0.00 0 29191 117 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 11324 394 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 38785 1315 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 3 140808 3079 0 0
x_make_qstorno_2100_3300 441405 441405 0 0.00 0 108016 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 07:38:37 to 12-09-2021 07:38:41.

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
05:07:08 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 31774 8192000000 9722793152 10021244928 804144144 8.938 100 87 173 1 832515584 7695488 20675952
05:09:51 20975 8192000000 9762458416 10066870272 833293552 9.232 100 87 174 0 867803200 13712960 20796688
05:11:15 24434 8192000000 9810902464 10130210816 869029504 9.590 100 85 170 0 914909504 26379696 19500304
05:12:47 13017 8192000000 9886157344 10216468480 913248960 10.029 100 79 158 0 992084416 61702848 17132608
05:14:01 11063 8192000000 9910522432 10257539072 953289248 10.423 100 79 158 1 1036444960 66275296 16880416
05:15:17 5151 8192000000 9960549600 10318626816 1007872752 10.955 100 74 148 3 1076438672 53225584 15340336
05:16:24 3322 8192000000 10005193712 10365116416 1034539712 11.212 100 80 158 1 1118188960 66793392 16855856
05:17:28 1941 8192000000 10017549136 10385522688 1058553968 11.443 100 87 170 1 1139400992 63106816 17740208
05:18:32 1446 8192000000 10046547920 10417238016 1064519616 11.500 100 91 181 1 1159844528 77383552 17941360
05:19:34 4483 8192000000 10008221200 10400395264 1068636720 11.539 100 45 89 1 1128311344 50782592 8892032
05:20:41 7084 8192000000 9998048512 10391834624 1078635152 11.634 100 32 65 0 1100174992 14619648 6920192
05:21:49 12661 8192000000 10015624544 10424307712 1085859280 11.703 100 29 57 2 1124388976 32777056 5752640
05:23:26 7755 8192000000 10023392784 10429546496 1089100752 11.734 100 32 62 3 1134660624 38996192 6563680
05:24:36 4074 8192000000 10030421888 10428248064 1101603968 11.853 100 51 103 2 1161514880 49082144 10828768
05:25:42 5383 8192000000 10015181056 10425888768 1098832128 11.827 100 26 52 1 1133983424 29712992 5438304
05:26:48 54317 8192000000 10014194352 10429341696 1088544128 11.729 99 32 65 0 1126902416 31782560 6575728
05:28:43 8618 8192000000 9960304736 10358628352 997431936 10.854 93 24 48 0 1032931392 30745232 4754224
05:29:53 3300 8192000000 9924870192 10302509056 984828624 10.731 96 40 79 2 1017776928 24582944 8365360
05:30:57 7645 8192000000 9942762192 10337648640 1026145552 11.131 100 36 71 0 1058058384 24436272 7476560
05:32:08 30182 8192000000 9937228432 10333618176 1008707040 10.963 97 28 55 1 1045539728 31653680 5179008
05:33:39 9275 8192000000 9960829792 10348990464 1026779120 11.137 99 59 120 1 1063691056 22272288 14639648
05:34:49 5643 8192000000 9970699936 10364559360 1058725904 11.444 100 49 99 3 1085076640 14982400 11368336
05:35:57 53734 8192000000 9984652112 10405806080 1068218352 11.535 100 22 44 0 1106092992 33395056 4479584
05:38:00 13565 8192000000 10035008560 10436034560 1097887264 11.818 100 50 100 0 1145174112 36514384 10772464
05:39:17 6401 8192000000 10057148416 10457833472 1114117856 11.971 100 41 81 0 1168969808 46621840 8230112
05:40:29 20130 8192000000 10073549632 10485235712 1131853152 12.139 100 57 113 2 1189743216 44334480 13555584
05:41:55 14447 8192000000 10167160592 10575687680 1146307984 12.275 100 66 132 1 1263667424 103820240 13539200
05:43:14 33734 8192000000 10186900576 10606559232 1154623616 12.353 100 42 84 3 1290327504 126426656 9277232
05:44:51 14787 8192000000 10195798416 10624692224 1170092112 12.498 100 39 77 0 1307940064 128978176 8869776
05:46:12 6140 8192000000 10269419312 10679046144 1177203472 12.564 100 67 132 1 1369553184 177721680 14628032
05:47:21 9021 8192000000 10268975824 10706329600 1176193840 12.555 100 63 125 0 1378708320 188445280 14069200
05:48:36 8316 8192000000 10277835264 10702749696 1179590496 12.586 100 49 98 0 1385021296 194288352 11142448
05:49:50 5136 8192000000 10327347296 10742521856 1195134400 12.731 100 73 145 0 1422682336 210882080 16665856
05:50:59 4322 8192000000 10375741984 10788085760 1178733344 12.578 99 73 144 1 1467507360 271768528 17005488
05:52:10 2749 8192000000 10385781440 10800005120 1184545312 12.633 100 84 167 0 1485728576 282270816 18912448
05:53:16 3940 8192000000 10404525248 10812399616 1167128336 12.470 100 78 156 1 1501675424 316384752 18162336
05:54:26 6946 8192000000 10370578208 10810245120 1158200432 12.386 100 56 112 2 1477272464 305573920 13498112
05:55:40 2259 8192000000 10349569472 10751176704 1133970384 12.159 99 82 165 3 1434660224 280826384 19863456
05:56:49 9060 8192000000 10348722816 10789359616 1159547408 12.399 100 34 68 1 1471464096 302909088 9007600
05:58:07 7183 8192000000 10350157920 10772525056 1145784176 12.270 100 40 80 4 1455632224 299243808 10604240
05:59:21 6607 8192000000 10360808656 10789539840 1142366080 12.238 99 43 86 0 1453255776 300840208 10049488
06:00:43 25365 8192000000 10269291744 10739535872 1119615984 12.023 98 25 50 0 1419738592 293442752 6679856
06:02:14 11249 8192000000 10312664752 10745090048 1132359456 12.144 100 38 77 0 1401102320 258719072 10023792
06:03:32 53667 8192000000 10141085376 10660581376 1105072736 11.886 99 12 24 0 1324278368 215902544 3303088
06:05:26 16850 8192000000 10042770400 10485792768 1083349088 11.679 97 48 97 0 1117016464 22783152 10884224
06:06:43 45013 8192000000 10049223760 10490507264 1095458224 11.795 99 28 56 0 1137218784 35896992 5863568
06:08:30 137921 8192000000 10072258720 10544848896 1093146816 11.773 98 12 24 0 1190315296 94629536 2538944
06:11:51 106685 8192000000 10098581456 10553651200 1076840144 11.617 95 17 34 1 1114024976 34142112 3042720
06:14:38 129100 8192000000 10040977600 10521518080 1086759488 11.712 97 23 46 2 1177698016 86151008 4787520
06:17:49 4437 8192000000 10053091504 10493632512 1124471632 12.069 100 30 60 2 1159614544 28179088 6963824
06:18:54 49199 8192000000 10097852224 10546151424 1134680704 12.165 100 28 56 2 1196362080 55783600 5897776
06:20:43 74450 8192000000 10150235488 10614296576 1146046672 12.272 100 17 34 1 1247205456 96845376 4313408
06:23:56 36691 8192000000 10091685744 10527293440 1117467728 12.003 98 39 78 1 1176116176 50259152 8389296
06:25:39 74439 8192000000 10151280496 10589368320 1110871936 11.941 97 22 44 0 1199411536 83773504 4766096
06:28:26 42066 8192000000 10121942976 10549407744 1142863520 12.242 100 43 84 0 1189833936 38602336 8368080
06:30:10 53197 8192000000 10086531072 10552262656 1144160240 12.255 100 22 44 0 1227169056 78650592 4358224
06:32:03 58480 8192000000 10084916752 10583814144 1106900384 11.903 97 30 60 0 1188138160 75296144 5941632
06:34:45 86862 8192000000 10123668144 10575364096 1126994016 12.093 98 29 56 1 1204713744 71586368 6133360
06:37:12 51045 8192000000 10091251296 10593337344 1109933296 11.932 97 16 30 0 1183741456 70778784 3029376
06:39:05 68633 8192000000 10060852656 10511192064 1115977040 11.989 98 12 25 0 1138097712 19730576 2390096
06:41:19 57154 8192000000 10062903632 10536755200 1129646832 12.118 99 3 6 0 1152369008 22352576 369600
06:43:17 62821 8192000000 10128156128 10586042368 1127400016 12.097 98 13 24 1 1164174272 34246848 2527408
06:45:27 49109 8192000000 10161165248 10626854912 1131982272 12.140 98 22 44 0 1237900640 100877280 5041088
06:47:31 95992 8192000000 10132772464 10613415936 1102192464 11.858 97 22 44 0 1164568512 58241040 4135008
06:50:07 64385 8192000000 10118314432 10558132224 1116344608 11.992 98 17 34 2 1195025376 75143424 3537344
06:52:13 24340 8192000000 10087158720 10507075584 1097644224 11.815 97 56 112 1 1151776576 42918720 11213632
06:53:43 52763 8192000000 10066758912 10512334848 1117489792 12.003 99 30 60 1 1180472496 57399584 5583120
06:55:38 102539 8192000000 10086898000 10549481472 1119062960 12.018 99 23 46 0 1167482640 43533360 4886320
06:58:59 98996 8192000000 10057709504 10565083136 1066371376 11.517 94 7 14 0 1121067328 53350624 1345328
07:01:38 33815 8192000000 10046232784 10494136320 1114871840 11.979 99 21 42 0 1148989200 29717696 4399664
07:03:17 91471 8192000000 10084441888 10552164352 1114699360 11.977 98 15 31 1 1181842672 63317520 3825792
07:05:49 32146 8192000000 10067661952 10518962176 1111328640 11.945 98 23 46 0 1151958128 35873488 4756000
07:07:22 66147 8192000000 10121438752 10593869824 1117886848 12.007 98 28 54 1 1194995200 71745968 5362384
07:09:56 69759 8192000000 10157167104 10619125760 1132025632 12.140 99 18 36 1 1234105408 98193360 3886416
07:12:06 75575 8192000000 10052689056 10501316608 1071363392 11.565 94 11 21 2 1117592048 44171744 2056912
07:14:30 58058 8192000000 10112711616 10650890240 1137266000 12.190 100 29 57 0 1259221328 116349888 5605440
07:16:31 32619 8192000000 10081119904 10512240640 1104060640 11.876 96 36 70 1 1125700192 14922560 6716992
07:18:08 98940 8192000000 10120203888 10580832256 1129545088 12.117 99 32 64 0 1200751344 64445424 6760832
07:21:02 54193 8192000000 10063637184 10552041472 1119115744 12.019 98 29 58 0 1179770096 54652848 6001504
07:23:03 66126 8192000000 10085440352 10556350464 1154841376 12.355 100 13 27 1 1188259056 30343088 3074592
07:25:11 75583 8192000000 10084226000 10556293120 1148638448 12.297 99 9 18 0 1198403792 48132096 1633248
07:27:27 44356 8192000000 10121507520 10568699904 1160631216 12.409 100 33 66 1 1210486752 42740928 7114608
07:29:16 79130 8192000000 10237575520 10687176704 1158436464 12.389 98 28 56 0 1319130272 154057184 6636624
07:31:43 93418 8192000000 10107064016 10626035712 1153574720 12.343 100 8 16 0 1273565632 117938816 2052096
07:34:20 42505 8192000000 10065808672 10495889408 1064289312 11.498 93 30 60 0 1092152272 22318336 5544624
07:36:07 23262 8192000000 10148255120 10580520960 1123958912 12.064 99 52 104 0 1194036480 59427936 10649632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-09-2021 07:38:43 to 12-09-2021 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 07:38:59 to 12-09-2021 07:39:01.

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          227432
MON$OLDEST_ACTIVE               231700
MON$OLDEST_SNAPSHOT             231700
MON$NEXT_TRANSACTION            231700
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                       2038240
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 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 ---
:38: 
:39: mscs-test-161.budzdorov.ru    Sun Sep 12 07:37:09 2021
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
:42: 
:43: mscs-test-161.budzdorov.ru    Sun Sep 12 07:37:22 2021
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Sep 12 07:37:23 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
--- end of diff output ---

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

12.09.21 07:39:02. 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-12 07:39:04 No severe PSQL-related problems occured
12.09.21 07:39:05. End of report.