Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.11.2021 22: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 16002260 kB
MemAvailable 30765732 kB
Buffers 0 kB
Cached 15680888 kB
SwapCached 2456 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-11-09 22:37:57
DTS_END                         2021-11-09 22:37:57.2840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53734
TRN_ID                          237507
ATT_ID                          623
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: 9303.24 23631 1116389
customer order: creation 168.59 384 20231
customer order: refuse 14.40 388 1728
order to supplier: creation 44.83 1297 5380
order to supplier: removal 3.57 111193 428
invoice (draft): creation 45.68 3505 5482
invoice (draft): removal 55.46 799 6655
invoice accept: apply 40.92 18903 4911
invoice accept: cancel 49.75 714 5970
customer reserve: creation 3758.44 187 451013
customer reserve: removal 4826.84 64 579221
realization accept: apply 84.20 190 10104
realization accept: cancel 83.73 127 10047
payment from customer: creation 58.42 116 7010
payment from customer: removal 5.21 71 625
payment to supplier: creation 48.25 135 5790
payment to supplier: removal 3.37 179 404
service: total inventory turnovers 4.44 1289 533
service: total monetary turnovers 6.34 41 761
service: aggregate perf. data 0.77 23686 92
service: refresh index statistics 0.03 309360 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2021 22:38:50 to 09-11-2021 22:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2307
TEST_TIME 2 3064
TEST_TIME 3 3658
TEST_TIME 4 4622
TEST_TIME 5 4326
TEST_TIME 6 4408
TEST_TIME 7 4915
TEST_TIME 8 4772
TEST_TIME 9 5231
TEST_TIME 10 5214
TEST_TIME 11 5620
TEST_TIME 12 5905
TEST_TIME 13 6057
TEST_TIME 14 6129
TEST_TIME 15 6408
TEST_TIME 16 6477
TEST_TIME 17 6507
TEST_TIME 18 6486
TEST_TIME 19 6787
TEST_TIME 20 7005
TEST_TIME 21 7411
TEST_TIME 22 7688
TEST_TIME 23 7938
TEST_TIME 24 8046
TEST_TIME 25 8201
TEST_TIME 26 8317
TEST_TIME 27 8537
TEST_TIME 28 8730
TEST_TIME 29 9787
TEST_TIME 30 10263
TEST_TIME 31 10475
TEST_TIME 32 10373
TEST_TIME 33 10261
TEST_TIME 34 10095
TEST_TIME 35 10010
TEST_TIME 36 10000
TEST_TIME 37 9980
TEST_TIME 38 9931
TEST_TIME 39 9823
TEST_TIME 40 9797
TEST_TIME 41 9658
TEST_TIME 42 9594
TEST_TIME 43 9534
TEST_TIME 44 9465
TEST_TIME 45 9395
TEST_TIME 46 9483
TEST_TIME 47 9334
TEST_TIME 48 9401
TEST_TIME 49 9412
TEST_TIME 50 9443
TEST_TIME 51 9519
TEST_TIME 52 9483
TEST_TIME 53 9481
TEST_TIME 54 9647
TEST_TIME 55 9625
TEST_TIME 56 9722
TEST_TIME 57 9688
TEST_TIME 58 9846
TEST_TIME 59 9863
TEST_TIME 60 9874
TEST_TIME 61 9811
TEST_TIME 62 9781
TEST_TIME 63 9731
TEST_TIME 64 9634
TEST_TIME 65 9628
TEST_TIME 66 9583
TEST_TIME 67 9526
TEST_TIME 68 9454
TEST_TIME 69 9494
TEST_TIME 70 9461
TEST_TIME 71 9471
TEST_TIME 72 9554
TEST_TIME 73 9562
TEST_TIME 74 9554
TEST_TIME 75 9562
TEST_TIME 76 9583
TEST_TIME 77 9550
TEST_TIME 78 9530
TEST_TIME 79 9582
TEST_TIME 80 9561
TEST_TIME 81 9540
TEST_TIME 82 9484
TEST_TIME 83 9429
TEST_TIME 84 9356
TEST_TIME 85 9322
TEST_TIME 86 9303
TEST_TIME 87 9251
TEST_TIME 88 9225
TEST_TIME 89 9233
TEST_TIME 90 9316
TEST_TIME 91 9312
TEST_TIME 92 9282
TEST_TIME 93 9318
TEST_TIME 94 9369
TEST_TIME 95 9337
TEST_TIME 96 9354
TEST_TIME 97 9323
TEST_TIME 98 9338
TEST_TIME 99 9434
TEST_TIME 100 9408
TEST_TIME 101 9411
TEST_TIME 102 9460
TEST_TIME 103 9459
TEST_TIME 104 9427
TEST_TIME 105 9348
TEST_TIME 106 9306
TEST_TIME 107 9339
TEST_TIME 108 9308
TEST_TIME 109 9304
TEST_TIME 110 9299
TEST_TIME 111 9262
TEST_TIME 112 9233
TEST_TIME 113 9190
TEST_TIME 114 9282
TEST_TIME 115 9272
TEST_TIME 116 9250
TEST_TIME 117 9261
TEST_TIME 118 9289
TEST_TIME 119 9259
TEST_TIME 120 9308

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-11-2021 22:38:53 to 09-11-2021 22:38:57.

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 1535066 1535066 0 0.00 0 25528 7 0 0
doc_list_biud 1535064 1535064 0 0.00 0 53724 30 0 0
sp_add_invoice_to_stock 5049 4911 138 2.73 40 252892 18904 0 138
sp_cancel_adding_invoice 5970 5970 0 0.00 4 67156 714 0 0
sp_cancel_client_order 1728 1728 0 0.00 3 10974 388 0 0
sp_cancel_customer_reserve 579221 579221 0 0.00 1 53726 65 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 2849 72 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 16094 180 0 0
sp_cancel_supplier_invoice 6670 6655 15 0.22 1 46902 799 0 15
sp_cancel_supplier_order 428 428 0 0.00 9 1150394 111194 0 0
sp_cancel_write_off 10047 10047 0 0.00 1 17637 127 0 0
sp_client_order 20231 20231 0 0.00 3 47199 384 0 0
sp_customer_reserve 451013 451013 0 0.00 0 73628 188 0 0
sp_fill_shopping_cart 31086 31086 0 0.00 1 30969 44 0 0
sp_fill_shopping_cart_clo_res 451002 451002 0 0.00 0 14199 16 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 40165 352 0 0
sp_kill_cost_storno 17046 17046 0 0.00 0 16087 66 0 0
sp_kill_qstorno_ret_qs2qd 1175572 1175572 0 0.00 0 53721 35 0 0
sp_kill_qty_storno 619051 619051 0 0.00 0 53724 74 0 0
sp_lock_selected_doc 20678 20678 0 0.00 0 10369 6 0 0
sp_make_cost_storno 27802 27802 0 0.00 0 25513 62 0 0
sp_multiply_rows_for_pdistr 27802 27802 0 0.00 0 16052 32 0 0
sp_multiply_rows_for_qdistr 20231 20231 0 0.00 0 47185 184 0 0
sp_pay_from_customer 7010 7010 0 0.00 4 19562 117 0 0
sp_pay_to_supplier 5790 5790 0 0.00 5 26392 136 0 0
sp_payment_common 12800 12800 0 0.00 1 19202 32 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 10967 362 0 0
sp_qd_handle_on_invoice_upd_sts 10868 10868 0 0.00 0 15530 324 0 0
sp_qd_handle_on_reserve_upd_sts 10104 10104 0 0.00 0 10239 56 0 0
sp_reserve_write_off 10104 10104 0 0.00 6 20337 190 0 0
sp_supplier_invoice 5482 5482 0 0.00 6 86734 3506 0 0
sp_supplier_order 5380 5380 0 0.00 5 60128 1298 0 0
srv_aggregate_perf_data 92 92 0 0.00 3174 390348 23687 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1748 134118 45496 0 0
srv_find_qd_qs_mism 1070150 1070150 0 0.00 0 9098 5 0 0
srv_make_invnt_saldo 533 533 0 0.00 50 99640 1289 0 0
srv_make_money_saldo 761 761 0 0.00 1 13607 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 221310 550563 309361 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4911 4911 0 0.00 0 13999 198 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 0 29853 223 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 2222 12 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 1627 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 4011 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 4302 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 754 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 39053 1673 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 1829 10 0 0
t$perf-norm:sp_client_order 20231 20231 0 0.00 0 9682 23 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 3247 8 0 0
t$perf-norm:sp_pay_from_customer 7010 7010 0 0.00 0 15633 16 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 13881 18 0 0
t$perf-norm:sp_reserve_write_off 10104 10104 0 0.00 0 12859 16 0 0
t$perf-norm:sp_supplier_invoice 5482 5482 0 0.00 0 12768 24 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 10334 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 146 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3109 52 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 1063 26 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1430 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10070 9794 276 2.74 0 7997 22 0 276
v_all_customers 37248 37248 0 0.00 0 88 1 0 0
v_all_suppliers 30112 30112 0 0.00 0 16 1 0 0
v_all_wares 40462 40462 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 512 7 0 0
v_cancel_client_order 3456 3456 0 0.00 0 1489 4 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 660 5 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 1790 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2494 13 0 0
v_cancel_supplier_invoice 870 840 30 3.44 0 195 12 0 30
v_cancel_supplier_order 856 856 0 0.00 0 3745 11 0 0
v_cancel_write_off 2060 2060 0 0.00 0 5580 20 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 1502 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 1550 12 0 0
v_max_id_ord_sup 5476 5476 0 0.00 0 4011 4 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 5414 6 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 8934 6 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 2419 6 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 5565 10 0 0
v_min_id_ord_sup 5476 5476 0 0.00 0 5900 9 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 19193 21 0 0
v_min_non_paid_realizn 7010 7010 0 0.00 0 16750 26 0 0
v_our_firm 10006 10006 0 0.00 0 19 1 0 0
v_reserve_write_off 20208 20208 0 0.00 0 4259 8 0 0
x_lock_depdocs_on_canc_invoice 12205 12205 0 0.00 0 8755 122 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 17942 405 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 2 60121 1207 0 0
x_make_qstorno_1200_2000 5482 5482 0 0.00 3 80183 3348 0 0
x_make_qstorno_2100_3300 451013 451013 0 0.00 0 73624 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2021 22:38:58 to 09-11-2021 22:39:02.

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
20:08:01 18 8192000000 8811809280 8827215872 15703184 0.191 4 4 6 0 15940496 88016 149296
20:09:01 32981 8192000000 9920537776 10234937344 992483440 10.806 100 87 174 0 1021778304 6156032 23138832
20:10:38 28943 8192000000 9967187776 10294001664 1048047072 11.342 100 93 185 2 1080630208 8923584 23659552
20:12:10 16602 8192000000 9993650544 10337271808 1084292560 11.688 100 77 154 0 1112634960 9507168 18835232
20:13:34 14075 8192000000 10051866608 10403971072 1131160384 12.132 100 76 152 1 1159705712 10277648 18267680
20:14:51 9952 8192000000 10096743184 10458468352 1182663888 12.615 100 86 172 1 1214804608 12416928 19723792
20:16:03 5077 8192000000 10141257984 10515193856 1230676928 13.060 100 76 151 1 1259322784 12287792 16358064
20:17:10 3130 8192000000 10164690752 10545303552 1257381056 13.306 100 76 152 2 1287649904 13547744 16721104
20:18:15 2031 8192000000 10204761360 10586304512 1287434944 13.581 100 91 180 2 1320555680 12653776 20466960
20:19:19 1569 8192000000 10211088768 10600804352 1299837408 13.694 100 89 178 3 1334627648 14283840 20506400
20:20:27 10485 8192000000 10196908560 10606620672 1306835440 13.757 100 32 64 0 1320409824 6745664 6828720
20:21:41 7199 8192000000 10203961792 10622918656 1317116832 13.851 100 17 34 1 1326745440 5685312 3943296
20:22:52 7303 8192000000 10215060016 10633129984 1327548576 13.945 100 18 36 0 1337139664 5805168 3785920
20:24:05 16380 8192000000 10222063552 10640355328 1333693808 14.001 100 26 52 1 1346266208 5147024 7425376
20:25:22 28640 8192000000 10232481840 10653753344 1338223328 14.041 100 34 68 2 1352402512 6495520 7683664
20:26:52 5582 8192000000 10238335408 10654908416 1326044432 13.931 99 29 58 1 1338315872 5409424 6862016
20:28:01 10264 8192000000 10203313728 10627600384 1319296576 13.870 99 17 34 1 1328827760 5451488 4079696
20:29:12 62942 8192000000 10179036976 10583941120 1217765616 12.941 94 28 54 0 1229528240 5217744 6544880
20:31:17 4813 8192000000 10156679440 10554150912 1240637200 13.152 98 32 64 2 1254638848 6469056 7532592
20:32:23 6589 8192000000 10133377232 10532204544 1234736720 13.098 99 31 62 1 1247851376 5336288 7778368
20:33:31 6048 8192000000 10167108016 10570547200 1271403248 13.434 100 22 43 0 1281962224 5220928 5338048
20:34:42 33572 8192000000 10173857200 10585321472 1283283952 13.543 100 30 60 2 1296428000 6286768 6857280
20:36:18 8826 8192000000 10190122608 10599309312 1296400144 13.663 100 33 67 1 1310212640 6458912 7353584
20:37:27 8783 8192000000 10195573680 10612092928 1305198352 13.742 100 20 40 1 1315352032 5802624 4351056
20:38:41 26965 8192000000 10241310032 10646482944 1332247584 13.987 100 49 98 1 1351496128 8264816 10983728
20:40:13 62466 8192000000 10248806528 10666680320 1347219920 14.122 100 40 78 1 1364303808 8425648 8658240
20:42:25 23205 8192000000 10280733328 10698485760 1371275024 14.338 100 50 98 1 1394242720 11058160 11909536
20:43:51 8406 8192000000 10292768560 10712875008 1382227376 14.436 100 50 97 2 1406232608 11930336 12074896
20:45:04 14875 8192000000 10308151808 10728153088 1392535280 14.528 100 55 109 2 1417755376 12386992 12833104
20:46:22 9413 8192000000 10317762992 10740281344 1402334448 14.616 100 54 109 2 1429957472 14509904 13113120
20:47:40 4990 8192000000 10337808560 10751164416 1403989040 14.630 100 81 161 1 1441476496 17991392 19496064
20:48:56 11621 8192000000 10345245776 10762964992 1398758880 14.584 99 64 128 1 1437392784 22423648 16210256
20:50:12 2277 8192000000 10342004192 10765762560 1414292416 14.722 100 52 104 3 1452036144 24840752 12902976
20:51:17 8972 8192000000 10347235136 10767433728 1410796576 14.691 100 70 141 1 1458049488 29907792 17345120
20:52:30 5371 8192000000 10349084704 10765389824 1407766688 14.664 100 74 148 1 1454057792 27824288 18466816
20:53:40 6400 8192000000 10348359792 10762084352 1404593888 14.636 100 75 152 1 1453705664 29045184 20066592
20:54:53 2801 8192000000 10355854416 10767265792 1392959472 14.532 99 79 155 0 1450656624 36677792 21019360
20:56:03 4827 8192000000 10352731440 10758180864 1387352720 14.482 100 77 154 2 1454066960 46287552 20426688
20:57:13 1746 8192000000 10353330800 10755481600 1376046784 14.381 98 84 169 1 1445136896 46494432 22595680
20:58:19 3107 8192000000 10370468976 10773970944 1391971392 14.523 100 93 185 0 1473521280 56786272 24763616
20:59:26 14956 8192000000 10342100688 10770890752 1386458304 14.474 100 39 79 0 1448024464 50607856 10958304
21:00:47 8687 8192000000 10356070224 10779148288 1400149600 14.596 100 53 106 1 1459627408 45297408 14180400
21:02:16 13175 8192000000 10350058128 10778480640 1393271536 14.535 100 30 60 1 1453886736 52141952 8473248
21:04:10 23028 8192000000 10340272928 10778140672 1381662240 14.431 100 25 50 0 1445993552 57585152 6746160
21:05:36 50687 8192000000 10321501584 10763632640 1322191312 13.897 97 20 38 1 1386612704 58271008 6150384
21:07:31 94336 8192000000 10265228288 10719502336 1287107008 13.578 95 9 18 0 1317562368 28450192 2005168
21:10:06 31090 8192000000 10286987680 10716278784 1361807536 14.254 100 57 114 0 1386331504 10656784 13867184
21:11:42 90163 8192000000 10277460720 10724556800 1357880752 14.218 99 21 42 3 1373652864 10350912 5421200
21:14:13 76016 8192000000 10282964528 10733588480 1369112704 14.319 99 25 48 1 1384784208 10531552 5139952
21:16:35 47922 8192000000 10271850640 10730467328 1369148560 14.319 99 17 34 1 1382466864 8950448 4367856
21:18:50 45741 8192000000 10301760736 10759315456 1389312528 14.500 100 12 24 0 1410988000 18914064 2761408
21:20:36 39279 8192000000 10296671456 10743824384 1380355024 14.420 99 26 51 4 1405389760 18213488 6821248
21:22:24 134118 8192000000 10306958896 10762162176 1373188560 14.356 99 20 40 1 1397273488 19451744 4633184
21:25:39 61172 8192000000 10311350928 10778914816 1398973808 14.586 100 12 24 2 1423895104 22121040 2800256
21:27:43 49700 8192000000 10321204160 10774614016 1389880736 14.505 99 12 24 0 1408787456 16287264 2619456
21:29:34 56016 8192000000 10306408224 10765418496 1369354592 14.321 97 28 56 0 1386853808 11402336 6096880
21:31:31 79494 8192000000 10320556352 10786283520 1384239456 14.454 99 9 18 0 1406088864 19850288 1999120
21:33:51 70702 8192000000 10333714640 10801573888 1399347728 14.589 99 13 26 1 1421518640 19035184 3135728
21:36:47 58573 8192000000 10320879392 10769072128 1388962544 14.497 99 32 63 0 1409920496 13989312 6968640
21:38:46 77260 8192000000 10299880016 10755981312 1368119040 14.310 98 35 70 1 1391261776 15469936 7672800
21:41:12 40428 8192000000 10297510800 10752589824 1381444352 14.429 99 22 42 1 1394622736 8884544 4293840
21:42:54 38693 8192000000 10276715888 10730905600 1372477616 14.349 99 18 36 0 1386068848 9759312 3831920
21:44:38 76651 8192000000 10297437968 10759471104 1394287712 14.544 100 11 22 0 1411251712 14565936 2398064
21:46:58 30490 8192000000 10271111376 10722533376 1329031744 13.958 96 18 36 1 1342012176 8547232 4433200
21:48:35 67624 8192000000 10282891072 10744266752 1363235904 14.266 99 15 31 0 1382103120 15179792 3687424
21:51:02 41319 8192000000 10274454256 10711572480 1339187504 14.050 98 45 87 2 1356307120 8036864 9082752
21:52:43 50158 8192000000 10280889728 10717958144 1340617568 14.063 98 31 63 0 1360649856 12997200 7035088
21:54:39 26104 8192000000 10290755216 10719232000 1329107808 13.959 97 45 90 2 1347038736 8199712 9731216
21:56:07 53413 8192000000 10272403248 10729664512 1355539936 14.197 99 31 62 1 1374939696 12487120 6912640
21:58:08 70478 8192000000 10267554080 10703814656 1293778576 13.639 95 25 50 1 1308153312 9018080 5356656
22:00:22 75670 8192000000 10266174000 10705477632 1350106032 14.148 99 33 66 1 1363671536 5770432 7795072
22:02:38 49602 8192000000 10261984448 10722267136 1363681168 14.270 100 23 46 0 1382063712 13417568 4964976
22:04:46 67373 8192000000 10266213728 10719690752 1339965936 14.057 98 22 44 2 1363847680 18753728 5128016
22:07:40 26895 8192000000 10251777136 10707271680 1345129312 14.104 99 14 28 0 1360550048 12603536 2817200
22:09:07 62281 8192000000 10292111936 10741714944 1375211984 14.374 100 22 42 1 1395266384 15136384 4918016
22:11:11 66947 8192000000 10280756336 10727763968 1359423232 14.232 99 19 38 0 1372178944 7853840 4901872
22:13:26 39816 8192000000 10300815936 10755002368 1384366496 14.456 100 25 50 0 1401426128 10914896 6144736
22:15:16 55585 8192000000 10306795680 10765074432 1368786080 14.316 98 24 48 1 1392121952 18027728 5308144
22:17:14 52308 8192000000 10300136096 10746470400 1375828672 14.379 99 30 60 0 1392181808 9406496 6946640
22:19:07 115390 8192000000 10284070272 10738655232 1311314688 13.798 94 7 14 0 1325853504 12986256 1552560
22:22:02 103743 8192000000 10308818336 10760282112 1368985536 14.318 98 16 32 1 1385326272 13018432 3322304
22:24:52 35203 8192000000 10291255360 10755952640 1370412992 14.331 98 16 32 0 1398348816 24309088 3626736
22:26:46 40716 8192000000 10266335776 10736922624 1358595584 14.225 98 10 20 0 1372325872 11536416 2193872
22:28:33 72783 8192000000 10265904032 10722750464 1324685728 13.919 96 19 37 1 1343282624 13724864 4872032
22:30:46 66693 8192000000 10258411776 10724298752 1348270384 14.132 100 8 16 0 1369346304 19089856 1986064
22:33:29 44552 8192000000 10270535376 10722234368 1362097056 14.256 99 32 64 1 1379753696 10327504 7329136
22:35:22 75652 8192000000 10278120720 10723467264 1331583184 13.981 97 18 35 0 1344176752 9088320 3505248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2021 22:39:04 to 09-11-2021 22:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-11-2021 22:39:19 to 09-11-2021 22:39:21.

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          237112
MON$OLDEST_ACTIVE               237653
MON$OLDEST_SNAPSHOT             237653
MON$NEXT_TRANSACTION            237653
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                       2028960
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Nov  9 22:37:58 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Nov  9 22:38:04 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Nov  9 22:38:04 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

09.11.21 22:39:22. 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-11-09 22:39:25 No severe PSQL-related problems occured
09.11.21 22:39:26. End of report.