Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.10.2021 16:43
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 16237156 kB
MemAvailable 30869556 kB
Buffers 0 kB
Cached 15487600 kB
SwapCached 4376 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-10-30 16:42:34
DTS_END                         2021-10-30 16:42:34.5620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(151:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36268
TRN_ID                          237954
ATT_ID                          617
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: 9083.42 28845 1090011
customer order: creation 168.29 327 20195
customer order: refuse 14.87 389 1784
order to supplier: creation 44.73 1259 5368
order to supplier: removal 3.36 105160 403
invoice (draft): creation 45.63 3570 5475
invoice (draft): removal 51.26 845 6151
invoice accept: apply 42.46 18233 5095
invoice accept: cancel 45.82 793 5498
customer reserve: creation 3920.86 180 470503
customer reserve: removal 4454.60 62 534552
realization accept: apply 82.10 183 9852
realization accept: cancel 83.64 104 10037
payment from customer: creation 56.48 116 6777
payment from customer: removal 5.64 79 677
payment to supplier: creation 48.67 112 5840
payment to supplier: removal 3.34 93 401
service: total inventory turnovers 4.48 1133 538
service: total monetary turnovers 6.28 44 753
service: aggregate perf. data 0.90 19988 108
service: refresh index statistics 0.03 424229 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-10-2021 16:44:16 to 30-10-2021 16:44:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3415
TEST_TIME 2 3744
TEST_TIME 3 3886
TEST_TIME 4 4236
TEST_TIME 5 5082
TEST_TIME 6 5344
TEST_TIME 7 5719
TEST_TIME 8 5791
TEST_TIME 9 6110
TEST_TIME 10 6278
TEST_TIME 11 6292
TEST_TIME 12 6446
TEST_TIME 13 6847
TEST_TIME 14 6879
TEST_TIME 15 6780
TEST_TIME 16 6820
TEST_TIME 17 7173
TEST_TIME 18 7668
TEST_TIME 19 7750
TEST_TIME 20 8151
TEST_TIME 21 8416
TEST_TIME 22 8846
TEST_TIME 23 9060
TEST_TIME 24 9114
TEST_TIME 25 9386
TEST_TIME 26 9501
TEST_TIME 27 9901
TEST_TIME 28 10619
TEST_TIME 29 10548
TEST_TIME 30 10574
TEST_TIME 31 10464
TEST_TIME 32 10307
TEST_TIME 33 10317
TEST_TIME 34 10128
TEST_TIME 35 9995
TEST_TIME 36 9973
TEST_TIME 37 9964
TEST_TIME 38 9929
TEST_TIME 39 9706
TEST_TIME 40 9589
TEST_TIME 41 9525
TEST_TIME 42 9503
TEST_TIME 43 9372
TEST_TIME 44 9263
TEST_TIME 45 9203
TEST_TIME 46 9099
TEST_TIME 47 9174
TEST_TIME 48 9173
TEST_TIME 49 9083
TEST_TIME 50 9102
TEST_TIME 51 9006
TEST_TIME 52 9096
TEST_TIME 53 9035
TEST_TIME 54 8978
TEST_TIME 55 9173
TEST_TIME 56 9115
TEST_TIME 57 9117
TEST_TIME 58 9187
TEST_TIME 59 9108
TEST_TIME 60 9332
TEST_TIME 61 9290
TEST_TIME 62 9310
TEST_TIME 63 9285
TEST_TIME 64 9271
TEST_TIME 65 9386
TEST_TIME 66 9393
TEST_TIME 67 9440
TEST_TIME 68 9324
TEST_TIME 69 9312
TEST_TIME 70 9259
TEST_TIME 71 9204
TEST_TIME 72 9256
TEST_TIME 73 9183
TEST_TIME 74 9160
TEST_TIME 75 9141
TEST_TIME 76 9129
TEST_TIME 77 9118
TEST_TIME 78 9116
TEST_TIME 79 9126
TEST_TIME 80 9125
TEST_TIME 81 9134
TEST_TIME 82 9140
TEST_TIME 83 9218
TEST_TIME 84 9250
TEST_TIME 85 9220
TEST_TIME 86 9177
TEST_TIME 87 9228
TEST_TIME 88 9310
TEST_TIME 89 9308
TEST_TIME 90 9363
TEST_TIME 91 9281
TEST_TIME 92 9197
TEST_TIME 93 9174
TEST_TIME 94 9115
TEST_TIME 95 9079
TEST_TIME 96 9086
TEST_TIME 97 9188
TEST_TIME 98 9164
TEST_TIME 99 9227
TEST_TIME 100 9225
TEST_TIME 101 9248
TEST_TIME 102 9212
TEST_TIME 103 9168
TEST_TIME 104 9225
TEST_TIME 105 9187
TEST_TIME 106 9231
TEST_TIME 107 9378
TEST_TIME 108 9338
TEST_TIME 109 9332
TEST_TIME 110 9343
TEST_TIME 111 9289
TEST_TIME 112 9242
TEST_TIME 113 9221
TEST_TIME 114 9216
TEST_TIME 115 9163
TEST_TIME 116 9152
TEST_TIME 117 9159
TEST_TIME 118 9105
TEST_TIME 119 9106
TEST_TIME 120 9089

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-10-2021 16:44:20 to 30-10-2021 16:44:23.

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 1522377 1522377 0 0.00 0 17079 6 0 0
doc_list_biud 1522376 1522376 0 0.00 0 27276 28 0 0
sp_add_invoice_to_stock 5222 5095 127 2.43 26 160740 18233 0 127
sp_cancel_adding_invoice 5498 5498 0 0.00 5 52211 793 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 17826 390 0 0
sp_cancel_customer_reserve 534552 534552 0 0.00 1 27284 62 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 7010 79 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 8081 93 0 0
sp_cancel_supplier_invoice 6161 6151 10 0.16 2 24158 845 0 10
sp_cancel_supplier_order 403 403 0 0.00 7 1044814 105160 0 0
sp_cancel_write_off 10037 10037 0 0.00 1 17082 105 0 0
sp_client_order 20195 20195 0 0.00 4 26488 328 0 0
sp_customer_reserve 470503 470503 0 0.00 0 33981 180 0 0
sp_fill_shopping_cart 31012 31012 0 0.00 1 20147 41 0 0
sp_fill_shopping_cart_clo_res 470488 470488 0 0.00 0 13817 14 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 36267 312 0 0
sp_kill_cost_storno 16613 16613 0 0.00 0 17078 60 0 0
sp_kill_qstorno_ret_qs2qd 1085695 1085695 0 0.00 0 27274 34 0 0
sp_kill_qty_storno 573354 573354 0 0.00 0 27275 73 0 0
sp_lock_selected_doc 20665 20665 0 0.00 0 9390 7 0 0
sp_make_cost_storno 27546 27546 0 0.00 0 9478 51 0 0
sp_multiply_rows_for_pdistr 27546 27546 0 0.00 0 12430 30 0 0
sp_multiply_rows_for_qdistr 20195 20195 0 0.00 0 11051 143 0 0
sp_pay_from_customer 6777 6777 0 0.00 4 13211 117 0 0
sp_pay_to_supplier 5840 5840 0 0.00 5 12122 113 0 0
sp_payment_common 12617 12617 0 0.00 2 13205 29 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 17819 352 0 0
sp_qd_handle_on_invoice_upd_sts 10575 10575 0 0.00 0 13352 329 0 0
sp_qd_handle_on_reserve_upd_sts 9852 9852 0 0.00 0 11327 65 0 0
sp_reserve_write_off 9852 9852 0 0.00 6 13413 183 0 0
sp_supplier_invoice 5475 5475 0 0.00 7 99065 3571 0 0
sp_supplier_order 5368 5368 0 0.00 5 43067 1260 0 0
srv_aggregate_perf_data 108 108 0 0.00 2811 281024 19988 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1576 137625 46027 0 0
srv_find_qd_qs_mism 1041033 1041033 0 0.00 0 11240 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 41 37478 1133 0 0
srv_make_money_saldo 753 753 0 0.00 0 13741 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 147152 687484 424230 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5095 5095 0 0.00 0 16373 177 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3538 108 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 11807 26 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 296 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2036 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1275 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 354 9 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 29011 1847 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 996 5 0 0
t$perf-norm:sp_client_order 20195 20195 0 0.00 0 8384 17 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 15757 30 0 0
t$perf-norm:sp_pay_from_customer 6777 6777 0 0.00 0 14364 13 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 14021 16 0 0
t$perf-norm:sp_reserve_write_off 9852 9852 0 0.00 0 10721 12 0 0
t$perf-norm:sp_supplier_invoice 5475 5475 0 0.00 0 10134 21 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 10672 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 304 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 822 17 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 2673 19 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 9367 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 10404 10150 254 2.44 0 5389 17 0 254
v_all_customers 36396 36396 0 0.00 0 96 1 0 0
v_all_suppliers 29864 29864 0 0.00 0 96 1 0 0
v_all_wares 40390 40390 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 633 6 0 0
v_cancel_client_order 3568 3568 0 0.00 0 4706 7 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1521 6 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 4258 7 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1735 8 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 2776 19 0 20
v_cancel_supplier_order 806 806 0 0.00 0 3555 12 0 0
v_cancel_write_off 1966 1966 0 0.00 0 4815 19 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 5103 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 1547 12 0 0
v_max_id_ord_sup 5448 5448 0 0.00 0 8006 5 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 945 3 0 0
v_max_non_paid_realizn 3837 3837 0 0.00 0 2426 4 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 5229 9 0 0
v_min_id_clo_res 970 970 0 0.00 0 2285 6 0 0
v_min_id_ord_sup 5448 5448 0 0.00 0 5517 8 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 12102 19 0 0
v_min_non_paid_realizn 6777 6777 0 0.00 0 13201 23 0 0
v_our_firm 9874 9874 0 0.00 0 17 1 0 0
v_reserve_write_off 19704 19704 0 0.00 0 4683 5 0 0
x_lock_depdocs_on_canc_invoice 11250 11250 0 0.00 0 13185 119 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 13386 388 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 1 43053 1167 0 0
x_make_qstorno_1200_2000 5475 5475 0 0.00 3 98954 3425 0 0
x_make_qstorno_2100_3300 470503 470503 0 0.00 0 33980 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2021 16:44:24 to 30-10-2021 16:44:28.

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:12:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:39 30599 8192000000 9919523584 10232545280 993571776 10.816 100 88 176 0 1022734272 6100928 23061568
14:15:15 15196 8192000000 9951916512 10274336768 1031351696 11.181 100 92 184 0 1063366384 8575600 23439088
14:16:33 13098 8192000000 9974763328 10306039808 1059160400 11.448 100 79 157 2 1088187760 9540544 19486816
14:17:50 13672 8192000000 10038251120 10389757952 1123338144 12.059 100 79 158 0 1152680608 10381760 18960704
14:19:07 8697 8192000000 10096682496 10459111424 1182492256 12.613 100 81 161 3 1213519328 12527584 18499488
14:20:18 5314 8192000000 10136831344 10507468800 1223559200 12.995 100 74 148 3 1253050448 12984128 16507120
14:21:25 3520 8192000000 10161167440 10537795584 1251068816 13.248 100 89 179 2 1283999024 12983856 19946352
14:22:30 2141 8192000000 10203931840 10585608192 1280585616 13.518 100 88 172 1 1313287984 13065168 19637200
14:23:34 3541 8192000000 10210015600 10605973504 1303007936 13.723 100 65 130 1 1329682704 12378416 14296352
14:24:51 6249 8192000000 10203014464 10616238080 1314666896 13.828 100 34 67 1 1327616224 5787440 7161888
14:25:58 7469 8192000000 10201358224 10623709184 1322743136 13.902 100 9 18 1 1330170896 4989664 2438096
14:27:10 37258 8192000000 10217767840 10641928192 1332441712 13.989 100 28 56 1 1345594592 5676288 7476592
14:29:26 5685 8192000000 10232982528 10653069312 1339532464 14.053 100 31 61 1 1352851120 5970416 7348240
14:30:36 4805 8192000000 10249310416 10663407616 1346233424 14.114 100 35 70 0 1360954016 6197792 8522800
14:31:43 56197 8192000000 10200225824 10621136896 1279908112 13.512 96 35 70 1 1294038304 5880656 8249536
14:33:46 4103 8192000000 10211484816 10627182592 1316192128 13.842 100 34 68 3 1331160752 7814960 7153664
14:34:52 6712 8192000000 10152773920 10555326464 1216944464 12.933 96 19 38 1 1227065392 5926960 4193968
14:36:01 15492 8192000000 10135964816 10531020800 1204314688 12.816 96 22 44 3 1214073280 5191152 4567440
14:37:21 17062 8192000000 10133895728 10540658688 1232546400 13.078 98 47 94 0 1250019440 5665840 11807200
14:38:41 4203 8192000000 10164733088 10568470528 1270405984 13.425 100 30 60 1 1284258816 7150128 6702704
14:39:46 8936 8192000000 10183669248 10593484800 1290062448 13.605 100 29 58 0 1302646720 5541984 7042288
14:40:59 9352 8192000000 10202701088 10612125696 1305306608 13.743 100 30 59 1 1318799600 5524800 7968192
14:42:11 38476 8192000000 10217086032 10631499776 1318632176 13.864 100 35 70 4 1332042208 5460480 7949552
14:43:58 20316 8192000000 10246993056 10660917248 1338229680 14.041 100 44 88 0 1358965968 10599648 10136640
14:45:21 9795 8192000000 10269584560 10686492672 1362168352 14.257 100 52 102 2 1388447488 14003296 12275840
14:46:34 26029 8192000000 10284633280 10708340736 1369938832 14.326 100 45 90 0 1398400000 17381984 11079184
14:48:06 8696 8192000000 10295834384 10722267136 1383384192 14.447 100 44 88 2 1414268080 20395696 10488192
14:49:18 7838 8192000000 10329535056 10750824448 1399405760 14.590 100 49 98 0 1435081248 23968176 11707312
14:50:34 17725 8192000000 10335973680 10765160448 1409397888 14.679 100 50 100 0 1447444304 26069088 11977328
14:52:01 2711 8192000000 10325271488 10744823808 1388768160 14.495 99 59 118 1 1431343536 28608464 13966912
14:53:07 8889 8192000000 10340040832 10763915264 1402692000 14.619 100 52 103 0 1449453728 34345936 12415792
14:54:20 4000 8192000000 10360090800 10781122560 1414017408 14.720 100 66 129 2 1467799840 38113648 15668784
14:55:28 11607 8192000000 10336321376 10763186176 1396366768 14.563 99 39 78 0 1440725584 33593008 10765808
14:56:44 3521 8192000000 10372497296 10786340864 1416211904 14.739 100 80 156 0 1478206592 42818000 19176688
14:57:53 2868 8192000000 10358432416 10770030592 1386752592 14.477 99 72 144 2 1451194560 45974288 18467680
14:59:03 4324 8192000000 10382218416 10792087552 1406656544 14.654 100 89 179 0 1486205872 57793200 21756128
15:00:12 5695 8192000000 10375934928 10792656896 1407008640 14.657 100 82 164 1 1480100576 51616800 21475136
15:01:25 5075 8192000000 10358103648 10779983872 1401408416 14.608 100 69 138 2 1463561104 44179984 17972704
15:02:38 1575 8192000000 10381967824 10794700800 1401946912 14.612 100 88 176 0 1479489680 55166448 22376320
15:03:46 16662 8192000000 10351246064 10782814208 1348313968 14.132 96 42 84 2 1420997840 61453536 11230336
15:05:05 9686 8192000000 10329710480 10758594560 1381464880 14.430 100 41 82 0 1436307872 43959920 10883072
15:06:22 25420 8192000000 10298226800 10733060096 1318649024 13.864 96 32 64 2 1373129824 45624752 8856048
15:07:56 29757 8192000000 10306906592 10742833152 1347474176 14.125 99 23 46 2 1399231664 45409520 6347968
15:09:26 31124 8192000000 10291573728 10738933760 1339848640 14.056 99 22 44 1 1391402592 45168400 6385552
15:11:06 41365 8192000000 10274412384 10715811840 1341411072 14.070 99 26 52 3 1369317584 21810768 6095744
15:12:53 40726 8192000000 10252519280 10700267520 1349443056 14.142 100 15 30 0 1365391696 12796976 3151664
15:15:01 48861 8192000000 10253313920 10703187968 1347454528 14.125 99 8 14 2 1357997024 9410048 1132448
15:16:51 47665 8192000000 10280075024 10730295296 1374308016 14.366 100 12 24 0 1388143968 10926592 2909360
15:18:40 55673 8192000000 10271170560 10729041920 1353385872 14.178 98 10 20 0 1367268528 11773536 2109120
15:20:38 92825 8192000000 10278113984 10730225664 1364549872 14.278 99 28 56 0 1377131744 5875168 6706704
15:23:18 40094 8192000000 10279021440 10734960640 1377782112 14.397 100 20 40 1 1396214000 13640544 4791344
15:24:59 42515 8192000000 10288065920 10742218752 1372361152 14.348 99 25 50 1 1387369328 9174480 5833696
15:26:42 72286 8192000000 10282125728 10742800384 1374752480 14.370 99 23 46 1 1391977856 11424208 5801168
15:29:06 56033 8192000000 10268478496 10726273024 1358521232 14.224 98 27 54 1 1374011472 9187968 6302272
15:31:05 16013 8192000000 10295143232 10754514944 1391427328 14.519 100 18 36 0 1411075840 14657968 4990544
15:32:22 74871 8192000000 10303677408 10767659008 1402505312 14.617 100 13 26 0 1420546496 14507184 3534000
15:34:55 50855 8192000000 10283066368 10742935552 1354379280 14.187 97 16 33 1 1367205328 9153376 3672672
15:36:48 78550 8192000000 10295735120 10754080768 1352722864 14.172 97 17 34 1 1375694944 19046112 3925968
15:39:08 46318 8192000000 10302075520 10751447040 1360351600 14.241 98 38 76 0 1379319184 11171056 7796528
15:40:59 62961 8192000000 10315406800 10765082624 1376278896 14.383 98 16 32 0 1398262208 17882016 4101296
15:43:26 39992 8192000000 10295364224 10741522432 1339359296 14.052 96 22 44 1 1351382864 7392320 4631248
15:45:11 37824 8192000000 10287389248 10722590720 1331998032 13.985 96 30 60 1 1347257776 8227328 7032416
15:46:53 75063 8192000000 10294325280 10766295040 1383372832 14.447 100 24 48 1 1408681808 19841328 5467648
15:49:24 107591 8192000000 10269164352 10711277568 1318232752 13.861 96 26 52 0 1335935472 11876640 5826080
15:52:37 44534 8192000000 10272666048 10727202816 1368092976 14.310 100 26 52 0 1386508080 12628656 5786448
15:54:23 71838 8192000000 10250914320 10700242944 1303674912 13.729 95 12 24 0 1314176384 8073056 2428416
15:56:35 50991 8192000000 10259747376 10709422080 1360967120 14.246 100 12 24 1 1374729600 11130048 2632432
15:59:59 44636 8192000000 10257024064 10700337152 1291991008 13.622 95 17 34 0 1303324016 7456224 3876784
16:01:52 79575 8192000000 10264045248 10711703552 1337520144 14.035 98 13 26 0 1348342048 8117424 2704480
16:04:12 46142 8192000000 10279976336 10726137856 1334246176 14.006 97 12 24 0 1350021456 13421920 2353360
16:05:59 84523 8192000000 10277373360 10723278848 1339977136 14.057 98 14 28 0 1358943552 15846720 3119696
16:08:24 36259 8192000000 10295034656 10730729472 1353800816 14.182 99 37 73 1 1378404432 16432240 8171376
16:10:02 99413 8192000000 10293023024 10741043200 1361019040 14.247 99 21 42 0 1381500240 15912224 4568976
16:12:43 137624 8192000000 10273834352 10724880384 1345665776 14.108 97 16 32 0 1358498256 9361088 3471392
16:16:01 59628 8192000000 10294248656 10753982464 1395468736 14.555 100 7 14 0 1410247168 13233088 1545344
16:18:01 82277 8192000000 10314017520 10779328512 1405584400 14.645 100 18 36 1 1425505120 14932624 4988096
16:20:30 62597 8192000000 10301342640 10761211904 1367406864 14.304 97 11 22 0 1384383600 14642928 2333808
16:22:33 89521 8192000000 10294145216 10752446464 1357424992 14.214 97 11 22 1 1369923328 10235040 2263296
16:25:07 64322 8192000000 10306894560 10749845504 1372638864 14.351 98 19 38 0 1389520048 12267216 4613968
16:27:23 70879 8192000000 10319334096 10776801280 1388425072 14.492 99 31 62 1 1416371536 20891104 7055360
16:29:39 56320 8192000000 10287941056 10736074752 1346736000 14.118 97 12 24 0 1368003168 18601456 2665712
16:31:36 31424 8192000000 10296396992 10725982208 1319814976 13.875 95 50 100 1 1341871808 11432224 10624608
16:33:10 120474 8192000000 10277172064 10723627008 1356759264 14.208 99 28 56 0 1371955776 8941072 6255440
16:36:11 61036 8192000000 10274861984 10741825536 1365859248 14.290 99 10 20 0 1380086528 11946096 2281184
16:38:16 51993 8192000000 10269225088 10720825344 1350600672 14.153 98 4 8 0 1358380144 6981600 797872
16:40:12 79767 8192000000 10276419680 10735247360 1373593728 14.359 99 16 32 0 1386747888 9362032 3792128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-10-2021 16:44:30 to 30-10-2021 16:44:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2021 16:44:46 to 30-10-2021 16:44:48.

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          237464
MON$OLDEST_ACTIVE               238091
MON$OLDEST_SNAPSHOT             238091
MON$NEXT_TRANSACTION            238091
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                       2013824
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.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Oct 30 16:42:41 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Oct 30 16:42:50 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

30.10.21 16:44:49. 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-10-30 16:44:51 No severe PSQL-related problems occured
30.10.21 16:44:52. End of report.