Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.2021 07:49
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 16050312 kB
MemAvailable 30869076 kB
Buffers 0 kB
Cached 15693180 kB
SwapCached 2480 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-04 07:47:36
DTS_END                         2021-11-04 07:47:36.9500
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(68:17)
IP                              ::1/58018
TRN_ID                          228239
ATT_ID                          620
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: 9194.62 23420 1103354
customer order: creation 164.17 454 19700
customer order: refuse 14.34 336 1721
order to supplier: creation 43.43 1401 5211
order to supplier: removal 3.33 122601 399
invoice (draft): creation 43.17 3459 5180
invoice (draft): removal 54.81 920 6577
invoice accept: apply 40.10 18652 4812
invoice accept: cancel 48.84 815 5861
customer reserve: creation 3723.49 182 446819
customer reserve: removal 4775.75 68 573090
realization accept: apply 79.44 196 9533
realization accept: cancel 82.26 135 9871
payment from customer: creation 56.26 111 6751
payment from customer: removal 5.69 91 683
payment to supplier: creation 45.99 144 5519
payment to supplier: removal 3.28 93 394
service: total inventory turnovers 3.74 3693 449
service: total monetary turnovers 5.60 650 672
service: aggregate perf. data 0.90 17067 108
service: refresh index statistics 0.03 297339 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2021 07:49:30 to 04-11-2021 07:49:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3722
TEST_TIME 2 4776
TEST_TIME 3 5143
TEST_TIME 4 5198
TEST_TIME 5 5592
TEST_TIME 6 5636
TEST_TIME 7 5715
TEST_TIME 8 5796
TEST_TIME 9 6151
TEST_TIME 10 6091
TEST_TIME 11 6073
TEST_TIME 12 6364
TEST_TIME 13 6588
TEST_TIME 14 6959
TEST_TIME 15 7098
TEST_TIME 16 7404
TEST_TIME 17 7388
TEST_TIME 18 7690
TEST_TIME 19 7793
TEST_TIME 20 8101
TEST_TIME 21 8399
TEST_TIME 22 8572
TEST_TIME 23 8810
TEST_TIME 24 9556
TEST_TIME 25 9640
TEST_TIME 26 10457
TEST_TIME 27 11119
TEST_TIME 28 10853
TEST_TIME 29 10700
TEST_TIME 30 10475
TEST_TIME 31 10288
TEST_TIME 32 10182
TEST_TIME 33 10025
TEST_TIME 34 10185
TEST_TIME 35 10263
TEST_TIME 36 10235
TEST_TIME 37 10203
TEST_TIME 38 10092
TEST_TIME 39 9942
TEST_TIME 40 9822
TEST_TIME 41 9617
TEST_TIME 42 9669
TEST_TIME 43 9621
TEST_TIME 44 9504
TEST_TIME 45 9498
TEST_TIME 46 9503
TEST_TIME 47 9600
TEST_TIME 48 9573
TEST_TIME 49 9564
TEST_TIME 50 9711
TEST_TIME 51 9622
TEST_TIME 52 9596
TEST_TIME 53 9778
TEST_TIME 54 9776
TEST_TIME 55 9799
TEST_TIME 56 9632
TEST_TIME 57 9705
TEST_TIME 58 9652
TEST_TIME 59 9610
TEST_TIME 60 9521
TEST_TIME 61 9438
TEST_TIME 62 9406
TEST_TIME 63 9319
TEST_TIME 64 9408
TEST_TIME 65 9355
TEST_TIME 66 9343
TEST_TIME 67 9331
TEST_TIME 68 9523
TEST_TIME 69 9460
TEST_TIME 70 9502
TEST_TIME 71 9567
TEST_TIME 72 9541
TEST_TIME 73 9552
TEST_TIME 74 9581
TEST_TIME 75 9592
TEST_TIME 76 9545
TEST_TIME 77 9477
TEST_TIME 78 9389
TEST_TIME 79 9448
TEST_TIME 80 9410
TEST_TIME 81 9394
TEST_TIME 82 9347
TEST_TIME 83 9355
TEST_TIME 84 9351
TEST_TIME 85 9307
TEST_TIME 86 9312
TEST_TIME 87 9333
TEST_TIME 88 9361
TEST_TIME 89 9349
TEST_TIME 90 9417
TEST_TIME 91 9558
TEST_TIME 92 9555
TEST_TIME 93 9589
TEST_TIME 94 9582
TEST_TIME 95 9541
TEST_TIME 96 9471
TEST_TIME 97 9426
TEST_TIME 98 9425
TEST_TIME 99 9402
TEST_TIME 100 9368
TEST_TIME 101 9347
TEST_TIME 102 9295
TEST_TIME 103 9237
TEST_TIME 104 9241
TEST_TIME 105 9203
TEST_TIME 106 9168
TEST_TIME 107 9218
TEST_TIME 108 9214
TEST_TIME 109 9287
TEST_TIME 110 9286
TEST_TIME 111 9282
TEST_TIME 112 9285
TEST_TIME 113 9385
TEST_TIME 114 9371
TEST_TIME 115 9353
TEST_TIME 116 9361
TEST_TIME 117 9302
TEST_TIME 118 9273
TEST_TIME 119 9221
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-11-2021 07:49:33 to 04-11-2021 07:49:37.

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 1517007 1517007 0 0.00 0 21036 7 0 0
doc_list_biud 1517007 1517007 0 0.00 0 60105 32 0 0
sp_add_invoice_to_stock 4936 4812 124 2.51 47 196644 18652 0 124
sp_cancel_adding_invoice 5861 5861 0 0.00 4 68924 816 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 7314 336 0 0
sp_cancel_customer_reserve 573090 573090 0 0.00 1 60106 68 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 6171 92 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 5453 93 0 0
sp_cancel_supplier_invoice 6589 6577 12 0.18 2 54226 921 0 12
sp_cancel_supplier_order 399 399 0 0.00 6 990121 122601 0 0
sp_cancel_write_off 9871 9871 0 0.00 1 11061 135 0 0
sp_client_order 19700 19700 0 0.00 4 82314 455 0 0
sp_customer_reserve 446819 446819 0 0.00 0 112725 183 0 0
sp_fill_shopping_cart 30064 30064 0 0.00 1 15725 38 0 0
sp_fill_shopping_cart_clo_res 446814 446814 0 0.00 0 18922 19 0 0
sp_get_clo_for_invoice 4807 4807 0 0.00 0 22179 412 0 0
sp_kill_cost_storno 16809 16809 0 0.00 0 6895 60 0 0
sp_kill_qstorno_ret_qs2qd 1163027 1163027 0 0.00 0 60101 37 0 0
sp_kill_qty_storno 611859 611859 0 0.00 0 60104 79 0 0
sp_lock_selected_doc 20011 20011 0 0.00 0 7316 5 0 0
sp_make_cost_storno 26610 26610 0 0.00 0 21007 60 0 0
sp_multiply_rows_for_pdistr 26610 26610 0 0.00 0 14688 34 0 0
sp_multiply_rows_for_qdistr 19700 19700 0 0.00 0 77187 253 0 0
sp_pay_from_customer 6751 6751 0 0.00 4 14830 111 0 0
sp_pay_to_supplier 5519 5519 0 0.00 5 15096 144 0 0
sp_payment_common 12270 12270 0 0.00 1 8334 26 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 7268 301 0 0
sp_qd_handle_on_invoice_upd_sts 10668 10668 0 0.00 0 28434 333 0 0
sp_qd_handle_on_reserve_upd_sts 9533 9533 0 0.00 0 12404 58 0 0
sp_reserve_write_off 9533 9533 0 0.00 7 20163 197 0 0
sp_supplier_invoice 5180 5180 0 0.00 6 87726 3460 0 0
sp_supplier_order 5211 5211 0 0.00 6 38610 1402 0 0
srv_aggregate_perf_data 108 108 0 0.00 2707 165705 17068 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2105 155148 48139 0 0
srv_find_qd_qs_mism 1057773 1057773 0 0.00 0 15387 5 0 0
srv_make_invnt_saldo 449 449 0 0.00 61 96573 3694 0 0
srv_make_money_saldo 672 672 0 0.00 1 15190 651 0 0
srv_recalc_idx_stat 4 4 0 0.00 223202 368367 297339 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4812 4812 0 0.00 0 16976 172 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 2852 101 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 4056 14 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 551 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1523 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1226 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 610 16 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 20012 1439 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 8812 14 0 0
t$perf-norm:sp_client_order 19700 19700 0 0.00 0 9996 19 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 5146 19 0 0
t$perf-norm:sp_pay_from_customer 6751 6751 0 0.00 0 4635 10 0 0
t$perf-norm:sp_pay_to_supplier 5519 5519 0 0.00 0 10125 11 0 0
t$perf-norm:sp_reserve_write_off 9533 9533 0 0.00 0 14991 14 0 0
t$perf-norm:sp_supplier_invoice 5180 5180 0 0.00 0 8493 20 0 0
t$perf-norm:sp_supplier_order 5211 5211 0 0.00 0 4336 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 714 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 138 3 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 3940 25 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 2049 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9862 9614 248 2.51 0 6257 17 0 248
v_all_customers 35852 35852 0 0.00 0 105 1 0 0
v_all_suppliers 28716 28716 0 0.00 0 18 1 0 0
v_all_wares 39400 39400 0 0.00 0 153 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2445 13 0 0
v_cancel_client_order 3442 3442 0 0.00 0 5431 9 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 4621 13 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 5435 10 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 5240 12 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 1846 14 0 24
v_cancel_supplier_order 798 798 0 0.00 0 2576 8 0 0
v_cancel_write_off 2014 2014 0 0.00 0 1026 12 0 0
v_max_id_clo_ord 5211 5211 0 0.00 0 442 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 3570 25 0 0
v_max_id_ord_sup 5153 5153 0 0.00 0 4383 4 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 1386 3 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 4893 5 0 0
v_min_id_clo_ord 5211 5211 0 0.00 0 2599 4 0 0
v_min_id_clo_res 959 959 0 0.00 0 489 2 0 0
v_min_id_ord_sup 5153 5153 0 0.00 0 5526 6 0 0
v_min_non_paid_invoice 5519 5519 0 0.00 0 8328 20 0 0
v_min_non_paid_realizn 6751 6751 0 0.00 0 7579 15 0 0
v_our_firm 9592 9592 0 0.00 0 27 1 0 0
v_reserve_write_off 19066 19066 0 0.00 0 5903 6 0 0
x_lock_depdocs_on_canc_invoice 12024 12024 0 0.00 0 14822 98 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 6114 351 0 0
x_make_qstorno_1000_1200 5211 5211 0 0.00 1 38605 1329 0 0
x_make_qstorno_1200_2000 5180 5180 0 0.00 3 83629 3313 0 0
x_make_qstorno_2100_3300 446819 446819 0 0.00 0 112723 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2021 07:49:37 to 04-11-2021 07:49:41.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:40 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:42 31046 8192000000 9728046384 10029572096 801056816 8.907 100 81 162 0 828575536 7636080 19882640
05:20:22 26716 8192000000 9779878112 10093228032 862659984 9.527 100 88 177 0 895271632 12119296 20492352
05:21:51 16857 8192000000 9819694224 10141806592 886498048 9.764 100 87 174 0 927742656 21859120 19385488
05:23:13 14486 8192000000 9895089120 10232778752 931211872 10.207 100 78 156 0 996251536 48156928 16882736
05:24:35 11329 8192000000 9947014848 10292887552 973155216 10.617 100 77 153 1 1052243392 62592720 16495456
05:25:50 4862 8192000000 9945868864 10309844992 1003446736 10.912 100 71 142 0 1066365248 48431760 14486752
05:26:58 3776 8192000000 10003768576 10368819200 1031545264 11.183 100 78 156 1 1116462624 69318672 15598688
05:28:04 2053 8192000000 10017392912 10386542592 1055011136 11.409 100 91 182 0 1132407008 58834816 18561056
05:29:09 1725 8192000000 10063334640 10436829184 1074072960 11.591 100 91 184 2 1170991776 78415888 18502928
05:30:11 2904 8192000000 10027217808 10430119936 1079901856 11.647 100 45 90 2 1146554608 57999120 8653632
05:31:16 6312 8192000000 10019929136 10417754112 1087904752 11.723 100 44 88 1 1140633856 42840640 9888464
05:32:25 7797 8192000000 10001009408 10404880384 1098315840 11.822 100 36 71 2 1125262704 18442208 8504656
05:33:39 14771 8192000000 10019696608 10427392000 1102610896 11.862 100 19 38 0 1123763248 16995040 4157312
05:34:55 6181 8192000000 10043030816 10445733888 1107094688 11.905 100 29 60 1 1138400944 24591952 6714304
05:36:02 4206 8192000000 10016333840 10416463872 1111056704 11.942 100 57 114 1 1130924432 5578144 14289584
05:37:08 59494 8192000000 10008835040 10424426496 1101405104 11.851 100 29 55 1 1130725888 23103200 6217584
05:39:09 3989 8192000000 10000314528 10396151808 1073447664 11.585 100 60 122 2 1114250320 27662080 13140576
05:40:13 3779 8192000000 9951046000 10337882112 1013436192 11.009 97 48 97 3 1047540064 23095888 11007984
05:41:20 9737 8192000000 9916970304 10289917952 965426576 10.542 96 22 43 2 1002833664 32356464 5050624
05:42:34 39101 8192000000 9945025200 10340417536 1034903536 11.216 100 29 58 0 1057907824 17361008 5643280
05:44:13 5256 8192000000 9958844672 10349854720 1041071168 11.275 100 47 93 1 1075613392 23671840 10870384
05:45:19 10960 8192000000 9963059888 10347499520 1051562208 11.376 100 39 78 2 1077972928 17004048 9406672
05:46:32 5173 8192000000 9984718944 10369576960 1062181120 11.477 100 45 91 2 1095051904 22738256 10132528
05:47:39 76073 8192000000 10023555152 10417274880 1083219280 11.678 100 51 101 1 1127378304 33667872 10491152
05:50:03 29666 8192000000 10092921168 10491269120 1114519440 11.975 100 51 102 1 1186803904 62337232 9947232
05:51:39 30150 8192000000 10121365152 10528043008 1131979312 12.140 100 49 95 2 1214290464 71633040 10678112
05:53:16 17947 8192000000 10152900848 10559275008 1148173632 12.292 100 46 91 0 1244077472 86151280 9752560
05:54:42 7623 8192000000 10156885232 10569670656 1156516080 12.371 100 47 94 1 1272651968 106149984 9985904
05:55:53 10279 8192000000 10201841824 10611220480 1164355664 12.444 100 61 122 1 1311083376 133237824 13489888
05:57:07 6018 8192000000 10209080096 10621538304 1176913024 12.561 100 44 86 1 1314383872 127790720 9680128
05:58:18 5456 8192000000 10257559184 10667282432 1187551712 12.661 100 64 128 1 1356630960 154692640 14386608
05:59:31 3589 8192000000 10289989632 10691473408 1173891712 12.533 99 71 141 0 1385046368 194644000 16510656
06:00:42 7689 8192000000 10300127904 10721054720 1175656576 12.550 99 60 119 1 1412526400 223317056 13552768
06:01:56 5875 8192000000 10350366560 10761867264 1176070848 12.554 99 76 149 3 1457926464 264673280 17182336
06:03:05 6345 8192000000 10401507632 10805493760 1177246144 12.565 100 78 156 0 1494512624 299177936 18088544
06:04:19 2103 8192000000 10386994192 10800840704 1161921792 12.421 99 59 118 1 1486217856 310416144 13879920
06:05:29 2369 8192000000 10418411168 10836647936 1171178928 12.508 100 85 168 0 1513781680 321922544 20680208
06:06:35 7996 8192000000 10474642832 10903318528 1158959088 12.394 100 46 92 1 1574232400 403118784 12154528
06:07:57 11585 8192000000 10452494368 10899648512 1155803152 12.364 99 39 78 3 1556398608 390375504 10219952
06:09:20 16896 8192000000 10421483680 10848239616 1157326608 12.378 100 43 86 1 1521204160 352999552 10878000
06:10:39 37367 8192000000 10446816512 10954432512 1152383744 12.332 100 26 51 1 1544885648 385554176 6947728
06:12:18 16335 8192000000 10318589136 10763427840 1114033088 11.971 98 21 42 2 1391438384 272278304 5126992
06:13:36 30544 8192000000 10103768608 10645417984 1074592832 11.596 96 17 34 0 1295746736 217119488 4034416
06:15:07 116621 8192000000 10036184832 10477940736 1065427728 11.508 96 35 67 5 1106268928 33718752 7122448
06:18:04 110269 8192000000 10119878400 10545278976 1096249968 11.802 97 45 89 1 1169427072 64123984 9053120
06:21:39 112086 8192000000 10075739008 10532691968 1109802608 11.931 98 22 44 0 1212332672 97884608 4645456
06:24:32 27732 8192000000 10062630768 10560188416 1132816880 12.148 100 5 10 0 1185575616 51757728 1001008
06:26:00 45006 8192000000 10080796096 10522730496 1143187264 12.246 100 26 51 0 1189441760 40859520 5394976
06:27:48 72101 8192000000 10082789696 10543562752 1134836128 12.167 100 8 16 1 1188733520 52003744 1893648
06:30:01 57292 8192000000 10040851376 10527150080 1092680016 11.768 97 8 16 0 1144512544 50424064 1408464
06:31:59 38946 8192000000 10119703568 10567049216 1124572192 12.070 100 27 54 1 1200302304 69655232 6074880
06:33:53 52813 8192000000 10090208432 10545700864 1134076464 12.160 100 21 42 1 1201323728 62672320 4574944
06:35:47 40920 8192000000 10127041264 10567401472 1152443472 12.332 100 34 68 1 1211851088 51877536 7530080
06:37:34 60597 8192000000 10107888640 10568253440 1144550416 12.258 99 14 28 0 1192758304 45396400 2811488
06:39:36 47461 8192000000 10172278592 10620272640 1162744816 12.429 100 27 54 0 1259466176 90988064 5733296
06:41:23 56041 8192000000 10107219200 10591907840 1131544912 12.136 98 19 38 0 1202201536 66296464 4360160
06:43:28 90643 8192000000 10105207424 10604331008 1125856304 12.082 98 35 67 3 1199236992 65370896 8009792
06:46:24 45273 8192000000 10048070112 10505601024 1097099936 11.810 96 18 36 0 1133183856 32542368 3541552
06:48:18 56926 8192000000 10109268528 10575826944 1143994304 12.253 100 10 20 0 1212609456 66437776 2177376
06:50:31 66484 8192000000 10083309136 10598899712 1138970704 12.206 100 10 18 1 1235399760 94528656 1900400
06:52:38 40851 8192000000 10127880304 10580430848 1140903024 12.224 99 27 52 1 1217614592 70677104 6034464
06:54:20 42416 8192000000 10182430800 10630324224 1160464032 12.408 100 23 46 0 1268851776 102798720 5589024
06:56:12 41712 8192000000 10105745296 10531614720 1132730512 12.147 98 42 81 1 1179246176 37557168 8958496
06:57:56 64530 8192000000 10085771504 10588377088 1142010288 12.234 99 20 40 0 1205559440 59401760 4147392
07:00:04 24494 8192000000 10099995376 10538328064 1117906704 12.007 98 43 86 1 1183068624 56353776 8808144
07:01:32 40698 8192000000 10079897920 10505973760 1078719472 11.635 95 40 79 1 1127771664 41366704 7685488
07:03:13 155148 8192000000 10162273616 10586951680 1095920000 11.799 97 38 77 0 1183336336 78968272 8448064
07:06:49 35805 8192000000 10081170944 10627629056 1115767552 11.987 100 4 8 0 1191965600 75591728 606320
07:08:40 59276 8192000000 10063251504 10496081920 1098184432 11.820 98 13 24 0 1157461344 56305104 2971808
07:10:47 67965 8192000000 10048994544 10496421888 1087361552 11.718 96 16 32 1 1132401488 41522640 3517296
07:13:00 37256 8192000000 10137717808 10581233664 1128650544 12.109 99 22 45 0 1201932448 68491488 4790416
07:14:45 35008 8192000000 10128932656 10586480640 1110461488 11.937 98 23 46 0 1213329328 97808464 5059376
07:16:23 39353 8192000000 10160566800 10614407168 1116560480 11.994 98 33 66 2 1236661360 112334336 7766544
07:18:12 70949 8192000000 10078830448 10585096192 1102890464 11.865 97 9 18 0 1154374512 49969104 1514944
07:20:32 54189 8192000000 10117199840 10575474688 1127291136 12.096 98 18 36 0 1196598752 65909648 3397968
07:22:26 80241 8192000000 10081676832 10523619328 1138324752 12.200 99 32 64 1 1159647776 14286768 7036256
07:25:05 53371 8192000000 10053970832 10519183360 1114832496 11.978 97 21 40 2 1169086864 49967440 4286928
07:27:04 51479 8192000000 10045780176 10508513280 1122167696 12.047 99 6 12 1 1140089904 17157488 764720
07:28:56 97071 8192000000 10084547472 10546929664 1147957088 12.290 100 20 40 0 1192962352 40926720 4078544
07:31:55 40528 8192000000 10057001280 10503499776 1115682928 11.986 97 34 72 3 1148666480 26005344 6978208
07:33:36 33333 8192000000 10141919632 10581327872 1155108144 12.357 100 30 60 0 1226164608 64605184 6451280
07:35:15 125150 8192000000 10121028016 10607075328 1116806576 11.997 97 20 40 2 1192630432 71694080 4129776
07:38:38 54880 8192000000 10127010480 10556735488 1137937392 12.196 99 27 54 0 1209879680 66192544 5749744
07:40:47 79802 8192000000 10096088176 10546950144 1114751648 11.977 97 17 34 0 1161131264 43384528 2995088
07:43:07 107118 8192000000 10088667664 10534395904 1144708096 12.260 99 31 62 1 1201978592 50658624 6611872
07:46:01 70950 8192000000 10073116016 10552188928 1140171376 12.217 99 6 12 0 1173502160 32233280 1097504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-11-2021 07:49:43 to 04-11-2021 07:49:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-11-2021 07:49:59 to 04-11-2021 07:50:01.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228029
MON$OLDEST_ACTIVE               228459
MON$OLDEST_SNAPSHOT             228459
MON$NEXT_TRANSACTION            228459
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2037664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2652 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2652 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2652 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2652 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Nov  4 07:47:40 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Nov  4 07:47:57 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

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