Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.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 16491244 kB
MemAvailable 30950116 kB
Buffers 0 kB
Cached 15154748 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-17 16:37:47
DTS_END                         2021-08-17 16:37:47.5550
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/34400
TRN_ID                          238175
ATT_ID                          663
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: 8980.98 21398 1077718
customer order: creation 168.53 413 20223
customer order: refuse 14.53 330 1744
order to supplier: creation 43.25 1248 5190
order to supplier: removal 3.35 108039 402
invoice (draft): creation 44.65 3299 5358
invoice (draft): removal 51.12 798 6134
invoice accept: apply 40.59 17963 4871
invoice accept: cancel 46.11 906 5533
customer reserve: creation 3716.28 179 445953
customer reserve: removal 4564.05 63 547686
realization accept: apply 80.59 203 9671
realization accept: cancel 84.03 117 10084
payment from customer: creation 57.23 155 6867
payment from customer: removal 5.31 94 637
payment to supplier: creation 46.33 186 5560
payment to supplier: removal 3.18 117 381
service: total inventory turnovers 4.38 1125 526
service: total monetary turnovers 6.54 23 785
service: aggregate perf. data 0.91 16374 109
service: refresh index statistics 0.03 276325 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2021 16:38:44 to 17-08-2021 16:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2781
TEST_TIME 2 2038
TEST_TIME 3 2916
TEST_TIME 4 3679
TEST_TIME 5 4041
TEST_TIME 6 4351
TEST_TIME 7 4337
TEST_TIME 8 4536
TEST_TIME 9 4544
TEST_TIME 10 4564
TEST_TIME 11 4936
TEST_TIME 12 5035
TEST_TIME 13 4964
TEST_TIME 14 5177
TEST_TIME 15 5211
TEST_TIME 16 6000
TEST_TIME 17 6511
TEST_TIME 18 6608
TEST_TIME 19 7937
TEST_TIME 20 8265
TEST_TIME 21 8839
TEST_TIME 22 9586
TEST_TIME 23 10146
TEST_TIME 24 10412
TEST_TIME 25 10291
TEST_TIME 26 10012
TEST_TIME 27 10093
TEST_TIME 28 10060
TEST_TIME 29 10227
TEST_TIME 30 10099
TEST_TIME 31 9970
TEST_TIME 32 9802
TEST_TIME 33 9855
TEST_TIME 34 9714
TEST_TIME 35 9578
TEST_TIME 36 9494
TEST_TIME 37 9413
TEST_TIME 38 9327
TEST_TIME 39 9223
TEST_TIME 40 9082
TEST_TIME 41 9008
TEST_TIME 42 8869
TEST_TIME 43 8788
TEST_TIME 44 8783
TEST_TIME 45 8789
TEST_TIME 46 8864
TEST_TIME 47 8813
TEST_TIME 48 8807
TEST_TIME 49 8900
TEST_TIME 50 8929
TEST_TIME 51 8908
TEST_TIME 52 8944
TEST_TIME 53 9182
TEST_TIME 54 9109
TEST_TIME 55 9225
TEST_TIME 56 9405
TEST_TIME 57 9302
TEST_TIME 58 9369
TEST_TIME 59 9241
TEST_TIME 60 9196
TEST_TIME 61 9146
TEST_TIME 62 9149
TEST_TIME 63 9121
TEST_TIME 64 9030
TEST_TIME 65 8959
TEST_TIME 66 8921
TEST_TIME 67 8973
TEST_TIME 68 8972
TEST_TIME 69 8979
TEST_TIME 70 8994
TEST_TIME 71 9069
TEST_TIME 72 9041
TEST_TIME 73 9095
TEST_TIME 74 9125
TEST_TIME 75 9083
TEST_TIME 76 9169
TEST_TIME 77 9193
TEST_TIME 78 9173
TEST_TIME 79 9199
TEST_TIME 80 9101
TEST_TIME 81 9069
TEST_TIME 82 9001
TEST_TIME 83 9031
TEST_TIME 84 8982
TEST_TIME 85 8933
TEST_TIME 86 8909
TEST_TIME 87 8869
TEST_TIME 88 8840
TEST_TIME 89 8870
TEST_TIME 90 8935
TEST_TIME 91 8956
TEST_TIME 92 8982
TEST_TIME 93 8961
TEST_TIME 94 9066
TEST_TIME 95 9019
TEST_TIME 96 9053
TEST_TIME 97 9033
TEST_TIME 98 9079
TEST_TIME 99 9083
TEST_TIME 100 9105
TEST_TIME 101 9109
TEST_TIME 102 9108
TEST_TIME 103 9034
TEST_TIME 104 9039
TEST_TIME 105 9052
TEST_TIME 106 9008
TEST_TIME 107 9010
TEST_TIME 108 8969
TEST_TIME 109 8956
TEST_TIME 110 8916
TEST_TIME 111 8868
TEST_TIME 112 8933
TEST_TIME 113 8934
TEST_TIME 114 8951
TEST_TIME 115 8954
TEST_TIME 116 8967
TEST_TIME 117 9006
TEST_TIME 118 8986
TEST_TIME 119 8983
TEST_TIME 120 8986

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 16:38:47 to 17-08-2021 16:38:50.

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 1492936 1492936 0 0.00 0 32850 7 0 0
doc_list_biud 1492933 1492933 0 0.00 0 46941 29 0 0
sp_add_invoice_to_stock 4985 4871 114 2.28 37 156361 17963 0 114
sp_cancel_adding_invoice 5533 5533 0 0.00 5 61572 907 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 7404 331 0 0
sp_cancel_customer_reserve 547686 547686 0 0.00 1 46945 64 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 4199 94 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 15274 118 0 0
sp_cancel_supplier_invoice 6145 6134 11 0.17 2 19806 798 0 11
sp_cancel_supplier_order 402 402 0 0.00 6 1115660 108039 0 0
sp_cancel_write_off 10084 10084 0 0.00 1 12049 117 0 0
sp_client_order 20223 20223 0 0.00 4 48373 414 0 0
sp_customer_reserve 445953 445953 0 0.00 0 69785 180 0 0
sp_fill_shopping_cart 30765 30765 0 0.00 1 18978 41 0 0
sp_fill_shopping_cart_clo_res 445941 445941 0 0.00 0 14716 13 0 0
sp_get_clo_for_invoice 4859 4859 0 0.00 0 22321 339 0 0
sp_kill_cost_storno 16635 16635 0 0.00 0 15266 58 0 0
sp_kill_qstorno_ret_qs2qd 1111992 1111992 0 0.00 0 46938 34 0 0
sp_kill_qty_storno 586112 586112 0 0.00 0 46941 74 0 0
sp_lock_selected_doc 20103 20103 0 0.00 0 7566 7 0 0
sp_make_cost_storno 26957 26957 0 0.00 0 24641 69 0 0
sp_multiply_rows_for_pdistr 26956 26956 0 0.00 0 19411 34 0 0
sp_multiply_rows_for_qdistr 20223 20223 0 0.00 0 48361 205 0 0
sp_pay_from_customer 6867 6867 0 0.00 4 60848 155 0 0
sp_pay_to_supplier 5560 5560 0 0.00 5 25531 186 0 0
sp_payment_common 12427 12427 0 0.00 1 60839 61 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 7398 297 0 0
sp_qd_handle_on_invoice_upd_sts 10392 10392 0 0.00 0 15559 329 0 0
sp_qd_handle_on_reserve_upd_sts 9670 9670 0 0.00 0 27603 57 0 0
sp_reserve_write_off 9671 9671 0 0.00 6 33337 204 0 0
sp_supplier_invoice 5358 5358 0 0.00 5 75692 3299 0 0
sp_supplier_order 5190 5190 0 0.00 5 57741 1249 0 0
srv_aggregate_perf_data 109 109 0 0.00 2889 263268 16375 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 979 140888 47281 0 0
srv_find_qd_qs_mism 1032808 1032808 0 0.00 0 13081 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 31 45087 1125 0 0
srv_make_money_saldo 785 785 0 0.00 1 1835 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 195429 411837 276325 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 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 4871 4871 0 0.00 0 16046 155 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 3279 90 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 11282 24 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 1220 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1081 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 1704 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2030 14 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 23628 1436 0 0
t$perf-norm:sp_cancel_write_off 932 932 0 0.00 0 1749 5 0 0
t$perf-norm:sp_client_order 20223 20223 0 0.00 0 18179 20 0 0
t$perf-norm:sp_customer_reserve 881 881 0 0.00 0 2636 7 0 0
t$perf-norm:sp_pay_from_customer 6867 6867 0 0.00 0 7498 10 0 0
t$perf-norm:sp_pay_to_supplier 5560 5560 0 0.00 0 7085 9 0 0
t$perf-norm:sp_reserve_write_off 9671 9671 0 0.00 0 7518 9 0 0
t$perf-norm:sp_supplier_invoice 5358 5358 0 0.00 0 18745 25 0 0
t$perf-norm:sp_supplier_order 5190 5190 0 0.00 0 12008 14 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 713 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 92 2 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 3368 24 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 950 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9944 9716 228 2.29 0 9582 21 0 228
v_all_customers 36888 36888 0 0.00 0 24 1 0 0
v_all_suppliers 29218 29218 0 0.00 0 25 1 0 0
v_all_wares 40444 40444 0 0.00 0 117 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 282 6 0 0
v_cancel_client_order 3488 3488 0 0.00 0 2401 7 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 3814 15 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 1029 4 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1847 8 0 0
v_cancel_supplier_invoice 902 880 22 2.43 0 8607 34 0 22
v_cancel_supplier_order 804 804 0 0.00 0 10190 21 0 0
v_cancel_write_off 1864 1864 0 0.00 0 3628 19 0 0
v_max_id_clo_ord 5187 5187 0 0.00 0 18849 5 0 0
v_max_id_clo_res 881 881 0 0.00 0 1302 11 0 0
v_max_id_ord_sup 5354 5354 0 0.00 0 2772 4 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 15006 13 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 4074 7 0 0
v_min_id_clo_ord 5187 5187 0 0.00 0 16535 24 0 0
v_min_id_clo_res 881 881 0 0.00 0 2422 5 0 0
v_min_id_ord_sup 5354 5354 0 0.00 0 4685 7 0 0
v_min_non_paid_invoice 5560 5560 0 0.00 0 18036 48 0 0
v_min_non_paid_realizn 6867 6867 0 0.00 0 60833 38 0 0
v_our_firm 10196 10196 0 0.00 0 14 1 0 0
v_reserve_write_off 19340 19340 0 0.00 0 8986 8 0 0
x_lock_depdocs_on_canc_invoice 11227 11227 0 0.00 0 13913 122 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 6453 344 0 0
x_make_qstorno_1000_1200 5190 5190 0 0.00 2 57734 1157 0 0
x_make_qstorno_1200_2000 5358 5358 0 0.00 3 67100 3160 0 0
x_make_qstorno_2100_3300 445952 445952 0 0.00 0 69783 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2021 16:38:52 to 17-08-2021 16:38: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
14:07:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:52 32302 8192000000 10382968432 10702602240 1428104544 14.845 100 86 172 1 1462341600 6170400 28066656
14:10:33 20618 8192000000 10445743152 10779799552 1491696368 15.404 100 86 172 0 1528307232 8628144 27982720
14:11:59 13286 8192000000 10490964832 10833768448 1546754608 15.882 100 84 168 2 1582452144 9624448 26073088
14:13:16 11108 8192000000 10564267184 10921238528 1619464720 16.505 100 82 164 0 1655566096 11747952 24353424
14:14:38 7378 8192000000 10641525184 11013644288 1704871360 17.226 100 80 160 1 1739875232 12025904 22977968
14:15:49 4324 8192000000 10716710416 11090776064 1773791264 17.798 100 85 168 0 1809089408 12034896 23263248
14:16:56 3949 8192000000 10756065392 11144646656 1818836000 18.168 100 76 152 0 1852302192 12289312 21176880
14:18:01 1515 8192000000 10800978272 11189596160 1863282176 18.530 100 90 177 1 1901474000 12925184 25266640
14:19:04 1788 8192000000 10833523552 11222409216 1884929904 18.705 100 96 193 2 1926986752 14267168 27789680
14:20:06 2758 8192000000 10795919600 11215740928 1882920672 18.689 100 43 86 0 1901949600 6921168 12107760
14:21:12 2581 8192000000 10829019376 11240648704 1898864688 18.817 100 54 110 2 1920217792 6333968 15019136
14:22:17 7754 8192000000 10810796832 11238252544 1900923648 18.834 100 25 50 1 1915186208 6997536 7265024
14:23:26 15025 8192000000 10821967040 11251048448 1907438032 18.886 100 27 54 2 1922454816 6763072 8253712
14:24:43 16173 8192000000 10821122336 11257946112 1914448976 18.942 100 32 63 2 1929176096 6074288 8652832
14:26:00 4222 8192000000 10812592784 11248939008 1898549104 18.815 99 37 74 3 1916026576 6820080 10657392
14:27:05 6362 8192000000 10831636624 11267039232 1917648656 18.968 100 28 54 2 1931833392 6134544 8050192
14:28:12 70014 8192000000 10722623184 11138752512 1611445744 16.437 86 26 53 1 1623457008 4779296 7231968
14:30:27 4859 8192000000 10726240288 11138031616 1783524240 17.879 98 43 86 1 1802944752 6775600 12644912
14:31:35 8294 8192000000 10716391184 11129905152 1744270560 17.554 96 23 46 1 1757100400 6206064 6623776
14:32:44 18443 8192000000 10759122384 11174965248 1823200816 18.204 99 25 50 2 1834837040 4765040 6871184
14:34:02 24001 8192000000 10761311360 11189960704 1846997728 18.398 100 21 42 0 1859428416 6639632 5791056
14:35:29 11226 8192000000 10785479616 11211964416 1867018464 18.560 100 22 42 2 1878249504 4922736 6308304
14:36:42 10883 8192000000 10787638864 11218407424 1876727232 18.639 100 18 34 1 1887315760 5709264 4879264
14:37:59 109504 8192000000 10871411920 11298430976 1920730976 18.993 100 35 68 1 1946313168 15914624 9667568
14:40:55 16495 8192000000 10904519536 11327037440 1955151504 19.267 100 52 104 1 1992665136 22253680 15259952
14:42:16 6026 8192000000 10885708976 11313844224 1934779360 19.105 99 55 110 0 1974659568 23775168 16105040
14:43:26 4589 8192000000 10925778368 11345235968 1969770880 19.384 100 64 124 3 2013886304 25718576 18396848
14:44:36 18856 8192000000 10941264624 11369472000 1979571520 19.461 100 55 110 1 2025073680 29195824 16306336
14:46:00 10421 8192000000 10943397856 11380862976 1992890160 19.567 100 50 98 2 2040955376 32476080 15589136
14:47:16 3147 8192000000 10957817248 11380617216 1978761648 19.455 99 69 136 0 2035839776 36533232 20544896
14:48:26 9922 8192000000 10982499376 11411750912 2013535360 19.729 100 57 114 2 2069834832 37696928 18602544
14:49:43 10068 8192000000 10985400224 11421024256 2017683856 19.762 100 56 110 1 2076552064 41324112 17544096
14:50:57 5715 8192000000 11001336000 11429892096 2020307680 19.783 100 67 133 1 2092333328 50170672 21854976
14:52:08 977 8192000000 11003172192 11413536768 2000655328 19.628 100 91 181 1 2084510512 55608400 28246784
14:53:14 3694 8192000000 10976118208 11395805184 1985726960 19.510 100 80 157 3 2069451312 58465504 25258848
14:54:21 2213 8192000000 10988099024 11399086080 1979978768 19.465 100 92 185 4 2068808288 59818544 29010976
14:55:31 4912 8192000000 10991503024 11408601088 1986356144 19.515 100 77 152 0 2071781168 60829648 24595376
14:56:40 17076 8192000000 10935931472 11375136768 1953869088 19.257 99 42 83 1 2013746736 46214576 13663072
14:58:00 24321 8192000000 10919611008 11388612608 1964438864 19.341 100 27 54 0 2019399744 45149136 9811744
14:59:28 15989 8192000000 10914644688 11374825472 1942196752 19.164 99 24 48 0 1991273632 40688528 8388352
15:00:46 43008 8192000000 10885037984 11354648576 1948871232 19.217 100 12 24 3 1978185200 25731760 3582208
15:02:43 38749 8192000000 10906953040 11361185792 1951796416 19.241 99 38 76 1 1978687536 15720544 11170576
15:04:26 19534 8192000000 10905656368 11351924736 1943026320 19.171 99 50 100 2 1979333792 20914048 15393424
15:05:49 40542 8192000000 10866258544 11360305152 1941422816 19.158 100 10 20 0 1967062784 22300176 3339792
15:07:30 51995 8192000000 10869517440 11340972032 1935149632 19.108 99 17 34 1 1948577552 8606288 4821632
15:09:30 125558 8192000000 10873988272 11349594112 1891425344 18.757 96 10 20 1 1906245312 11727216 3092752
15:12:36 46106 8192000000 10853116448 11316441088 1919644368 18.984 98 23 46 1 1932113808 6665568 5803872
15:15:32 61351 8192000000 10873491104 11348652032 1941988016 19.163 99 15 30 0 1954225872 8210720 4027136
15:17:36 67716 8192000000 10863973488 11334864896 1943999456 19.179 99 17 34 1 1957672816 8882880 4790480
15:19:54 41267 8192000000 10886859904 11356807168 1968488112 19.373 100 26 52 3 1989480832 13603312 7389408
15:21:59 46961 8192000000 10896023824 11373662208 1977973664 19.449 100 13 26 1 1992169072 10774048 3421360
15:23:47 65317 8192000000 10922350704 11394285568 1991716304 19.557 100 20 40 2 2011191344 14503360 4971680
15:26:02 65351 8192000000 10918319824 11380236288 1973000512 19.409 99 31 62 1 1997680816 15423072 9257232
15:28:10 37029 8192000000 10951759136 11410096128 1991538064 19.556 99 35 70 2 2021537520 19559456 10440000
15:30:00 65297 8192000000 10931204000 11420479488 1976767952 19.439 99 13 26 0 2004805664 24493104 3544608
15:32:11 63748 8192000000 10930324192 11414282240 1976977248 19.441 99 13 26 0 2011021856 30223328 3821280
15:34:27 21115 8192000000 10935568848 11381092352 1972716224 19.407 99 43 86 1 1993519232 8781328 12021680
15:35:49 106609 8192000000 10904572528 11363229696 1946013472 19.195 98 32 64 2 1961792576 7569200 8209904
15:38:36 31678 8192000000 10909641904 11370897408 1963137648 19.331 99 43 84 3 1989658544 15160384 11360512
15:40:08 41916 8192000000 10888498880 11362906112 1943212032 19.172 98 8 16 0 1951948816 6546960 2189824
15:42:21 80682 8192000000 10873815296 11350581248 1931887280 19.082 98 9 17 3 1945046608 10573376 2585952
15:44:42 50907 8192000000 10893541296 11352141824 1895028272 18.786 96 27 54 0 1912679392 10057456 7593664
15:46:38 52015 8192000000 10878291520 11348029440 1909231792 18.900 97 20 40 3 1928630336 13613616 5784928
15:48:30 36707 8192000000 10858259392 11305746432 1870497184 18.588 96 25 50 0 1892863792 15902512 6464096
15:50:07 50129 8192000000 10878144720 11336851456 1888637872 18.735 97 24 48 0 1905377456 10145408 6594176
15:51:59 79992 8192000000 10899341264 11355140096 1946606608 19.199 100 30 58 1 1973373840 18697984 8069248
15:54:21 92320 8192000000 10877611776 11338559488 1839612192 18.338 94 9 18 0 1852920080 10236096 3071792
15:57:04 130342 8192000000 10843168640 11316125696 1889322688 18.740 97 33 66 0 1907867152 8754368 9790096
16:00:15 58108 8192000000 10815574624 11282669568 1882193792 18.683 98 20 40 2 1897861008 10008688 5658528
16:02:13 47946 8192000000 10823612640 11294232576 1887093632 18.722 98 9 18 0 1896738176 7057168 2587376
16:04:06 73915 8192000000 10875970704 11349053440 1946611920 19.199 100 17 35 0 1965587488 14091584 4883984
16:06:27 63683 8192000000 10878451856 11342200832 1913067024 18.931 98 15 27 2 1935799536 17829536 4902976
16:08:35 44355 8192000000 10903287296 11363938304 1965832064 19.352 100 10 20 2 1980578784 11219312 3527408
16:10:22 94104 8192000000 10899422544 11388436480 1955988176 19.274 99 13 26 1 1971592208 12347616 3256416
16:13:00 20990 8192000000 10932935136 11373924352 1985801424 19.511 100 39 79 2 2007512816 9914112 11797280
16:14:25 35012 8192000000 10907246576 11359760384 1932143888 19.084 98 36 72 0 1951914384 9778944 9991552
16:16:02 140888 8192000000 10897979504 11370262528 1936516336 19.119 98 9 18 1 1949361648 10530896 2314416
16:19:25 38253 8192000000 10903332400 11369189376 1959828368 19.305 99 20 39 1 1979973232 14104512 6040352
16:21:27 98336 8192000000 10901737920 11388923904 1951643936 19.240 98 13 26 0 1968373728 12990544 3739248
16:24:20 60158 8192000000 10864372016 11348541440 1923281936 19.013 98 12 25 0 1934546464 7733664 3530864
16:26:42 59988 8192000000 10894285104 11349614592 1941494960 19.159 99 18 36 0 1957823568 11132704 5195904
16:28:43 72730 8192000000 10868984240 11361546240 1935920544 19.114 99 12 24 0 1952044576 12610224 3513808
16:31:28 67344 8192000000 10871165488 11345727488 1891530096 18.758 96 22 45 1 1910637680 12751680 6355904
16:34:28 52570 8192000000 10858066512 11330646016 1936884864 19.122 100 26 51 1 1957452864 12721872 7846128
16:36:21 9144 8192000000 10908434944 11336253440 1942810080 19.169 99 62 124 0 1970374128 10007760 17556288
16:37:36 23253 8192000000 9362472832 9647869952 513537136 5.898 27 6 12 0 519566016 4783376 1245504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 16:38:57 to 17-08-2021 16:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 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 17-08-2021 16:39:13 to 17-08-2021 16:39: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          236795
MON$OLDEST_ACTIVE               238449
MON$OLDEST_SNAPSHOT             238449
MON$NEXT_TRANSACTION            238449
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                       2001408
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:386: 
:387: mscs-test-161.budzdorov.ru    Tue Aug 17 16:38:05 2021
:388:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:389: 
--- end of diff output ---

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

17.08.21 16:39: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-17 16:39:18 No severe PSQL-related problems occured
17.08.21 16:39:20. End of report.