Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2021 10: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 15772492 kB
MemAvailable 30877620 kB
Buffers 0 kB
Cached 15963080 kB
SwapCached 4052 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-01 10:37:08
DTS_END                         2021-11-01 10:37:08.7170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41334
TRN_ID                          231344
ATT_ID                          680
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: 9473.77 24961 1136852
customer order: creation 170.68 383 20482
customer order: refuse 14.48 333 1738
order to supplier: creation 45.32 1419 5438
order to supplier: removal 3.58 109215 429
invoice (draft): creation 46.03 3656 5523
invoice (draft): removal 55.29 933 6635
invoice accept: apply 41.45 17337 4974
invoice accept: cancel 50.26 729 6031
customer reserve: creation 3861.33 169 463360
customer reserve: removal 4894.56 62 587347
realization accept: apply 84.04 196 10085
realization accept: cancel 79.53 113 9543
payment from customer: creation 58.36 118 7003
payment from customer: removal 5.56 69 667
payment to supplier: creation 48.78 123 5854
payment to supplier: removal 3.63 105 435
service: total inventory turnovers 3.68 3391 442
service: total monetary turnovers 6.32 429 758
service: aggregate perf. data 0.87 19161 104
service: refresh index statistics 0.03 341272 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2021 10:38:45 to 01-11-2021 10:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3306
TEST_TIME 2 3797
TEST_TIME 3 4928
TEST_TIME 4 4924
TEST_TIME 5 5192
TEST_TIME 6 5605
TEST_TIME 7 5802
TEST_TIME 8 6096
TEST_TIME 9 6375
TEST_TIME 10 6226
TEST_TIME 11 6528
TEST_TIME 12 6493
TEST_TIME 13 6918
TEST_TIME 14 7062
TEST_TIME 15 7320
TEST_TIME 16 7681
TEST_TIME 17 7924
TEST_TIME 18 8410
TEST_TIME 19 8696
TEST_TIME 20 8713
TEST_TIME 21 9303
TEST_TIME 22 9481
TEST_TIME 23 9903
TEST_TIME 24 11034
TEST_TIME 25 11487
TEST_TIME 26 11496
TEST_TIME 27 11533
TEST_TIME 28 11572
TEST_TIME 29 11472
TEST_TIME 30 11336
TEST_TIME 31 11256
TEST_TIME 32 11420
TEST_TIME 33 11393
TEST_TIME 34 11413
TEST_TIME 35 11401
TEST_TIME 36 11544
TEST_TIME 37 11426
TEST_TIME 38 11317
TEST_TIME 39 11142
TEST_TIME 40 11069
TEST_TIME 41 11001
TEST_TIME 42 10926
TEST_TIME 43 10854
TEST_TIME 44 10838
TEST_TIME 45 10730
TEST_TIME 46 10747
TEST_TIME 47 10885
TEST_TIME 48 10849
TEST_TIME 49 10756
TEST_TIME 50 10677
TEST_TIME 51 10579
TEST_TIME 52 10631
TEST_TIME 53 10508
TEST_TIME 54 10564
TEST_TIME 55 10574
TEST_TIME 56 10561
TEST_TIME 57 10533
TEST_TIME 58 10500
TEST_TIME 59 10433
TEST_TIME 60 10415
TEST_TIME 61 10490
TEST_TIME 62 10452
TEST_TIME 63 10436
TEST_TIME 64 10492
TEST_TIME 65 10463
TEST_TIME 66 10509
TEST_TIME 67 10484
TEST_TIME 68 10514
TEST_TIME 69 10455
TEST_TIME 70 10354
TEST_TIME 71 10341
TEST_TIME 72 10306
TEST_TIME 73 10232
TEST_TIME 74 10270
TEST_TIME 75 10171
TEST_TIME 76 10114
TEST_TIME 77 10095
TEST_TIME 78 10012
TEST_TIME 79 10042
TEST_TIME 80 10050
TEST_TIME 81 10012
TEST_TIME 82 10031
TEST_TIME 83 9963
TEST_TIME 84 10007
TEST_TIME 85 10024
TEST_TIME 86 10032
TEST_TIME 87 10219
TEST_TIME 88 10280
TEST_TIME 89 10257
TEST_TIME 90 10252
TEST_TIME 91 10180
TEST_TIME 92 10097
TEST_TIME 93 10047
TEST_TIME 94 9992
TEST_TIME 95 9933
TEST_TIME 96 9932
TEST_TIME 97 9972
TEST_TIME 98 9964
TEST_TIME 99 9926
TEST_TIME 100 9915
TEST_TIME 101 9894
TEST_TIME 102 9962
TEST_TIME 103 9931
TEST_TIME 104 9960
TEST_TIME 105 9958
TEST_TIME 106 9917
TEST_TIME 107 9910
TEST_TIME 108 9878
TEST_TIME 109 9888
TEST_TIME 110 9803
TEST_TIME 111 9810
TEST_TIME 112 9817
TEST_TIME 113 9769
TEST_TIME 114 9712
TEST_TIME 115 9672
TEST_TIME 116 9632
TEST_TIME 117 9600
TEST_TIME 118 9557
TEST_TIME 119 9555
TEST_TIME 120 9479

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-11-2021 10:38:48 to 01-11-2021 10:38:52.

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 1565770 1565770 0 0.00 0 45141 6 0 0
doc_list_biud 1565770 1565770 0 0.00 0 32032 29 0 0
sp_add_invoice_to_stock 5171 4974 197 3.80 17 274342 17338 0 197
sp_cancel_adding_invoice 6031 6031 0 0.00 6 56201 730 0 0
sp_cancel_client_order 1738 1738 0 0.00 5 10197 334 0 0
sp_cancel_customer_reserve 587347 587347 0 0.00 1 32037 62 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 7824 70 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 5496 105 0 0
sp_cancel_supplier_invoice 6647 6635 12 0.18 3 25902 933 0 12
sp_cancel_supplier_order 429 429 0 0.00 8 1108166 109215 0 0
sp_cancel_write_off 9543 9543 0 0.00 1 11870 113 0 0
sp_client_order 20482 20482 0 0.00 4 33517 384 0 0
sp_customer_reserve 463360 463360 0 0.00 0 49000 169 0 0
sp_fill_shopping_cart 31422 31422 0 0.00 1 16929 45 0 0
sp_fill_shopping_cart_clo_res 463356 463356 0 0.00 0 20076 14 0 0
sp_get_clo_for_invoice 4961 4961 0 0.00 0 30556 335 0 0
sp_kill_cost_storno 16676 16676 0 0.00 0 11864 60 0 0
sp_kill_qstorno_ret_qs2qd 1191301 1191301 0 0.00 0 32030 34 0 0
sp_kill_qty_storno 626765 626765 0 0.00 0 32032 72 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 8328 6 0 0
sp_make_cost_storno 27899 27899 0 0.00 0 45134 61 0 0
sp_multiply_rows_for_pdistr 27899 27899 0 0.00 0 9154 33 0 0
sp_multiply_rows_for_qdistr 20482 20482 0 0.00 0 12721 186 0 0
sp_pay_from_customer 7003 7003 0 0.00 4 13537 119 0 0
sp_pay_to_supplier 5854 5854 0 0.00 5 19624 123 0 0
sp_payment_common 12857 12857 0 0.00 1 11939 24 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 10190 299 0 0
sp_qd_handle_on_invoice_upd_sts 10988 10988 0 0.00 0 13546 318 0 0
sp_qd_handle_on_reserve_upd_sts 10085 10085 0 0.00 0 12099 52 0 0
sp_reserve_write_off 10085 10085 0 0.00 6 24650 197 0 0
sp_supplier_invoice 5523 5523 0 0.00 6 102278 3657 0 0
sp_supplier_order 5438 5438 0 0.00 6 53475 1420 0 0
srv_aggregate_perf_data 104 104 0 0.00 2575 212058 19161 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1263 123352 44889 0 0
srv_find_qd_qs_mism 1089299 1089299 0 0.00 0 29582 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 61 82165 3391 0 0
srv_make_money_saldo 758 758 0 0.00 1 13116 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 122600 528149 341272 0 0
t$perf-abend:sp_add_invoice_to_stock 197 197 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 4974 4974 0 0.00 0 12244 213 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3839 67 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 6633 21 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 8901 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2919 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 740 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 10717 58 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 22070 1752 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 7430 14 0 0
t$perf-norm:sp_client_order 20482 20482 0 0.00 0 12625 20 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 10078 16 0 0
t$perf-norm:sp_pay_from_customer 7003 7003 0 0.00 0 15260 17 0 0
t$perf-norm:sp_pay_to_supplier 5854 5854 0 0.00 0 4579 11 0 0
t$perf-norm:sp_reserve_write_off 10085 10085 0 0.00 0 6468 11 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 13606 21 0 0
t$perf-norm:sp_supplier_order 5438 5438 0 0.00 0 8990 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 3196 49 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 271 10 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 6559 28 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1461 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 10308 9914 394 3.82 0 10574 24 0 394
v_all_customers 37232 37232 0 0.00 0 16 1 0 0
v_all_suppliers 30372 30372 0 0.00 0 23 1 0 0
v_all_wares 40964 40964 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 7211 21 0 0
v_cancel_client_order 3476 3476 0 0.00 0 9701 8 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 682 5 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1630 7 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2163 10 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 4482 26 0 24
v_cancel_supplier_order 858 858 0 0.00 0 4361 13 0 0
v_cancel_write_off 2052 2052 0 0.00 0 3417 16 0 0
v_max_id_clo_ord 5438 5438 0 0.00 0 1365 2 0 0
v_max_id_clo_res 971 971 0 0.00 0 4123 25 0 0
v_max_id_ord_sup 5500 5500 0 0.00 0 11163 8 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 97 2 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 2245 4 0 0
v_min_id_clo_ord 5438 5438 0 0.00 0 3808 7 0 0
v_min_id_clo_res 971 971 0 0.00 0 4068 9 0 0
v_min_id_ord_sup 5500 5500 0 0.00 0 8159 15 0 0
v_min_non_paid_invoice 5854 5854 0 0.00 0 5747 15 0 0
v_min_non_paid_realizn 7003 7003 0 0.00 0 11932 18 0 0
v_our_firm 10340 10340 0 0.00 0 17 1 0 0
v_reserve_write_off 20170 20170 0 0.00 0 10305 8 0 0
x_lock_depdocs_on_canc_invoice 12252 12252 0 0.00 0 12414 99 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 6996 319 0 0
x_make_qstorno_1000_1200 5438 5438 0 0.00 2 53441 1341 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 3 97749 3482 0 0
x_make_qstorno_2100_3300 463360 463360 0 0.00 0 48956 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2021 10:38:53 to 01-11-2021 10:38:57.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 30547 8192000000 9705084480 9997012992 776442880 8.657 100 81 162 0 804909248 7962640 20503728
08:09:54 27120 8192000000 9763011168 10070872064 840385872 9.304 100 90 181 0 874483056 12152240 21944944
08:11:37 17430 8192000000 9824841248 10147135488 888044016 9.780 100 77 154 2 930572352 25488800 17039536
08:13:00 7066 8192000000 9876184400 10212143104 931994944 10.214 100 73 145 1 982765664 34643760 16126960
08:14:10 8855 8192000000 9944643456 10293182464 983115744 10.715 100 78 156 3 1050169856 51292432 15761680
08:15:22 3911 8192000000 9981466416 10339180544 1006067520 10.937 100 74 147 0 1088439088 68420688 13950880
08:16:27 7601 8192000000 9999957792 10368286720 1031040720 11.178 100 81 160 0 1121348016 74421248 15886048
08:17:37 1983 8192000000 10040870512 10409750528 1049612112 11.357 100 93 181 1 1158446640 91131312 17703216
08:18:41 1643 8192000000 10053491328 10426142720 1067364128 11.527 100 89 177 1 1159939424 75281760 17293536
08:19:44 5512 8192000000 10011960240 10409664512 1068877904 11.541 100 35 70 1 1128025360 52274000 6873456
08:20:51 5811 8192000000 9987126832 10385731584 1077769456 11.626 100 39 80 1 1104228992 18400576 8058960
08:21:57 71461 8192000000 10010748720 10414616576 1083888736 11.685 100 35 70 1 1128822752 37055840 7878176
08:24:09 7737 8192000000 10011884080 10406834176 1091828960 11.760 100 35 66 4 1122849792 23835520 7185312
08:25:20 5254 8192000000 10004077728 10411241472 1100802064 11.845 100 56 110 0 1135918800 23176928 11939808
08:26:26 58144 8192000000 10001421664 10404671488 1065453600 11.509 97 31 62 1 1088260448 15883728 6923120
08:28:28 4910 8192000000 9977726896 10369650688 1061019968 11.466 99 48 97 1 1084526784 11667920 11838896
08:29:33 6155 8192000000 9931106368 10307215360 995725216 10.837 95 24 48 0 1027168752 26180384 5263152
08:30:41 5099 8192000000 9937463120 10322501632 1023807424 11.109 100 34 67 1 1060851904 30297328 6747152
08:31:48 51918 8192000000 9935488064 10329038848 980139072 10.686 95 27 55 1 1018219744 32415248 5665424
08:33:40 6430 8192000000 9960234624 10345181184 1036882688 11.235 100 46 90 2 1066717168 20004960 9829520
08:34:49 8762 8192000000 9969253664 10365112320 1060592752 11.462 100 37 72 1 1084087056 15576624 7917680
08:35:58 17644 8192000000 9978748064 10377150464 1068653696 11.539 100 36 70 2 1103552224 26963216 7935312
08:37:17 56623 8192000000 10011375520 10412482560 1084161728 11.687 100 40 79 1 1115791456 23670592 7959136
08:39:18 30872 8192000000 10084555552 10494459904 1111374176 11.945 100 42 84 1 1189151712 69232384 8545152
08:40:54 35278 8192000000 10122675696 10530648064 1130235536 12.124 100 41 81 5 1229813936 90758432 8819968
08:42:33 2128 8192000000 10138375040 10550054912 1136270544 12.180 100 66 132 0 1248438384 96628928 15538912
08:43:40 14730 8192000000 10161194720 10574843904 1150342624 12.313 100 46 91 1 1266751136 106465520 9942992
08:45:03 8760 8192000000 10208656976 10617475072 1164769328 12.448 100 61 122 1 1311817184 133968576 13079280
08:46:17 7270 8192000000 10254937888 10666921984 1174081744 12.535 100 64 128 1 1356522336 168474928 13965664
08:47:28 5615 8192000000 10264077984 10679209984 1183980880 12.627 100 61 122 1 1367955856 170554016 13420960
08:48:37 6635 8192000000 10295148512 10707005440 1180781072 12.597 100 63 125 0 1400620176 206106384 13732720
08:49:50 7060 8192000000 10347795040 10758406144 1182204848 12.611 100 66 132 1 1454414144 255866224 16343072
08:51:03 5378 8192000000 10379700320 10785484800 1186329184 12.649 100 83 165 1 1478535888 272717264 19489440
08:52:15 2226 8192000000 10422544880 10824962048 1193038624 12.712 100 95 187 3 1522716128 308606704 21070800
08:53:21 7780 8192000000 10417946384 10875437056 1193050816 12.712 100 43 86 0 1544486608 340077728 11358064
08:54:42 1262 8192000000 10434333328 10832674816 1160344544 12.406 99 90 178 0 1524643968 341976368 22323056
08:55:46 5921 8192000000 10430804912 10863927296 1162376144 12.426 99 36 72 2 1522763568 351013056 9374368
08:56:57 9386 8192000000 10418102240 10849251328 1139872992 12.214 98 44 88 0 1499390160 348732480 10784688
08:58:16 12135 8192000000 10403300352 10882412544 1146763920 12.279 99 40 78 2 1505553104 348613040 10176144
08:59:33 20283 8192000000 10374060000 10839007232 1128413712 12.106 99 25 50 0 1486840672 351752192 6674768
09:01:27 34044 8192000000 10166702752 10735263744 1126114928 12.085 99 17 34 2 1417061024 286646976 4299120
09:03:02 63285 8192000000 10071835936 10541289472 1091748032 11.759 97 17 34 0 1194611936 98726672 4137232
09:05:07 82956 8192000000 10050218864 10513276928 1084190672 11.687 98 4 8 0 1132638144 48017680 429792
09:07:34 39588 8192000000 10091995824 10515222528 1109424256 11.927 98 28 56 1 1193041376 77524256 6092864
09:09:32 55797 8192000000 10048396304 10486808576 1099327616 11.831 97 21 42 1 1161563712 58047312 4188784
09:11:35 55333 8192000000 10088527184 10534658048 1103341440 11.869 97 16 32 1 1170835744 64121712 3372592
09:13:32 80716 8192000000 10111934736 10571132928 1129509104 12.117 99 10 20 0 1202366016 70765600 2091312
09:15:55 104070 8192000000 10110817024 10551300096 1135247264 12.171 99 49 98 1 1192642240 46698160 10696816
09:18:43 23683 8192000000 10092736864 10539413504 1155773504 12.364 100 16 32 0 1192452416 33376624 3302288
09:20:09 21836 8192000000 10150972352 10591105024 1162324336 12.425 100 27 53 0 1242912016 74709680 5878000
09:21:34 21959 8192000000 10159070000 10613485568 1169168912 12.489 100 47 93 0 1262092560 82633712 10289936
09:23:06 35302 8192000000 10192376144 10677702656 1163618928 12.437 99 34 69 3 1289667824 117944800 8104096
09:24:47 28579 8192000000 10096094784 10558586880 1153550976 12.343 99 11 22 0 1220581600 64708640 2321984
09:26:20 33097 8192000000 10110835232 10577227776 1166037520 12.460 100 12 24 2 1213012512 44275312 2699680
09:28:01 58322 8192000000 10133510672 10602389504 1148110016 12.292 99 13 26 0 1228283648 77383472 2790160
09:30:02 40116 8192000000 10148084224 10620133376 1163070960 12.432 100 34 66 1 1233482576 63147280 7264336
09:32:04 56405 8192000000 10072691856 10539565056 1124804896 12.072 97 12 24 3 1167323296 40219472 2298928
09:34:03 35152 8192000000 10082364592 10517868544 1118218480 12.010 97 26 52 2 1162694288 39371392 5104416
09:35:39 31301 8192000000 10108260096 10546728960 1141964816 12.234 100 32 64 1 1206664528 58241632 6458080
09:37:16 78980 8192000000 10078058624 10548719616 1082108704 11.668 95 25 50 1 1148740656 61864240 4767712
09:39:36 69636 8192000000 10085844960 10539298816 1089745952 11.740 96 16 32 0 1146518496 53676176 3096368
09:42:23 73944 8192000000 10068398800 10546348032 1092587984 11.767 98 22 43 0 1154721680 58071504 4062192
09:44:37 89132 8192000000 10060017408 10530975744 1068638288 11.539 95 12 24 0 1135529872 64060768 2830816
09:47:13 88477 8192000000 10071453312 10549682176 1113458048 11.965 99 35 70 0 1175169136 54243376 7467712
09:49:48 66769 8192000000 10119304272 10564169728 1123991664 12.065 99 25 50 0 1198194416 68845968 5356784
09:52:09 57566 8192000000 10061769664 10506723328 1115112176 11.981 99 8 16 0 1169807456 53057184 1638096
09:54:07 101079 8192000000 10099132800 10582908928 1079650336 11.644 96 9 18 1 1127466064 45904448 1911280
09:57:27 34052 8192000000 10086869456 10525954048 1121699936 12.043 99 27 52 0 1186635504 59437440 5498128
09:59:02 90899 8192000000 10157001632 10601549824 1121571344 12.042 98 19 38 1 1235558752 109595104 4392304
10:01:42 56969 8192000000 10165871040 10645639168 1116961264 11.998 98 24 48 0 1281202256 158701040 5539952
10:03:52 52245 8192000000 10066534560 10509975552 1119513216 12.022 99 16 30 0 1219128688 96221856 3393616
10:05:47 87004 8192000000 10117991296 10583330816 1108091872 11.914 97 17 34 0 1151477744 40222224 3163648
10:08:14 123351 8192000000 10083837680 10528583680 1151098112 12.320 99 27 54 1 1190437872 33820992 5518768
10:11:19 69661 8192000000 10130251056 10595520512 1143976848 12.253 98 18 36 1 1214616144 66858656 3780640
10:13:29 55988 8192000000 10153988672 10645241856 1158449504 12.389 99 13 26 0 1231501952 70471520 2580928
10:15:28 47201 8192000000 10086272000 10553524224 1138393248 12.200 98 15 29 0 1203542688 62643136 2506304
10:17:16 52505 8192000000 10120438256 10591588352 1146995200 12.281 99 22 44 0 1242903472 91166960 4741312
10:19:11 65923 8192000000 10083942800 10539270144 1104232976 11.878 95 18 36 2 1149034096 41084368 3716752
10:21:23 62734 8192000000 10095536672 10545238016 1144026544 12.253 99 13 26 0 1186488608 39396224 3065840
10:23:26 36891 8192000000 10104864928 10534367232 1129128640 12.113 98 32 62 0 1172530928 37364672 6037616
10:25:04 17474 8192000000 10101081680 10532855808 1123878752 12.064 99 43 86 0 1178767696 46419744 8469200
10:26:22 36386 8192000000 10127278640 10586341376 1120678976 12.033 99 23 46 0 1232417360 106765968 4972416
10:27:58 76431 8192000000 10081719232 10533072896 1097479760 11.814 97 26 52 1 1176870256 74162304 5228192
10:30:19 15181 8192000000 10033990192 10476978176 1105649328 11.891 98 38 75 6 1135943392 20754544 9539520
10:31:37 51102 8192000000 10038584784 10508722176 1102571168 11.862 98 11 22 0 1146641776 41369200 2701408
10:33:31 57813 8192000000 10069100528 10551709696 1092837328 11.770 97 19 38 0 1118989264 22067776 4084160
10:35:59 61447 8192000000 10124293632 10596958208 1126519408 12.089 99 22 44 0 1212694432 81145792 5029232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 01-11-2021 10:38:59 to 01-11-2021 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 197 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 01-11-2021 10:39:14 to 01-11-2021 10:39:16.

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          230513
MON$OLDEST_ACTIVE               234249
MON$OLDEST_SNAPSHOT             234249
MON$NEXT_TRANSACTION            234249
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                       2072224
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Mon Nov  1 10:37:12 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Nov  1 10:37:12 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Mon Nov  1 10:37:15 2021
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
--- end of diff output ---

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

01.11.21 10:39:17. 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-01 10:39:20 No severe PSQL-related problems occured
01.11.21 10:39:21. End of report.