Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.2021 16: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 16084760 kB
MemAvailable 31015216 kB
Buffers 0 kB
Cached 15546276 kB
SwapCached 2580 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-05 16:37:47
DTS_END                         2021-08-05 16:37:47.8820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42674
TRN_ID                          258981
ATT_ID                          690
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: 9669.10 22872 1160292
customer order: creation 186.33 311 22360
customer order: refuse 16.28 361 1954
order to supplier: creation 50.09 1169 6011
order to supplier: removal 3.56 112189 427
invoice (draft): creation 49.42 3247 5930
invoice (draft): removal 52.93 819 6351
invoice accept: apply 45.58 18445 5470
invoice accept: cancel 48.05 985 5766
customer reserve: creation 4170.97 184 500516
customer reserve: removal 4728.97 66 567476
realization accept: apply 90.43 172 10851
realization accept: cancel 83.88 111 10066
payment from customer: creation 65.11 98 7813
payment from customer: removal 6.09 81 731
payment to supplier: creation 52.91 113 6349
payment to supplier: removal 4.11 76 493
service: total inventory turnovers 5.40 1101 648
service: total monetary turnovers 8.02 27 962
service: aggregate perf. data 0.96 18480 115
service: refresh index statistics 0.03 299403 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 16:38:38 to 05-08-2021 16:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1763
TEST_TIME 2 3136
TEST_TIME 3 4277
TEST_TIME 4 4580
TEST_TIME 5 5961
TEST_TIME 6 6138
TEST_TIME 7 6445
TEST_TIME 8 6522
TEST_TIME 9 6239
TEST_TIME 10 6239
TEST_TIME 11 6272
TEST_TIME 12 6203
TEST_TIME 13 6173
TEST_TIME 14 6192
TEST_TIME 15 6275
TEST_TIME 16 6233
TEST_TIME 17 6509
TEST_TIME 18 6702
TEST_TIME 19 6619
TEST_TIME 20 6682
TEST_TIME 21 6810
TEST_TIME 22 7207
TEST_TIME 23 7711
TEST_TIME 24 7898
TEST_TIME 25 8397
TEST_TIME 26 8557
TEST_TIME 27 8816
TEST_TIME 28 9123
TEST_TIME 29 10242
TEST_TIME 30 10581
TEST_TIME 31 10414
TEST_TIME 32 10336
TEST_TIME 33 10611
TEST_TIME 34 10434
TEST_TIME 35 10699
TEST_TIME 36 10670
TEST_TIME 37 10502
TEST_TIME 38 10322
TEST_TIME 39 10269
TEST_TIME 40 10083
TEST_TIME 41 9921
TEST_TIME 42 10234
TEST_TIME 43 10340
TEST_TIME 44 10263
TEST_TIME 45 10169
TEST_TIME 46 10041
TEST_TIME 47 10141
TEST_TIME 48 10146
TEST_TIME 49 10127
TEST_TIME 50 10145
TEST_TIME 51 10063
TEST_TIME 52 9990
TEST_TIME 53 9936
TEST_TIME 54 9940
TEST_TIME 55 9798
TEST_TIME 56 9735
TEST_TIME 57 9728
TEST_TIME 58 9677
TEST_TIME 59 9651
TEST_TIME 60 9637
TEST_TIME 61 9629
TEST_TIME 62 9638
TEST_TIME 63 9738
TEST_TIME 64 9721
TEST_TIME 65 9794
TEST_TIME 66 9842
TEST_TIME 67 9989
TEST_TIME 68 9936
TEST_TIME 69 9922
TEST_TIME 70 9996
TEST_TIME 71 10040
TEST_TIME 72 10023
TEST_TIME 73 9946
TEST_TIME 74 9897
TEST_TIME 75 9987
TEST_TIME 76 9893
TEST_TIME 77 9844
TEST_TIME 78 9827
TEST_TIME 79 9800
TEST_TIME 80 9748
TEST_TIME 81 9705
TEST_TIME 82 9717
TEST_TIME 83 9676
TEST_TIME 84 9663
TEST_TIME 85 9632
TEST_TIME 86 9681
TEST_TIME 87 9648
TEST_TIME 88 9730
TEST_TIME 89 9731
TEST_TIME 90 9739
TEST_TIME 91 9758
TEST_TIME 92 9758
TEST_TIME 93 9784
TEST_TIME 94 9833
TEST_TIME 95 9815
TEST_TIME 96 9903
TEST_TIME 97 9889
TEST_TIME 98 9871
TEST_TIME 99 9796
TEST_TIME 100 9814
TEST_TIME 101 9759
TEST_TIME 102 9763
TEST_TIME 103 9713
TEST_TIME 104 9672
TEST_TIME 105 9640
TEST_TIME 106 9639
TEST_TIME 107 9637
TEST_TIME 108 9598
TEST_TIME 109 9591
TEST_TIME 110 9600
TEST_TIME 111 9613
TEST_TIME 112 9604
TEST_TIME 113 9621
TEST_TIME 114 9613
TEST_TIME 115 9645
TEST_TIME 116 9612
TEST_TIME 117 9635
TEST_TIME 118 9658
TEST_TIME 119 9675
TEST_TIME 120 9673

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-08-2021 16:38:42 to 05-08-2021 16:38:45.

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 1624298 1624298 0 0.00 0 39220 7 0 0
doc_list_biud 1624298 1624298 0 0.00 0 82494 29 0 0
sp_add_invoice_to_stock 5631 5470 161 2.85 45 165660 18446 0 161
sp_cancel_adding_invoice 5766 5766 0 0.00 5 75657 986 0 0
sp_cancel_client_order 1954 1954 0 0.00 5 15787 362 0 0
sp_cancel_customer_reserve 567476 567476 0 0.00 1 31224 66 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 6246 81 0 0
sp_cancel_pay_to_supplier 493 493 0 0.00 3 3708 76 0 0
sp_cancel_supplier_invoice 6362 6351 11 0.17 1 82502 819 0 11
sp_cancel_supplier_order 427 427 0 0.00 8 1261727 112189 0 0
sp_cancel_write_off 10066 10066 0 0.00 1 14809 112 0 0
sp_client_order 22360 22360 0 0.00 3 16136 312 0 0
sp_customer_reserve 500516 500516 0 0.00 0 78817 185 0 0
sp_fill_shopping_cart 34290 34290 0 0.00 1 13433 34 0 0
sp_fill_shopping_cart_clo_res 500507 500507 0 0.00 0 18317 12 0 0
sp_get_clo_for_invoice 5461 5461 0 0.00 0 21400 243 0 0
sp_kill_cost_storno 17056 17056 0 0.00 0 7116 60 0 0
sp_kill_qstorno_ret_qs2qd 1151796 1151796 0 0.00 0 82490 35 0 0
sp_kill_qty_storno 608352 608352 0 0.00 0 82492 77 0 0
sp_lock_selected_doc 22693 22693 0 0.00 0 7415 5 0 0
sp_make_cost_storno 30474 30474 0 0.00 0 11167 48 0 0
sp_multiply_rows_for_pdistr 30474 30474 0 0.00 0 5470 26 0 0
sp_multiply_rows_for_qdistr 22360 22360 0 0.00 0 10988 130 0 0
sp_pay_from_customer 7813 7813 0 0.00 4 11703 99 0 0
sp_pay_to_supplier 6349 6349 0 0.00 5 9487 113 0 0
sp_payment_common 14162 14162 0 0.00 1 11684 23 0 0
sp_qd_handle_on_cancel_clo 1954 1954 0 0.00 0 15780 321 0 0
sp_qd_handle_on_invoice_upd_sts 11227 11227 0 0.00 0 39499 381 0 0
sp_qd_handle_on_reserve_upd_sts 10851 10851 0 0.00 0 16081 61 0 0
sp_reserve_write_off 10851 10851 0 0.00 6 16128 173 0 0
sp_supplier_invoice 5930 5930 0 0.00 7 98269 3247 0 0
sp_supplier_order 6011 6011 0 0.00 5 25131 1170 0 0
srv_aggregate_perf_data 115 115 0 0.00 3618 134381 18481 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1381 139100 43984 0 0
srv_find_qd_qs_mism 1108641 1108641 0 0.00 0 39098 6 0 0
srv_make_invnt_saldo 648 648 0 0.00 50 62111 1101 0 0
srv_make_money_saldo 962 962 0 0.00 0 3324 28 0 0
srv_recalc_idx_stat 3 3 0 0.00 163716 378360 299403 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5470 5470 0 0.00 0 8782 162 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 4267 125 0 0
t$perf-norm:sp_cancel_client_order 1954 1954 0 0.00 0 3788 15 0 0
t$perf-norm:sp_cancel_customer_reserve 785 785 0 0.00 0 2782 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 1048 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 493 493 0 0.00 0 1339 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 1834 21 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 23940 1723 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 4428 15 0 0
t$perf-norm:sp_client_order 22360 22360 0 0.00 0 20631 19 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 6102 9 0 0
t$perf-norm:sp_pay_from_customer 7813 7813 0 0.00 0 6514 8 0 0
t$perf-norm:sp_pay_to_supplier 6349 6349 0 0.00 0 8568 14 0 0
t$perf-norm:sp_reserve_write_off 10851 10851 0 0.00 0 21979 13 0 0
t$perf-norm:sp_supplier_invoice 5930 5930 0 0.00 0 8089 22 0 0
t$perf-norm:sp_supplier_order 6011 6011 0 0.00 0 3298 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 322 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2035 33 0 0
t$perf-norm:srv_make_invnt_saldo 648 648 0 0.00 0 1672 26 0 0
t$perf-norm:srv_make_money_saldo 962 962 0 0.00 0 5834 24 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 11244 10922 322 2.86 0 4872 18 0 322
v_all_customers 40846 40846 0 0.00 0 29 1 0 0
v_all_suppliers 33014 33014 0 0.00 0 18 1 0 0
v_all_wares 44720 44720 0 0.00 0 110 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 1440 9 0 0
v_cancel_client_order 3908 3908 0 0.00 0 3392 7 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2400 9 0 0
v_cancel_customer_reserve 1570 1570 0 0.00 0 3408 11 0 0
v_cancel_payment_to_supplier 986 986 0 0.00 0 3469 13 0 0
v_cancel_supplier_invoice 902 880 22 2.43 0 1158 14 0 22
v_cancel_supplier_order 854 854 0 0.00 0 970 5 0 0
v_cancel_write_off 2174 2174 0 0.00 0 3497 15 0 0
v_max_id_clo_ord 6003 6003 0 0.00 0 415 1 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 3586 21 0 0
v_max_id_ord_sup 5927 5927 0 0.00 0 10656 5 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 1253 4 0 0
v_max_non_paid_realizn 4079 4079 0 0.00 0 1035 3 0 0
v_min_id_clo_ord 6003 6003 0 0.00 0 3611 6 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 4777 13 0 0
v_min_id_ord_sup 5927 5927 0 0.00 0 13099 12 0 0
v_min_non_paid_invoice 6349 6349 0 0.00 0 4425 10 0 0
v_min_non_paid_realizn 7813 7813 0 0.00 0 11671 14 0 0
v_our_firm 11342 11342 0 0.00 0 33 1 0 0
v_reserve_write_off 21702 21702 0 0.00 0 3211 5 0 0
x_lock_depdocs_on_canc_invoice 11677 11677 0 0.00 0 17269 136 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 11627 468 0 0
x_make_qstorno_1000_1200 6011 6011 0 0.00 2 25121 1090 0 0
x_make_qstorno_1200_2000 5930 5930 0 0.00 3 85160 3132 0 0
x_make_qstorno_2100_3300 500516 500516 0 0.00 0 78816 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 16:38:46 to 05-08-2021 16:38:50.

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
14:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:52 25389 8192000000 10368772640 10691088384 1419158656 14.765 100 88 176 1 1454143024 6176448 28807920
14:10:24 21523 8192000000 10433503376 10767003648 1485693440 15.351 100 84 168 1 1521494672 8673360 27127872
14:11:53 18483 8192000000 10491878784 10836754432 1546186000 15.877 100 84 168 0 1583098336 10247888 26664448
14:13:16 10365 8192000000 10571695584 10932068352 1637080752 16.655 100 79 158 0 1670325360 10171248 23073360
14:14:31 9528 8192000000 10646351968 11010441216 1702096368 17.203 100 84 166 1 1738709856 12789072 23824416
14:15:45 5551 8192000000 10692764192 11075964928 1762944160 17.709 100 64 128 1 1792173232 10854064 18375008
14:16:54 4641 8192000000 10741525584 11132338176 1813022800 18.121 100 71 140 1 1845132656 12342064 19767792
14:18:00 1931 8192000000 10805340544 11193188352 1862961568 18.527 100 93 185 0 1902625568 14079664 25584336
14:19:03 9515 8192000000 10832659632 11219558400 1882791968 18.688 100 84 171 3 1919508480 12507600 24208912
14:20:13 9746 8192000000 10789746912 11216097280 1881422656 18.677 100 31 62 2 1898264384 7919296 8922432
14:21:24 7668 8192000000 10801535808 11226771456 1891431344 18.757 100 35 70 1 1908827088 6964416 10431328
14:22:32 6168 8192000000 10817021200 11246731264 1902574832 18.847 100 33 66 0 1923621024 11279600 9766592
14:23:41 48538 8192000000 10857170768 11272540160 1913895056 18.938 100 51 102 0 1934251328 5341824 15014448
14:25:30 5954 8192000000 10829377472 11266629632 1917289296 18.965 100 33 66 2 1934106240 7414032 9402912
14:26:38 6258 8192000000 10835628448 11268943872 1916730672 18.961 100 24 47 1 1929397776 6160336 6506768
14:27:47 9902 8192000000 10801549920 11232792576 1872150800 18.602 99 25 51 1 1885079440 5756336 7172304
14:29:02 8863 8192000000 10725518928 11148746752 1749738384 17.599 94 30 60 1 1764591776 6114976 8738416
14:30:11 6730 8192000000 10709377440 11125592064 1761373328 17.696 98 30 60 1 1776604832 6420464 8811040
14:31:19 6574 8192000000 10721275728 11140710400 1791287536 17.942 99 28 56 1 1806382880 5868864 9226480
14:32:26 10298 8192000000 10755681216 11187003392 1837576528 18.321 100 21 43 1 1854307504 10933152 5797824
14:33:39 19502 8192000000 10760707344 11195805696 1854272064 18.457 100 30 60 2 1869450128 6013072 9164992
14:35:00 8929 8192000000 10784707952 11213348864 1869646288 18.581 100 32 62 2 1885507104 6204336 9656480
14:36:11 6693 8192000000 10802326464 11235352576 1885861680 18.712 100 22 43 0 1897950992 6171792 5917520
14:37:18 12081 8192000000 10813864336 11248574464 1901257184 18.836 100 11 22 0 1908488272 4379936 2851152
14:38:31 55050 8192000000 10865483024 11291369472 1929271600 19.061 100 40 80 1 1951658496 11157456 11229440
14:40:29 8118 8192000000 10884346496 11310268416 1950223792 19.228 100 57 113 2 1977315872 10753408 16338672
14:41:40 31303 8192000000 10904548432 11329728512 1965591440 19.350 100 62 120 1 1994435232 12247936 16595856
14:43:16 13137 8192000000 10912463184 11341451264 1970549600 19.390 100 49 96 1 1995143008 11264672 13328736
14:44:33 5676 8192000000 10913597136 11342516224 1970818688 19.392 100 52 103 2 2002788976 16421088 15549200
14:45:45 2824 8192000000 10921876208 11345195008 1976067040 19.434 100 65 126 3 2010775248 16246064 18462144
14:46:55 5261 8192000000 10912251584 11340095488 1967000720 19.362 100 63 126 3 2003715440 17760800 18953920
14:48:06 7590 8192000000 10917029760 11346157568 1971780080 19.400 100 56 112 2 2009092320 20506896 16805344
14:49:19 13952 8192000000 10929568832 11356565504 1977162016 19.442 100 62 125 2 2018981360 23637744 18181600
14:50:40 3417 8192000000 10931579344 11348398080 1955923104 19.274 99 73 144 3 2003038672 24843488 22272080
14:51:49 11356 8192000000 10899818752 11309969408 1933033072 19.091 99 66 127 1 1983127872 30538608 19556192
14:53:03 6345 8192000000 10937863088 11361107968 1973440000 19.413 100 67 133 1 2029178320 35044800 20693520
14:54:19 2900 8192000000 10976998272 11387478016 1990699264 19.549 100 84 164 1 2057197504 40995088 25503152
14:55:29 6426 8192000000 10955124944 11379752960 1983752208 19.494 100 54 106 1 2037086272 36554528 16779536
14:56:43 4049 8192000000 10954627728 11358314496 1961825984 19.321 99 86 174 1 2033340608 42313168 29201456
14:57:52 2445 8192000000 10987082432 11394912256 1988049008 19.528 100 89 177 1 2068247408 50734560 29463840
14:59:02 1846 8192000000 10991222400 11396767744 1988656448 19.533 100 92 186 1 2072223968 54192160 29375360
15:00:09 1380 8192000000 11016183216 11430096896 2010277184 19.704 100 84 168 1 2091880656 54231712 27371760
15:01:21 9976 8192000000 10987577072 11422797824 2001015296 19.631 100 50 100 0 2072286928 53548480 17723152
15:02:39 13174 8192000000 10988298960 11428098048 1980809728 19.471 99 59 116 0 2058522544 58350192 19362624
15:03:57 30092 8192000000 10965120272 11412553728 1988959392 19.536 100 36 73 0 2051354848 48870960 13524496
15:05:31 24151 8192000000 10936159184 11402006528 1945830688 19.193 99 22 44 0 2000746976 46617808 8298480
15:06:59 72997 8192000000 10885648912 11344687104 1891651488 18.759 97 9 18 0 1921529488 27355120 2522880
15:09:13 10671 8192000000 10890587488 11334889472 1941544352 19.159 99 42 84 3 1966725792 14280000 10901440
15:10:25 72386 8192000000 10902439168 11356004352 1926924544 19.042 98 14 28 1 1950726592 20251360 3550688
15:12:41 99769 8192000000 10934151440 11376848896 1980344304 19.467 100 12 24 0 1995652672 12140848 3167520
15:15:33 70060 8192000000 10952009424 11406376960 1986155088 19.513 99 45 89 1 2015122576 15620896 13346592
15:17:49 107928 8192000000 10938168864 11389870080 1975067856 19.426 99 31 62 0 1996359712 11726128 9565728
15:20:39 76517 8192000000 10922705104 11401314304 1982179424 19.482 99 21 40 1 2002811792 14906304 5726064
15:22:57 70267 8192000000 10902464912 11363536896 1922667552 19.008 96 21 42 0 1940543296 11904192 5971552
15:25:09 11050 8192000000 10902784640 11359264768 1966775552 19.360 100 40 78 1 1987540672 8928112 11837008
15:26:24 50096 8192000000 10898785264 11376709632 1938169632 19.132 98 36 72 3 1967241632 18781328 10290672
15:28:38 38894 8192000000 10888637856 11372093440 1936014928 19.115 98 6 12 0 1951741072 14069168 1656976
15:30:18 93797 8192000000 10847323712 11315298304 1902332384 18.845 96 16 31 0 1916781552 9186384 5262784
15:32:52 63307 8192000000 10854213152 11325145088 1916057008 18.955 97 29 58 0 1932287328 7765328 8464992
15:34:59 45196 8192000000 10863191952 11342229504 1919412224 18.982 98 15 30 1 1936635056 12943328 4279504
15:36:47 61461 8192000000 10844716816 11327361024 1851821648 18.437 95 8 14 1 1868461088 15099024 1540416
15:38:48 43024 8192000000 10876504592 11347640320 1923683984 19.016 98 23 46 1 1938009616 7620112 6705520
15:40:31 31486 8192000000 10882336864 11359924224 1939006416 19.139 99 17 34 1 1957652544 14013120 4633008
15:42:06 56712 8192000000 10875692944 11338604544 1874368544 18.620 95 19 38 1 1902710256 22153712 6188000
15:44:02 46227 8192000000 10825598976 11292000256 1825652352 18.224 95 15 30 0 1845972384 15393008 4927024
15:45:49 31582 8192000000 10893352528 11330875392 1903222864 18.852 98 49 97 0 1932059872 14956048 13880960
15:47:23 74451 8192000000 10863588640 11334225920 1897590656 18.807 98 14 28 0 1915519152 14006272 3922224
15:49:38 37546 8192000000 10909403872 11353374720 1954117488 19.259 100 37 72 1 1975247696 10830624 10299584
15:51:18 91132 8192000000 10899117856 11374485504 1967539232 19.366 100 41 82 3 2002919728 23397680 11982816
15:53:52 61046 8192000000 10893113872 11368636416 1966481136 19.358 100 15 30 0 1985206688 13817408 4908144
15:55:53 41882 8192000000 10877768112 11367706624 1946262352 19.197 99 6 12 0 1965104880 17276832 1565696
15:57:35 104697 8192000000 10877663776 11361406976 1946501360 19.199 99 17 34 0 1960513968 8966080 5046528
16:01:12 55699 8192000000 10891629312 11373150208 1965566528 19.350 100 30 60 1 1987355648 13519264 8269856
16:03:13 85958 8192000000 10917519824 11392356352 1981911824 19.480 100 14 28 2 1996867632 11115472 3840336
16:06:03 75002 8192000000 10940516592 11403091968 1958501952 19.294 98 22 44 0 1973972240 9879568 5590720
16:08:31 84031 8192000000 10914271088 11377438720 1895675760 18.791 95 12 24 1 1907309504 8956432 2677312
16:10:57 9471 8192000000 10919519424 11365437440 1966608720 19.359 100 56 112 0 1990982592 7857984 16515888
16:12:07 116707 8192000000 10922310976 11415109632 1912705616 18.928 96 15 29 3 1937015424 20311312 3998496
16:15:04 139099 8192000000 10919646016 11399450624 1940600704 19.152 97 24 48 2 1960287376 12869008 6817664
16:18:24 10268 8192000000 10922809168 11403325440 1992911312 19.567 100 18 36 0 2009193040 11582944 4698784
16:19:35 52046 8192000000 10913600144 11407093760 1982562464 19.485 99 11 22 1 1995646016 9766656 3316896
16:21:27 64265 8192000000 10910458624 11396423680 1925814560 19.033 96 17 34 0 1940347616 9899648 4633408
16:23:37 34212 8192000000 10891457024 11375636480 1950545472 19.231 99 19 38 1 1968816832 12239744 6031616
16:25:20 56197 8192000000 10859941200 11343138816 1847488096 18.402 93 9 18 0 1858074304 8293856 2292352
16:27:56 38981 8192000000 10875516976 11351187456 1921244480 18.997 98 30 60 1 1938526000 8971376 8310144
16:29:35 37836 8192000000 10818712064 11301007360 1862707632 18.525 97 29 58 2 1882137424 11486512 7943280
16:31:18 68930 8192000000 10889106752 11350597632 1925037312 19.027 99 26 52 0 1943322160 11406160 6878688
16:33:27 20044 8192000000 10885089328 11346194432 1948600224 19.215 100 43 86 0 1970762096 9896224 12265648
16:34:48 87680 8192000000 10879984672 11362410496 1928923392 19.058 99 25 50 1 1952034288 16243968 6866928
16:37:21 41805 8192000000 8896946752 9194610688 26807936 0.326 2 1 2 0 27273216 403152 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 16:38:52 to 05-08-2021 16:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-08-2021 16:39:08 to 05-08-2021 16:39:10.

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          259767
MON$OLDEST_ACTIVE               259768
MON$OLDEST_SNAPSHOT             259768
MON$NEXT_TRANSACTION            259768
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                       2057120
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
--- end of diff output ---

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

05.08.21 16:39:11. 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-05 16:39:14 No severe PSQL-related problems occured
05.08.21 16:39:15. End of report.