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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16284512 kB
MemAvailable 30987176 kB
Buffers 0 kB
Cached 15424124 kB
SwapCached 3644 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-22 19:37:47
DTS_END                         2021-08-22 19:37:47.4110
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(57:17)
IP                              ::1/58678
TRN_ID                          230659
ATT_ID                          608
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: 9348.45 21673 1121814
customer order: creation 161.78 361 19414
customer order: refuse 14.28 382 1713
order to supplier: creation 43.50 1232 5220
order to supplier: removal 3.61 97396 433
invoice (draft): creation 44.61 3210 5353
invoice (draft): removal 53.91 751 6469
invoice accept: apply 40.37 16755 4844
invoice accept: cancel 49.03 783 5883
customer reserve: creation 3815.92 161 457910
customer reserve: removal 4833.46 57 580015
realization accept: apply 79.53 189 9544
realization accept: cancel 85.21 99 10225
payment from customer: creation 56.23 129 6747
payment from customer: removal 5.18 77 622
payment to supplier: creation 47.55 128 5706
payment to supplier: removal 3.43 78 411
service: total inventory turnovers 4.30 1286 516
service: total monetary turnovers 5.78 21 693
service: aggregate perf. data 0.77 19017 92
service: refresh index statistics 0.03 291354 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-08-2021 19:39:44 to 22-08-2021 19:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1281
TEST_TIME 2 2479
TEST_TIME 3 3306
TEST_TIME 4 4005
TEST_TIME 5 4446
TEST_TIME 6 4501
TEST_TIME 7 4492
TEST_TIME 8 4634
TEST_TIME 9 4718
TEST_TIME 10 5078
TEST_TIME 11 5395
TEST_TIME 12 5487
TEST_TIME 13 5595
TEST_TIME 14 5693
TEST_TIME 15 6017
TEST_TIME 16 6081
TEST_TIME 17 6434
TEST_TIME 18 6995
TEST_TIME 19 7187
TEST_TIME 20 7668
TEST_TIME 21 7950
TEST_TIME 22 8559
TEST_TIME 23 9067
TEST_TIME 24 9203
TEST_TIME 25 9166
TEST_TIME 26 9312
TEST_TIME 27 9561
TEST_TIME 28 9672
TEST_TIME 29 9912
TEST_TIME 30 9819
TEST_TIME 31 10017
TEST_TIME 32 10053
TEST_TIME 33 10069
TEST_TIME 34 10106
TEST_TIME 35 10068
TEST_TIME 36 9992
TEST_TIME 37 9760
TEST_TIME 38 9707
TEST_TIME 39 9718
TEST_TIME 40 9554
TEST_TIME 41 9566
TEST_TIME 42 9697
TEST_TIME 43 9562
TEST_TIME 44 9652
TEST_TIME 45 9578
TEST_TIME 46 9464
TEST_TIME 47 9339
TEST_TIME 48 9458
TEST_TIME 49 9452
TEST_TIME 50 9642
TEST_TIME 51 9666
TEST_TIME 52 9651
TEST_TIME 53 9651
TEST_TIME 54 9595
TEST_TIME 55 9774
TEST_TIME 56 9755
TEST_TIME 57 9752
TEST_TIME 58 9801
TEST_TIME 59 9881
TEST_TIME 60 9912
TEST_TIME 61 9929
TEST_TIME 62 9974
TEST_TIME 63 9839
TEST_TIME 64 9768
TEST_TIME 65 9739
TEST_TIME 66 9704
TEST_TIME 67 9665
TEST_TIME 68 9607
TEST_TIME 69 9587
TEST_TIME 70 9583
TEST_TIME 71 9509
TEST_TIME 72 9468
TEST_TIME 73 9495
TEST_TIME 74 9470
TEST_TIME 75 9434
TEST_TIME 76 9430
TEST_TIME 77 9326
TEST_TIME 78 9306
TEST_TIME 79 9270
TEST_TIME 80 9319
TEST_TIME 81 9308
TEST_TIME 82 9349
TEST_TIME 83 9382
TEST_TIME 84 9385
TEST_TIME 85 9434
TEST_TIME 86 9465
TEST_TIME 87 9445
TEST_TIME 88 9564
TEST_TIME 89 9580
TEST_TIME 90 9549
TEST_TIME 91 9575
TEST_TIME 92 9501
TEST_TIME 93 9466
TEST_TIME 94 9416
TEST_TIME 95 9429
TEST_TIME 96 9431
TEST_TIME 97 9410
TEST_TIME 98 9376
TEST_TIME 99 9410
TEST_TIME 100 9442
TEST_TIME 101 9448
TEST_TIME 102 9452
TEST_TIME 103 9500
TEST_TIME 104 9486
TEST_TIME 105 9517
TEST_TIME 106 9514
TEST_TIME 107 9554
TEST_TIME 108 9593
TEST_TIME 109 9594
TEST_TIME 110 9535
TEST_TIME 111 9498
TEST_TIME 112 9485
TEST_TIME 113 9489
TEST_TIME 114 9476
TEST_TIME 115 9436
TEST_TIME 116 9426
TEST_TIME 117 9418
TEST_TIME 118 9359
TEST_TIME 119 9361
TEST_TIME 120 9349

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-08-2021 19:39:48 to 22-08-2021 19:39:51.

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 1545046 1545046 0 0.00 0 16932 6 0 0
doc_list_biud 1545046 1545046 0 0.00 0 50111 27 0 0
sp_add_invoice_to_stock 4916 4844 72 1.46 41 201162 16755 0 72
sp_cancel_adding_invoice 5883 5883 0 0.00 4 75180 783 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 12701 383 0 0
sp_cancel_customer_reserve 580015 580015 0 0.00 1 50112 57 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 5988 78 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 5603 78 0 0
sp_cancel_supplier_invoice 6472 6469 3 0.04 2 15167 752 0 3
sp_cancel_supplier_order 433 433 0 0.00 6 970254 97397 0 0
sp_cancel_write_off 10225 10225 0 0.00 1 11902 100 0 0
sp_client_order 19414 19414 0 0.00 3 36031 361 0 0
sp_customer_reserve 457910 457910 0 0.00 0 65536 162 0 0
sp_fill_shopping_cart 29970 29970 0 0.00 1 8009 33 0 0
sp_fill_shopping_cart_clo_res 457909 457909 0 0.00 0 25926 14 0 0
sp_get_clo_for_invoice 4842 4842 0 0.00 0 21600 317 0 0
sp_kill_cost_storno 17141 17141 0 0.00 0 7011 54 0 0
sp_kill_qstorno_ret_qs2qd 1177157 1177157 0 0.00 0 50107 31 0 0
sp_kill_qty_storno 619123 619123 0 0.00 0 50110 67 0 0
sp_lock_selected_doc 19899 19899 0 0.00 0 8212 9 0 0
sp_make_cost_storno 26838 26838 0 0.00 0 11654 61 0 0
sp_multiply_rows_for_pdistr 26838 26838 0 0.00 0 9616 28 0 0
sp_multiply_rows_for_qdistr 19414 19414 0 0.00 0 33209 152 0 0
sp_pay_from_customer 6747 6747 0 0.00 4 11488 130 0 0
sp_pay_to_supplier 5706 5706 0 0.00 5 9127 128 0 0
sp_payment_common 12453 12453 0 0.00 1 9597 40 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 12694 351 0 0
sp_qd_handle_on_invoice_upd_sts 10724 10724 0 0.00 0 25729 347 0 0
sp_qd_handle_on_reserve_upd_sts 9544 9544 0 0.00 0 23265 59 0 0
sp_reserve_write_off 9544 9544 0 0.00 6 23297 190 0 0
sp_supplier_invoice 5353 5353 0 0.00 7 84334 3211 0 0
sp_supplier_order 5220 5220 0 0.00 5 29196 1233 0 0
srv_aggregate_perf_data 92 92 0 0.00 2198 102774 19017 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3323 130369 47346 0 0
srv_find_qd_qs_mism 1075349 1075349 0 0.00 0 16931 4 0 0
srv_make_invnt_saldo 516 516 0 0.00 33 62501 1286 0 0
srv_make_money_saldo 693 693 0 0.00 1 904 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 214655 372662 291354 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4844 4844 0 0.00 0 15706 184 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 5689 105 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 4952 15 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1114 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 4237 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 908 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1821 24 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 33219 1513 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 1837 6 0 0
t$perf-norm:sp_client_order 19414 19414 0 0.00 0 13764 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 7071 14 0 0
t$perf-norm:sp_pay_from_customer 6747 6747 0 0.00 0 10523 13 0 0
t$perf-norm:sp_pay_to_supplier 5706 5706 0 0.00 0 17636 19 0 0
t$perf-norm:sp_reserve_write_off 9544 9544 0 0.00 0 7992 14 0 0
t$perf-norm:sp_supplier_invoice 5353 5353 0 0.00 0 12342 30 0 0
t$perf-norm:sp_supplier_order 5220 5220 0 0.00 0 14217 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 414 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 516 516 0 0.00 0 2582 31 0 0
t$perf-norm:srv_make_money_saldo 693 693 0 0.00 0 6271 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9826 9682 144 1.46 0 8311 22 0 144
v_all_customers 35588 35588 0 0.00 0 25 1 0 0
v_all_suppliers 29638 29638 0 0.00 0 17 1 0 0
v_all_wares 38826 38826 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 3721 13 0 0
v_cancel_client_order 3426 3426 0 0.00 0 3692 8 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 668 4 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2228 9 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 4087 9 0 0
v_cancel_supplier_invoice 706 700 6 0.84 0 3659 28 0 6
v_cancel_supplier_order 866 866 0 0.00 0 661 6 0 0
v_cancel_write_off 1850 1850 0 0.00 0 6928 15 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 835 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 1737 10 0 0
v_max_id_ord_sup 5341 5341 0 0.00 0 4221 3 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 1050 3 0 0
v_max_non_paid_realizn 3564 3564 0 0.00 0 3817 5 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 2656 5 0 0
v_min_id_clo_res 962 962 0 0.00 0 1946 6 0 0
v_min_id_ord_sup 5341 5341 0 0.00 0 7991 6 0 0
v_min_non_paid_invoice 5706 5706 0 0.00 0 7467 24 0 0
v_min_non_paid_realizn 6747 6747 0 0.00 0 8074 30 0 0
v_our_firm 9604 9604 0 0.00 0 12 1 0 0
v_reserve_write_off 19088 19088 0 0.00 0 6648 5 0 0
x_lock_depdocs_on_canc_invoice 12002 12002 0 0.00 0 18194 111 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 5592 325 0 0
x_make_qstorno_1000_1200 5220 5220 0 0.00 2 29186 1160 0 0
x_make_qstorno_1200_2000 5353 5353 0 0.00 3 84326 3100 0 0
x_make_qstorno_2100_3300 457910 457910 0 0.00 0 65535 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-08-2021 19:39:52 to 22-08-2021 19:39:56.

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
17:07:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:52 35889 8192000000 10395034960 10716741632 1446544336 15.007 100 91 180 1 1481257728 6002176 28711216
17:10:35 19344 8192000000 10460655136 10797096960 1518539472 15.638 100 89 179 0 1555006048 8332640 28133936
17:12:03 11383 8192000000 10498052896 10844205056 1562916672 16.021 100 80 160 0 1597580688 9546784 25117232
17:13:18 11263 8192000000 10601136464 10957811712 1653325808 16.793 100 83 166 0 1689208016 11291552 24590656
17:14:33 9802 8192000000 10641811040 11018567680 1707010912 17.244 100 67 132 0 1737751168 11186304 19553952
17:15:46 5833 8192000000 10694471648 11070885888 1756307792 17.654 100 78 155 4 1791651232 12995200 22348240
17:16:53 4193 8192000000 10743819248 11124400128 1808027488 18.080 100 89 177 1 1844617280 12121968 24467824
17:18:00 3016 8192000000 10786686112 11172085760 1845375664 18.385 100 85 171 0 1882156080 12252224 24528192
17:19:04 1501 8192000000 10820698352 11211235328 1876219744 18.635 100 90 179 1 1914917904 12969984 25728176
17:20:09 6462 8192000000 10834008912 11222753280 1887440032 18.725 100 84 166 1 1924424768 13787120 23197616
17:21:16 2610 8192000000 10826661024 11229560832 1888859952 18.737 100 47 92 1 1910890688 8552080 13478656
17:22:19 5036 8192000000 10810657520 11233361920 1897924640 18.810 100 34 68 3 1915921296 8243168 9753488
17:23:25 5246 8192000000 10824562224 11248328704 1907047584 18.883 100 36 72 1 1924615280 7350496 10217200
17:24:35 59481 8192000000 10859144736 11268300800 1920154560 18.988 100 55 107 2 1940531216 5250016 15126640
17:26:35 13703 8192000000 10833051488 11267686400 1913587136 18.935 100 40 77 2 1940671600 16594992 10489472
17:27:49 6043 8192000000 10816220384 11247509504 1884716256 18.703 99 29 58 1 1899655904 7003792 7935856
17:28:56 67455 8192000000 10771684992 11203321856 1841694224 18.355 99 23 46 0 1854202352 5392784 7115344
17:31:04 5415 8192000000 10741995440 11155492864 1804812400 18.053 99 31 61 1 1819614992 5886848 8915744
17:32:10 4936 8192000000 10731623072 11139973120 1763404448 17.713 97 28 57 1 1777358832 5288544 8665840
17:33:15 52596 8192000000 10780326496 11176976384 1835669840 18.306 100 53 106 2 1857541888 5906928 15965120
17:35:08 6447 8192000000 10757248192 11183284224 1843257552 18.367 100 21 42 1 1854640048 5163024 6219472
17:36:15 8578 8192000000 10788425056 11202285568 1860650416 18.509 100 26 51 3 1872565152 4708160 7206576
17:37:26 89425 8192000000 10837541760 11257458688 1901542720 18.839 100 33 66 0 1916304048 5587968 9173360
17:40:10 13204 8192000000 10869516048 11292540928 1933113440 19.092 100 53 106 0 1957945808 10266400 14565968
17:41:26 12072 8192000000 10863263248 11298955264 1941160848 19.156 100 44 88 0 1965575840 10760928 13654064
17:42:43 18229 8192000000 10894884752 11325452288 1956086848 19.275 100 59 118 1 1988499904 15002352 17410704
17:44:17 17403 8192000000 10927049952 11352915968 1983660352 19.494 100 63 126 2 2021744032 19081120 19002560
17:45:55 5567 8192000000 10947782912 11374948352 1997702368 19.605 100 62 123 0 2038442256 21792320 18947568
17:47:07 9184 8192000000 10942469664 11380989952 1980651824 19.470 99 48 97 1 2022242432 26597568 14993040
17:48:21 9637 8192000000 10949029952 11390668800 1988407520 19.531 99 49 98 1 2029060432 25546032 15106880
17:49:44 7946 8192000000 10972951088 11402293248 2005912864 19.669 100 63 125 2 2057534368 32367312 19254192
17:50:57 7518 8192000000 10972213584 11405312000 2005164496 19.663 100 58 112 1 2056163088 34245264 16753328
17:52:13 3320 8192000000 11001334400 11427123200 2022608560 19.801 100 72 143 0 2084040384 39306736 22125088
17:53:21 4611 8192000000 10991722864 11411525632 1996887984 19.598 99 82 164 1 2059504000 37008624 25607392
17:54:32 7647 8192000000 10987943088 11412692992 1993841248 19.574 99 71 143 2 2058596192 42412400 22342544
17:55:44 11235 8192000000 10976737248 11415597056 1999792896 19.621 100 49 99 1 2061398672 44635504 16970272
17:57:08 17319 8192000000 10943269072 11402047488 1951141952 19.236 98 33 66 0 2006341312 44025040 11174320
17:58:38 19170 8192000000 10949329488 11396857856 1975021744 19.425 100 32 64 0 2029487968 42945968 11520256
18:00:16 17417 8192000000 10909831616 11352043520 1920250816 18.989 98 31 61 0 1962160768 32569104 9340848
18:01:38 7501 8192000000 10859079968 11289681920 1871513776 18.597 97 44 88 2 1899663376 15963984 12185616
18:02:50 21067 8192000000 10874962704 11329609728 1919716336 18.985 99 26 51 0 1949729424 22062112 7950976
18:04:41 69933 8192000000 10877652400 11332587520 1918776224 18.977 99 12 24 0 1958776240 35892096 4107920
18:06:57 42330 8192000000 10879322912 11325857792 1900158528 18.828 98 17 38 1 1921026736 15718960 5149248
18:08:41 67053 8192000000 10858681056 11325091840 1883463952 18.693 97 9 18 1 1905093232 19336384 2292896
18:10:52 123509 8192000000 10839634688 11292721152 1797050992 17.990 93 11 22 0 1819024016 19137280 2835744
18:14:03 91758 8192000000 10851504816 11287334912 1902970624 18.850 99 38 74 0 1925195440 11767696 10457120
18:16:55 52301 8192000000 10876154752 11344592896 1931003440 19.075 99 15 29 1 1950625328 15163744 4458144
18:18:50 52480 8192000000 10907587952 11367616512 1969659296 19.383 100 46 92 2 1995084784 11123152 14302336
18:21:27 67678 8192000000 10895049264 11370143744 1965149088 19.347 100 7 14 0 1979095616 11975392 1971136
18:24:30 33150 8192000000 10928444672 11390685184 1979294944 19.459 100 17 34 0 2006293440 21542592 5455904
18:26:05 58795 8192000000 10920744912 11400945664 1965681248 19.351 99 25 50 0 1997253664 23742944 7829472
18:28:04 51069 8192000000 10916288656 11376893952 1946951040 19.202 98 27 52 0 1962250912 7594048 7705824
18:29:57 41600 8192000000 10936427008 11400146944 1989806896 19.542 100 32 64 1 2013554976 14337184 9410896
18:31:42 53153 8192000000 10931849136 11404701696 1991307824 19.554 100 13 26 0 2010530480 15774448 3448208
18:33:35 36263 8192000000 10942662864 11398275072 1979997264 19.465 99 21 42 0 1998190096 12363440 5829392
18:35:17 81046 8192000000 10914494880 11397787648 1953235504 19.252 98 6 12 0 1970531296 15794512 1501280
18:37:39 98946 8192000000 10921716480 11399524352 1936040960 19.115 97 11 22 0 1951185552 12280928 2863664
18:40:26 71826 8192000000 10932437616 11390304256 1963236000 19.332 98 35 70 1 1982989616 9620208 10133408
18:42:49 19706 8192000000 10902000368 11380187136 1964677232 19.343 99 14 28 1 1979761440 11526688 3557520
18:44:17 55146 8192000000 10902521216 11374702592 1956438064 19.278 99 11 22 0 1968236512 9161952 2636496
18:47:05 79543 8192000000 10885238224 11370045440 1908211904 18.892 96 15 30 2 1927763968 14690912 4861152
18:49:25 72296 8192000000 10869592096 11346202624 1887548128 18.726 96 13 26 0 1905081600 13514896 4018576
18:51:47 51485 8192000000 10863710896 11328348160 1900031504 18.827 97 18 36 0 1914162992 8765904 5365584
18:53:52 57123 8192000000 10861217712 11336060928 1929775408 19.065 99 18 36 2 1940055040 5861104 4418528
18:55:59 47492 8192000000 10864739616 11340173312 1914214928 18.940 98 16 32 3 1926946512 8183712 4547872
18:57:52 48765 8192000000 10855791936 11324092416 1831741488 18.274 94 24 47 2 1846933520 8398384 6793648
19:00:09 45059 8192000000 10862272528 11335708672 1879669792 18.662 97 13 26 1 1891703456 8218880 3814784
19:01:56 51596 8192000000 10864496256 11346857984 1921556416 18.999 99 15 30 0 1939343344 13263056 4523872
19:03:58 29718 8192000000 10887858832 11342913536 1914429360 18.942 98 39 78 1 1935216832 9884272 10903200
19:05:29 104119 8192000000 10882241168 11346374656 1892017744 18.762 97 10 20 0 1908246992 13585616 2643632
19:08:13 77490 8192000000 10881240768 11352178688 1924399120 19.022 98 20 40 0 1939864096 10305680 5159296
19:10:45 75491 8192000000 10856030848 11330220032 1861207536 18.513 94 20 40 1 1877808624 10990864 5610224
19:13:03 66327 8192000000 10884143520 11353305088 1927108320 19.044 99 13 26 0 1940396368 9761984 3526064
19:15:17 16957 8192000000 10911292096 11371364352 1970521056 19.390 100 45 90 0 1993879520 9944608 13413856
19:16:41 74066 8192000000 10916060912 11406585856 1981711984 19.478 100 15 30 1 2002183136 15833392 4637760
19:18:56 58982 8192000000 10919359456 11404767232 1984736976 19.502 100 18 36 0 2004638496 15065792 4835728
19:21:01 44710 8192000000 10935544288 11400982528 1971549136 19.398 99 21 42 0 1988812736 11460272 5803328
19:23:08 39380 8192000000 10920673408 11371634688 1941644112 19.160 98 14 28 0 1957963040 12870624 3448304
19:24:52 130368 8192000000 10902214128 11383574528 1944668960 19.184 98 12 24 0 1973309664 24201072 4439632
19:28:28 81682 8192000000 10896493232 11382882304 1936691632 19.120 98 9 18 0 1955364016 16327808 2344576
19:30:50 21300 8192000000 10862754832 11342761984 1923164736 19.012 98 30 59 0 1939291968 7415344 8711888
19:32:11 76370 8192000000 10898883040 11374813184 1970401024 19.389 100 14 28 1 1985247008 10590144 4255840
19:34:37 60115 8192000000 10884394720 11370819584 1939320128 19.141 98 8 16 1 1952972192 11208832 2443232
19:36:38 57765 8192000000 10876524320 11358691328 1937751312 19.129 98 13 26 1 1952344064 10881280 3711472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-08-2021 19:39:58 to 22-08-2021 19:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-08-2021 19:40:13 to 22-08-2021 19:40:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229756
MON$OLDEST_ACTIVE               230881
MON$OLDEST_SNAPSHOT             230881
MON$NEXT_TRANSACTION            230881
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2026976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:275: 
:276: mscs-test-161.budzdorov.ru    Sun Aug 22 19:37:50 2021
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
:279: 
:280: mscs-test-161.budzdorov.ru    Sun Aug 22 19:37:52 2021
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Sun Aug 22 19:38:12 2021
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:286: 
--- end of diff output ---

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

22.08.21 19:40:16. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-22 19:40:19 No severe PSQL-related problems occured
22.08.21 19:40:20. End of report.