Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2021 13:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16377100 kB
MemAvailable 31161284 kB
Buffers 0 kB
Cached 15270764 kB
SwapCached 4776 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.0
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-02-10 13:26:57
DTS_END                         2021-02-10 13:26:57.0650
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/45118
TRN_ID                          235733
ATT_ID                          631
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: 8953.64 22359 1074437
customer order: creation 167.39 394 20087
customer order: refuse 14.63 347 1756
order to supplier: creation 43.98 1360 5277
order to supplier: removal 3.72 104991 446
invoice (draft): creation 45.04 3394 5405
invoice (draft): removal 51.04 911 6125
invoice accept: apply 40.33 16403 4840
invoice accept: cancel 45.98 889 5517
customer reserve: creation 3731.41 160 447769
customer reserve: removal 4517.99 69 542159
realization accept: apply 82.23 175 9868
realization accept: cancel 82.52 116 9902
payment from customer: creation 58.71 111 7045
payment from customer: removal 5.73 88 687
payment to supplier: creation 48.82 116 5858
payment to supplier: removal 3.43 52 411
service: total inventory turnovers 3.81 3287 457
service: total monetary turnovers 6.01 475 721
service: aggregate perf. data 0.86 15770 103
service: refresh index statistics 0.03 298079 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-02-2021 13:27:59 to 10-02-2021 13:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1764
TEST_TIME 2 1670
TEST_TIME 3 2224
TEST_TIME 4 2722
TEST_TIME 5 3288
TEST_TIME 6 3694
TEST_TIME 7 3954
TEST_TIME 8 4210
TEST_TIME 9 4783
TEST_TIME 10 4960
TEST_TIME 11 5139
TEST_TIME 12 5701
TEST_TIME 13 6142
TEST_TIME 14 6741
TEST_TIME 15 6881
TEST_TIME 16 6972
TEST_TIME 17 7803
TEST_TIME 18 7959
TEST_TIME 19 7980
TEST_TIME 20 8234
TEST_TIME 21 8246
TEST_TIME 22 8387
TEST_TIME 23 8338
TEST_TIME 24 9261
TEST_TIME 25 9515
TEST_TIME 26 10887
TEST_TIME 27 10837
TEST_TIME 28 10555
TEST_TIME 29 10453
TEST_TIME 30 10594
TEST_TIME 31 10384
TEST_TIME 32 10238
TEST_TIME 33 10242
TEST_TIME 34 10232
TEST_TIME 35 10117
TEST_TIME 36 9970
TEST_TIME 37 10128
TEST_TIME 38 10190
TEST_TIME 39 10157
TEST_TIME 40 10145
TEST_TIME 41 10150
TEST_TIME 42 10121
TEST_TIME 43 10054
TEST_TIME 44 10135
TEST_TIME 45 10153
TEST_TIME 46 10076
TEST_TIME 47 9871
TEST_TIME 48 9743
TEST_TIME 49 9609
TEST_TIME 50 9695
TEST_TIME 51 9794
TEST_TIME 52 9694
TEST_TIME 53 9703
TEST_TIME 54 9615
TEST_TIME 55 9556
TEST_TIME 56 9472
TEST_TIME 57 9477
TEST_TIME 58 9375
TEST_TIME 59 9249
TEST_TIME 60 9389
TEST_TIME 61 9499
TEST_TIME 62 9476
TEST_TIME 63 9469
TEST_TIME 64 9417
TEST_TIME 65 9489
TEST_TIME 66 9536
TEST_TIME 67 9645
TEST_TIME 68 9600
TEST_TIME 69 9625
TEST_TIME 70 9713
TEST_TIME 71 9667
TEST_TIME 72 9609
TEST_TIME 73 9673
TEST_TIME 74 9589
TEST_TIME 75 9516
TEST_TIME 76 9474
TEST_TIME 77 9407
TEST_TIME 78 9346
TEST_TIME 79 9349
TEST_TIME 80 9270
TEST_TIME 81 9227
TEST_TIME 82 9234
TEST_TIME 83 9218
TEST_TIME 84 9242
TEST_TIME 85 9200
TEST_TIME 86 9234
TEST_TIME 87 9264
TEST_TIME 88 9205
TEST_TIME 89 9292
TEST_TIME 90 9346
TEST_TIME 91 9320
TEST_TIME 92 9430
TEST_TIME 93 9471
TEST_TIME 94 9376
TEST_TIME 95 9306
TEST_TIME 96 9263
TEST_TIME 97 9243
TEST_TIME 98 9201
TEST_TIME 99 9173
TEST_TIME 100 9140
TEST_TIME 101 9167
TEST_TIME 102 9180
TEST_TIME 103 9161
TEST_TIME 104 9141
TEST_TIME 105 9118
TEST_TIME 106 9123
TEST_TIME 107 9147
TEST_TIME 108 9177
TEST_TIME 109 9169
TEST_TIME 110 9225
TEST_TIME 111 9162
TEST_TIME 112 9095
TEST_TIME 113 9041
TEST_TIME 114 9018
TEST_TIME 115 8987
TEST_TIME 116 8987
TEST_TIME 117 8965
TEST_TIME 118 8953
TEST_TIME 119 8939
TEST_TIME 120 8954
TEST_TIME 121 8880

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-02-2021 13:28:03 to 10-02-2021 13:28:07.

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 1489316 1489316 0 0.00 0 16917 6 0 0
doc_list_biud 1489314 1489314 0 0.00 0 46160 32 0 0
sp_add_invoice_to_stock 4988 4840 148 2.96 44 235940 16403 0 148
sp_cancel_adding_invoice 5517 5517 0 0.00 6 86877 890 0 0
sp_cancel_client_order 1756 1756 0 0.00 5 17451 348 0 0
sp_cancel_customer_reserve 542159 542159 0 0.00 1 25114 70 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 8964 88 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 1146 52 0 0
sp_cancel_supplier_invoice 6135 6125 10 0.16 3 46305 911 0 10
sp_cancel_supplier_order 446 446 0 0.00 8 1019027 104992 0 0
sp_cancel_write_off 9902 9902 0 0.00 1 9698 116 0 0
sp_client_order 20087 20087 0 0.00 4 22599 395 0 0
sp_customer_reserve 447769 447769 0 0.00 0 99797 160 0 0
sp_fill_shopping_cart 30769 30769 0 0.00 1 17228 45 0 0
sp_fill_shopping_cart_clo_res 447767 447767 0 0.00 0 20514 17 0 0
sp_get_clo_for_invoice 4838 4838 0 0.00 0 31823 426 0 0
sp_kill_cost_storno 16517 16517 0 0.00 0 15304 57 0 0
sp_kill_qstorno_ret_qs2qd 1100791 1100791 0 0.00 0 46159 37 0 0
sp_kill_qty_storno 580607 580607 0 0.00 0 46160 80 0 0
sp_lock_selected_doc 20505 20505 0 0.00 0 9188 3 0 0
sp_make_cost_storno 27607 27607 0 0.00 0 16913 63 0 0
sp_multiply_rows_for_pdistr 27605 27605 0 0.00 0 14634 28 0 0
sp_multiply_rows_for_qdistr 20087 20087 0 0.00 0 20147 229 0 0
sp_pay_from_customer 7045 7045 0 0.00 5 16907 112 0 0
sp_pay_to_supplier 5858 5858 0 0.00 5 12116 117 0 0
sp_payment_common 12903 12903 0 0.00 1 8341 18 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 17443 324 0 0
sp_qd_handle_on_invoice_upd_sts 10352 10352 0 0.00 0 26177 348 0 0
sp_qd_handle_on_reserve_upd_sts 9867 9867 0 0.00 0 13229 57 0 0
sp_reserve_write_off 9868 9868 0 0.00 6 16930 175 0 0
sp_supplier_invoice 5405 5405 0 0.00 8 75823 3394 0 0
sp_supplier_order 5277 5277 0 0.00 5 46907 1360 0 0
srv_aggregate_perf_data 103 103 0 0.00 2970 159260 15770 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2094 132220 49545 0 0
srv_find_qd_qs_mism 1027960 1027960 0 0.00 0 10469 4 0 0
srv_make_invnt_saldo 457 457 0 0.00 30 78291 3288 0 0
srv_make_money_saldo 721 721 0 0.00 1 15046 475 0 0
srv_recalc_idx_stat 4 4 0 0.00 197229 439224 298080 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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 4840 4840 0 0.00 0 11856 141 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2940 85 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 4553 13 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 355 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2184 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 2993 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 356 11 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 20409 1321 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 1108 6 0 0
t$perf-norm:sp_client_order 20087 20087 0 0.00 0 7973 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1842 5 0 0
t$perf-norm:sp_pay_from_customer 7045 7045 0 0.00 0 3483 8 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 6664 8 0 0
t$perf-norm:sp_reserve_write_off 9868 9868 0 0.00 0 4577 8 0 0
t$perf-norm:sp_supplier_invoice 5405 5405 0 0.00 0 6791 17 0 0
t$perf-norm:sp_supplier_order 5277 5277 0 0.00 0 5327 13 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 458 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 253 9 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 3925 18 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 5131 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9966 9670 296 2.97 0 6683 17 0 296
v_all_customers 36682 36682 0 0.00 0 19 1 0 0
v_all_suppliers 29870 29870 0 0.00 0 50 1 0 0
v_all_wares 40174 40174 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 266 5 0 0
v_cancel_client_order 3512 3512 0 0.00 0 2127 4 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2609 10 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1016 4 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 673 4 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 2257 16 0 20
v_cancel_supplier_order 892 892 0 0.00 0 2600 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 814 13 0 0
v_max_id_clo_ord 5277 5277 0 0.00 0 230 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 2500 11 0 0
v_max_id_ord_sup 5405 5405 0 0.00 0 3129 5 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 151 2 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 5577 5 0 0
v_min_id_clo_ord 5277 5277 0 0.00 0 13595 13 0 0
v_min_id_clo_res 990 990 0 0.00 0 2005 8 0 0
v_min_id_ord_sup 5405 5405 0 0.00 0 5660 7 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 4110 9 0 0
v_min_non_paid_realizn 7045 7045 0 0.00 0 5560 12 0 0
v_our_firm 10004 10004 0 0.00 0 18 1 0 0
v_reserve_write_off 19734 19734 0 0.00 0 2271 4 0 0
x_lock_depdocs_on_canc_invoice 11239 11239 0 0.00 0 18814 122 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 9009 301 0 0
x_make_qstorno_1000_1200 5277 5277 0 0.00 2 46811 1282 0 0
x_make_qstorno_1200_2000 5405 5405 0 0.00 4 75811 3226 0 0
x_make_qstorno_2100_3300 447769 447769 0 0.00 0 99795 141 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-02-2021 13:28:07 to 10-02-2021 13:28:12.

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
10:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 24488 8192000000 9717091712 10016808960 801702096 8.914 100 83 166 1 829057136 7110544 20244496
10:59:43 31404 8192000000 9775111744 10084130816 847959312 9.380 100 92 183 2 882676512 12934608 21782592
11:01:19 15869 8192000000 9820998592 10141290496 886360720 9.763 100 84 168 1 929134208 24106160 18667328
11:02:40 13146 8192000000 9890612992 10227818496 932373792 10.218 100 77 154 1 999890320 50717472 16799056
11:03:55 9320 8192000000 9938044688 10284847104 978290368 10.668 100 77 153 0 1044851952 50949264 15612320
11:05:11 4624 8192000000 9969814224 10334216192 1017502752 11.048 100 79 157 0 1094100160 60448912 16148496
11:06:18 2060 8192000000 10023475824 10388684800 1034710192 11.214 100 89 175 1 1136190688 84079952 17400544
11:07:21 2058 8192000000 10036216608 10407456768 1061351904 11.469 100 89 179 1 1143863344 63537344 18974096
11:08:24 17733 8192000000 10004016976 10392690688 1068517184 11.538 100 43 88 0 1150326176 73305376 8503616
11:09:43 7873 8192000000 10013875488 10398187520 1086321200 11.708 100 49 97 1 1132969376 36768128 9880048
11:10:52 5012 8192000000 10006012640 10398089216 1088035712 11.724 100 42 84 3 1128420432 30390064 9994656
11:11:58 59994 8192000000 10000161616 10405441536 1091889744 11.761 100 41 82 0 1109992592 8704256 9398592
11:14:02 3760 8192000000 10021905216 10424934400 1098484704 11.823 100 43 87 1 1142882496 35255232 9142560
11:15:08 8408 8192000000 10011443040 10422829056 1086329360 11.708 99 21 41 0 1117661440 27116624 4215456
11:16:19 7597 8192000000 10027197920 10431049728 1087763616 11.721 99 43 86 1 1125050784 28373632 8913536
11:17:28 54180 8192000000 9996941872 10386472960 1030036448 11.169 96 41 80 1 1058958144 20153648 8768048
11:19:23 7962 8192000000 9958725824 10343649280 980220272 10.686 93 37 74 1 1011534288 23024160 8289856
11:20:34 7511 8192000000 9949260256 10344194048 1013810976 11.012 98 27 54 0 1049335440 29775280 5749184
11:21:46 11179 8192000000 9950205360 10340749312 1024255792 11.113 99 29 58 2 1048348448 17880272 6212384
11:22:59 17005 8192000000 9969284560 10375888896 1045795696 11.320 100 38 74 2 1083342304 29184416 8362192
11:24:18 9711 8192000000 9975113680 10376957952 1054635040 11.405 100 45 90 1 1085251120 20838112 9777968
11:25:30 8454 8192000000 9976111904 10382008320 1063759664 11.492 100 35 70 1 1098398432 26712864 7925904
11:26:39 7967 8192000000 9989451520 10381582336 1077545168 11.624 100 27 51 0 1105348272 22510832 5292272
11:27:51 110123 8192000000 10053634624 10466209792 1099257120 11.831 100 34 68 2 1152372864 45719328 7396416
11:30:47 37501 8192000000 10100975440 10513858560 1124820416 12.073 100 43 86 1 1223041136 89194064 9026656
11:32:29 14547 8192000000 10125213664 10536509440 1137154864 12.189 100 61 119 1 1237875520 86724176 13996480
11:33:46 16851 8192000000 10153723200 10567680000 1144791392 12.261 100 49 98 0 1267436864 111133280 11512192
11:35:07 5472 8192000000 10190481440 10603917312 1146825744 12.280 99 57 113 0 1298288192 138935680 12526768
11:36:17 3835 8192000000 10227091776 10643247104 1164767872 12.448 100 60 117 0 1341405472 164281808 12355792
11:37:25 2094 8192000000 10272873472 10685095936 1169384560 12.491 99 68 134 1 1369323152 184497728 15440864
11:38:31 13721 8192000000 10285146224 10704801792 1173758464 12.532 100 68 137 2 1389635216 200110112 15766640
11:39:49 2740 8192000000 10322680320 10727952384 1174375808 12.538 100 90 179 1 1418269856 223792512 20101536
11:40:57 2523 8192000000 10288392528 10695741440 1155651808 12.363 98 78 156 0 1382382800 209051760 17679232
11:42:06 12734 8192000000 10343083280 10758766592 1166644304 12.465 100 65 126 3 1436356144 254771856 14939984
11:43:22 4342 8192000000 10337238448 10767478784 1179518096 12.586 100 64 128 2 1456939104 261793168 15627840
11:44:34 2525 8192000000 10351221168 10756120576 1172010640 12.516 100 78 154 0 1447014112 257110224 17893248
11:45:43 6762 8192000000 10391020096 10804633600 1160091984 12.404 100 57 114 2 1490146752 315729360 14325408
11:46:55 9759 8192000000 10406606064 10838310912 1159500512 12.399 99 44 87 0 1509321584 338129136 11691936
11:48:09 9116 8192000000 10454221376 10879262720 1159772624 12.401 99 44 88 1 1542843376 371646528 11424224
11:49:27 3581 8192000000 10455374224 10895929344 1151423936 12.323 99 49 96 0 1553927856 389805280 12698640
11:50:34 18876 8192000000 10380497920 10868842496 1158712064 12.391 100 23 46 1 1475322608 310317712 6292832
11:52:09 31370 8192000000 10134515824 10738958336 1093539360 11.776 98 25 50 1 1393065680 293348272 6178048
11:53:41 64548 8192000000 10108385440 10547613696 1128587648 12.108 100 40 83 0 1204070848 66924304 8558896
11:55:47 50348 8192000000 10061283824 10573938688 1101911264 11.856 98 7 15 0 1191383312 87863392 1608656
11:58:11 74993 8192000000 10102156352 10536869888 1098419520 11.823 97 15 30 0 1151169904 49829376 2921008
12:00:31 42506 8192000000 10070286432 10506125312 1122710752 12.053 99 24 49 0 1145888352 18525472 4652128
12:02:15 85059 8192000000 10110807952 10579558400 1125166880 12.076 99 20 40 0 1205150848 75869888 4114080
12:04:45 30774 8192000000 10068293184 10493202432 1115843712 11.988 98 42 84 1 1137977504 13566096 8567696
12:06:17 94865 8192000000 10101159216 10577920000 1124577136 12.070 99 18 36 0 1178430256 50256624 3596496
12:09:00 48665 8192000000 10095476192 10522722304 1130634352 12.127 99 23 45 0 1157631216 21754960 5241904
12:10:54 44162 8192000000 10102382320 10566606848 1143323472 12.247 100 10 20 2 1196477024 51343920 1809632
12:12:49 117452 8192000000 10138296080 10581733376 1145189824 12.264 99 23 45 0 1180202928 30183472 4829632
12:15:49 96218 8192000000 10119123776 10557915136 1094388080 11.784 95 34 68 0 1176134096 73760640 7985376
12:18:29 31207 8192000000 10089263872 10517442560 1132144576 12.142 99 40 77 0 1176409424 35866224 8398624
12:20:01 115525 8192000000 10140744768 10609258496 1152028864 12.329 100 12 24 0 1204089632 49407136 2653632
12:22:57 97671 8192000000 10151336512 10606735360 1120793728 12.034 97 18 36 0 1234550368 109685120 4071520
12:26:24 53161 8192000000 10101721648 10679455744 1122379152 12.049 98 12 24 0 1251636560 126212240 3045168
12:28:24 49399 8192000000 10085944560 10546606080 1114468464 11.975 97 11 22 0 1161589312 44963328 2157520
12:30:14 32796 8192000000 10173329376 10634924032 1139057216 12.207 99 26 52 0 1230990256 86187168 5745872
12:31:47 69829 8192000000 10105158624 10583633920 1108161168 11.915 97 14 28 1 1218330560 106739568 3429824
12:34:01 60780 8192000000 10101029008 10545491968 1074676064 11.597 95 38 76 1 1150759744 67299168 8784512
12:36:06 132220 8192000000 10082100592 10531987456 1070777872 11.560 95 14 28 0 1121107616 47740656 2589088
12:39:18 73937 8192000000 10094574576 10551422976 1102679840 11.863 98 46 92 1 1149061552 36713920 9667792
12:42:07 68346 8192000000 10053574688 10530447360 1120865680 12.035 100 14 28 2 1170658448 46606832 3185936
12:44:15 38826 8192000000 10059520784 10519072768 1092331296 11.765 97 6 12 0 1145101088 51789440 980352
12:45:58 44692 8192000000 10053702288 10531110912 1114905856 11.979 99 5 10 0 1158368816 42561984 900976
12:47:47 46072 8192000000 10111326416 10557476864 1114494640 11.975 98 19 38 0 1182762032 64334608 3932784
12:49:33 82960 8192000000 10065424560 10538041344 1122854720 12.054 100 20 40 1 1186133856 59401568 3877568
12:52:01 39636 8192000000 10100745632 10536144896 1097266480 11.812 98 23 46 2 1178617424 76171184 5179760
12:54:02 94690 8192000000 10112274176 10627280896 1096388176 11.803 98 13 26 0 1190909728 91536512 2985040
12:56:37 67771 8192000000 10116719520 10607648768 1102394432 11.860 99 16 32 1 1180580224 75085376 3100416
12:58:45 62256 8192000000 10069403872 10563096576 1100080384 11.838 98 9 19 0 1151089888 49356560 1652944
13:00:54 60660 8192000000 10049699936 10528907264 1125724608 12.081 100 10 20 0 1155606080 27809920 2071552
13:03:08 44029 8192000000 10058106272 10523439104 1119866192 12.026 99 5 10 0 1150830544 30318352 646000
13:04:53 55843 8192000000 10098695696 10568065024 1124343264 12.068 99 20 38 3 1176671904 48732992 3595648
13:06:57 57709 8192000000 10110068112 10601508864 1138204400 12.199 100 23 46 1 1199951616 56872288 4874928
13:09:06 41037 8192000000 10099114096 10541989888 1144017904 12.253 100 42 83 1 1196666896 44093952 8555040
13:10:55 54748 8192000000 10112754272 10556743680 1121394832 12.040 98 34 68 1 1173026400 44850512 6781056
13:12:52 27555 8192000000 10103501568 10557542400 1139249552 12.208 100 32 63 0 1204514816 58947120 6318144
13:14:23 53002 8192000000 10089841632 10543534080 1116826464 11.997 98 24 48 0 1169277520 47812384 4638672
13:16:19 123717 8192000000 10049843488 10545606656 1110169920 11.934 98 7 14 0 1154174784 42682208 1322656
13:20:01 68695 8192000000 10061022928 10557837312 1129984080 12.121 100 9 18 0 1190797632 58831152 1982400
13:22:12 90280 8192000000 10068398800 10528362496 1091679472 11.759 96 17 34 2 1148923312 53472784 3771056
13:24:50 45843 8192000000 10065058352 10511306752 1093435216 11.775 97 26 52 0 1140667232 42160032 5071984
13:26:36 28743 8192000000 9092864128 9353863168 251823152 2.982 23 11 22 0 275703888 21488928 2391808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-02-2021 13:28:13 to 10-02-2021 13:28:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 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 10-02-2021 13:28:29 to 10-02-2021 13:28:31.

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          235944
MON$OLDEST_ACTIVE               236225
MON$OLDEST_SNAPSHOT             236225
MON$NEXT_TRANSACTION            236225
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                       2112000
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Wed Feb 10 13:27:08 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:235: 
:236: 
:237: mscs-test-161.budzdorov.ru    Wed Feb 10 13:27:09 2021
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
--- end of diff output ---

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

10.02.21 13:28:32. 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-02-10 13:28:35 No severe PSQL-related problems occured
10.02.21 13:28:36. End of report.