Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2021 04: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 15778816 kB
MemAvailable 30583336 kB
Buffers 20 kB
Cached 15848384 kB
SwapCached 3700 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-10-21 04:37:08
DTS_END                         2021-10-21 04:37:08.1660
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(68:17)
IP                              ::1/34004
TRN_ID                          235240
ATT_ID                          616
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: 9191.86 21542 1103023
customer order: creation 173.88 471 20865
customer order: refuse 14.38 342 1725
order to supplier: creation 45.91 1422 5509
order to supplier: removal 3.23 125494 388
invoice (draft): creation 46.18 3720 5541
invoice (draft): removal 51.03 982 6123
invoice accept: apply 42.25 18641 5070
invoice accept: cancel 46.61 900 5593
customer reserve: creation 3923.44 182 470813
customer reserve: removal 4552.52 70 546302
realization accept: apply 83.79 202 10055
realization accept: cancel 80.03 129 9603
payment from customer: creation 59.59 135 7151
payment from customer: removal 5.65 77 678
payment to supplier: creation 48.97 144 5876
payment to supplier: removal 3.31 85 397
service: total inventory turnovers 3.73 3447 448
service: total monetary turnovers 6.48 528 777
service: aggregate perf. data 0.88 15752 105
service: refresh index statistics 0.03 258116 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-10-2021 04:38:20 to 21-10-2021 04:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5471
TEST_TIME 2 5085
TEST_TIME 3 4979
TEST_TIME 4 4861
TEST_TIME 5 4860
TEST_TIME 6 4867
TEST_TIME 7 5176
TEST_TIME 8 5136
TEST_TIME 9 5338
TEST_TIME 10 5888
TEST_TIME 11 6077
TEST_TIME 12 6373
TEST_TIME 13 6761
TEST_TIME 14 6828
TEST_TIME 15 7023
TEST_TIME 16 7496
TEST_TIME 17 7707
TEST_TIME 18 7748
TEST_TIME 19 8142
TEST_TIME 20 8280
TEST_TIME 21 8821
TEST_TIME 22 9035
TEST_TIME 23 9890
TEST_TIME 24 10622
TEST_TIME 25 11471
TEST_TIME 26 11795
TEST_TIME 27 11605
TEST_TIME 28 11296
TEST_TIME 29 11043
TEST_TIME 30 10954
TEST_TIME 31 10805
TEST_TIME 32 10781
TEST_TIME 33 10626
TEST_TIME 34 10576
TEST_TIME 35 10485
TEST_TIME 36 10369
TEST_TIME 37 10245
TEST_TIME 38 10116
TEST_TIME 39 10132
TEST_TIME 40 10009
TEST_TIME 41 10167
TEST_TIME 42 10065
TEST_TIME 43 10072
TEST_TIME 44 10059
TEST_TIME 45 10025
TEST_TIME 46 9951
TEST_TIME 47 9992
TEST_TIME 48 10142
TEST_TIME 49 10118
TEST_TIME 50 10166
TEST_TIME 51 10302
TEST_TIME 52 10242
TEST_TIME 53 10156
TEST_TIME 54 10028
TEST_TIME 55 9944
TEST_TIME 56 9906
TEST_TIME 57 9788
TEST_TIME 58 9754
TEST_TIME 59 9740
TEST_TIME 60 9771
TEST_TIME 61 9758
TEST_TIME 62 9708
TEST_TIME 63 9679
TEST_TIME 64 9659
TEST_TIME 65 9785
TEST_TIME 66 9858
TEST_TIME 67 9863
TEST_TIME 68 9855
TEST_TIME 69 9892
TEST_TIME 70 9934
TEST_TIME 71 9901
TEST_TIME 72 9802
TEST_TIME 73 9765
TEST_TIME 74 9688
TEST_TIME 75 9705
TEST_TIME 76 9659
TEST_TIME 77 9611
TEST_TIME 78 9573
TEST_TIME 79 9523
TEST_TIME 80 9475
TEST_TIME 81 9512
TEST_TIME 82 9532
TEST_TIME 83 9537
TEST_TIME 84 9504
TEST_TIME 85 9513
TEST_TIME 86 9545
TEST_TIME 87 9518
TEST_TIME 88 9553
TEST_TIME 89 9637
TEST_TIME 90 9593
TEST_TIME 91 9675
TEST_TIME 92 9573
TEST_TIME 93 9554
TEST_TIME 94 9490
TEST_TIME 95 9467
TEST_TIME 96 9437
TEST_TIME 97 9421
TEST_TIME 98 9419
TEST_TIME 99 9410
TEST_TIME 100 9392
TEST_TIME 101 9391
TEST_TIME 102 9440
TEST_TIME 103 9425
TEST_TIME 104 9392
TEST_TIME 105 9380
TEST_TIME 106 9470
TEST_TIME 107 9516
TEST_TIME 108 9504
TEST_TIME 109 9446
TEST_TIME 110 9416
TEST_TIME 111 9384
TEST_TIME 112 9385
TEST_TIME 113 9354
TEST_TIME 114 9301
TEST_TIME 115 9258
TEST_TIME 116 9284
TEST_TIME 117 9289
TEST_TIME 118 9290
TEST_TIME 119 9268
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2021 04:38:24 to 21-10-2021 04:38:28.

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 1538001 1538001 0 0.00 0 17655 7 0 0
doc_list_biud 1538001 1538001 0 0.00 0 34626 31 0 0
sp_add_invoice_to_stock 5179 5070 109 2.10 35 213936 18642 0 109
sp_cancel_adding_invoice 5593 5593 0 0.00 5 80527 901 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 9792 343 0 0
sp_cancel_customer_reserve 546302 546302 0 0.00 1 34627 71 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 3859 78 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 3203 85 0 0
sp_cancel_supplier_invoice 6134 6123 11 0.17 2 16860 983 0 11
sp_cancel_supplier_order 388 388 0 0.00 7 1114903 125495 0 0
sp_cancel_write_off 9603 9603 0 0.00 1 9449 129 0 0
sp_client_order 20865 20865 0 0.00 4 33811 471 0 0
sp_customer_reserve 470813 470813 0 0.00 0 83685 182 0 0
sp_fill_shopping_cart 31909 31909 0 0.00 1 18742 45 0 0
sp_fill_shopping_cart_clo_res 470808 470808 0 0.00 0 17295 16 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 28951 387 0 0
sp_kill_cost_storno 16271 16271 0 0.00 0 8837 61 0 0
sp_kill_qstorno_ret_qs2qd 1108718 1108718 0 0.00 0 34621 38 0 0
sp_kill_qty_storno 584851 584851 0 0.00 0 34626 82 0 0
sp_lock_selected_doc 20880 20880 0 0.00 0 8894 7 0 0
sp_make_cost_storno 28144 28144 0 0.00 0 13273 62 0 0
sp_multiply_rows_for_pdistr 28144 28144 0 0.00 0 7488 37 0 0
sp_multiply_rows_for_qdistr 20865 20865 0 0.00 0 19204 248 0 0
sp_pay_from_customer 7151 7151 0 0.00 4 15911 135 0 0
sp_pay_to_supplier 5876 5876 0 0.00 5 16091 144 0 0
sp_payment_common 13027 13027 0 0.00 1 7331 25 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 9783 310 0 0
sp_qd_handle_on_invoice_upd_sts 10655 10655 0 0.00 0 23275 360 0 0
sp_qd_handle_on_reserve_upd_sts 10055 10055 0 0.00 0 29897 59 0 0
sp_reserve_write_off 10055 10055 0 0.00 7 29946 203 0 0
sp_supplier_invoice 5541 5541 0 0.00 7 92190 3721 0 0
sp_supplier_order 5509 5509 0 0.00 5 72913 1423 0 0
srv_aggregate_perf_data 105 105 0 0.00 3162 115769 15752 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1259 108219 42991 0 0
srv_find_qd_qs_mism 1054379 1054379 0 0.00 0 17655 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 69 103011 3447 0 0
srv_make_money_saldo 777 777 0 0.00 1 14640 529 0 0
srv_recalc_idx_stat 4 4 0 0.00 199841 329056 258117 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5070 5070 0 0.00 0 12113 168 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 1997 72 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 2273 12 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1534 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 10492 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 3704 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1855 16 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 37681 1529 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 3668 8 0 0
t$perf-norm:sp_client_order 20865 20865 0 0.00 0 6752 18 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 548 3 0 0
t$perf-norm:sp_pay_from_customer 7151 7151 0 0.00 0 7294 7 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 10430 14 0 0
t$perf-norm:sp_reserve_write_off 10055 10055 0 0.00 0 8746 10 0 0
t$perf-norm:sp_supplier_invoice 5541 5541 0 0.00 0 9198 23 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 4387 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 273 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1600 30 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 884 8 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 2958 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10342 10124 218 2.10 0 6780 18 0 218
v_all_customers 37912 37912 0 0.00 0 93 1 0 0
v_all_suppliers 30506 30506 0 0.00 0 86 1 0 0
v_all_wares 41730 41730 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1677 14 0 0
v_cancel_client_order 3450 3450 0 0.00 0 3228 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2131 7 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 1085 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 584 5 0 0
v_cancel_supplier_invoice 874 852 22 2.51 0 3527 24 0 22
v_cancel_supplier_order 776 776 0 0.00 0 310 4 0 0
v_cancel_write_off 2036 2036 0 0.00 0 799 13 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 861 2 0 0
v_max_id_clo_res 963 963 0 0.00 0 2429 13 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 7465 6 0 0
v_max_non_paid_invoice 1667 1667 0 0.00 0 3431 5 0 0
v_max_non_paid_realizn 3806 3806 0 0.00 0 2941 3 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 2710 6 0 0
v_min_id_clo_res 963 963 0 0.00 0 1995 5 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 6036 11 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 5901 18 0 0
v_min_non_paid_realizn 7151 7151 0 0.00 0 7328 19 0 0
v_our_firm 10508 10508 0 0.00 0 282 1 0 0
v_reserve_write_off 20110 20110 0 0.00 0 3223 6 0 0
x_lock_depdocs_on_canc_invoice 11290 11290 0 0.00 0 22373 124 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 7650 358 0 0
x_make_qstorno_1000_1200 5509 5509 0 0.00 2 72903 1344 0 0
x_make_qstorno_1200_2000 5541 5541 0 0.00 3 91955 3544 0 0
x_make_qstorno_2100_3300 470813 470813 0 0.00 0 83684 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2021 04:38:29 to 21-10-2021 04:38:33.

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
02:07:12 8 8192000000 8799343792 8810975232 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:13 30072 8192000000 9724136096 10017517568 797232256 8.868 100 90 180 0 826796640 7776224 21788160
02:09:49 13228 8192000000 9754360016 10054627328 821256864 9.111 100 88 176 1 855749152 12760288 21732000
02:11:05 23667 8192000000 9791778304 10101383168 850351056 9.404 100 87 174 0 890759168 20076544 20331568
02:12:32 12061 8192000000 9853481648 10180632576 903678288 9.935 100 81 162 0 962436848 40793632 17964928
02:13:46 10362 8192000000 9903293200 10244743168 945576224 10.348 100 79 157 0 1019378752 56939040 16863488
02:14:59 6413 8192000000 9936883408 10290667520 983691744 10.720 100 84 166 2 1056673136 56439056 16542336
02:16:08 4413 8192000000 9991475792 10351448064 1013154512 11.006 100 88 176 0 1111255520 80530896 17570112
02:17:15 2551 8192000000 10022212144 10385391616 1042390496 11.288 100 89 178 0 1130020048 68914736 18714816
02:18:18 17383 8192000000 10015988480 10391187456 1061631744 11.472 100 70 139 0 1137018400 60994192 14392464
02:19:38 6583 8192000000 9974282176 10367504384 1064681792 11.501 100 33 66 0 1092898592 21593584 6623216
02:20:48 11845 8192000000 9990207936 10391281664 1071928720 11.570 100 31 65 1 1100776672 22512432 6335520
02:22:01 32417 8192000000 9994617200 10400915456 1075583472 11.605 100 35 70 0 1096823936 14212512 7027952
02:23:34 6793 8192000000 9999386176 10404851712 1078348144 11.632 100 46 91 0 1116626480 28145648 10132688
02:24:41 6248 8192000000 9987179408 10389712896 1083232848 11.678 100 31 62 2 1111042960 20795776 7014336
02:25:49 4159 8192000000 10000465280 10398109696 1069338128 11.546 98 42 84 0 1096777872 17542192 9897552
02:26:53 34828 8192000000 9990950640 10405347328 1080398128 11.651 99 36 68 2 1114498720 27030208 7070384
02:28:30 4160 8192000000 9999832544 10390691840 1049880224 11.360 98 47 94 3 1090614416 29825904 10908288
02:29:35 3060 8192000000 9936881776 10313248768 1015267680 11.026 97 28 55 0 1034042384 10849632 7925072
02:30:43 7576 8192000000 9951066336 10336645120 998379456 10.863 97 36 70 1 1023932496 17848528 7704512
02:31:54 26435 8192000000 9926434080 10310873088 944269952 10.335 92 25 50 0 967820080 18418800 5131328
02:33:22 6876 8192000000 9973190384 10365022208 1046778624 11.330 100 36 71 1 1076530224 22333232 7418368
02:34:31 6561 8192000000 9968602368 10351362048 1043419568 11.298 99 43 84 1 1069741312 17290416 9031328
02:35:39 78592 8192000000 9990432016 10394599424 1071624944 11.568 100 26 50 2 1099734048 22803824 5305280
02:38:02 30745 8192000000 10045273392 10443726848 1099699232 11.835 100 49 98 2 1146299920 36474272 10126416
02:39:42 11318 8192000000 10066919216 10469052416 1125165984 12.076 100 47 91 2 1175750064 41344576 9239504
02:40:57 20960 8192000000 10109036112 10519683072 1143198464 12.246 100 35 70 0 1229707792 78151632 8357696
02:42:21 28383 8192000000 10185023008 10600992768 1150702080 12.316 100 45 90 2 1280174432 119472112 10000240
02:43:55 13946 8192000000 10213293600 10629959680 1164874032 12.449 100 45 90 0 1321626512 146505328 10247152
02:45:14 6132 8192000000 10263986256 10672025600 1177982624 12.571 100 61 121 1 1363364224 171956400 13425200
02:46:28 7967 8192000000 10319202944 10742874112 1189109920 12.675 100 51 101 0 1425611152 224941600 11559632
02:47:42 4736 8192000000 10350431664 10761920512 1185675680 12.643 99 73 142 3 1454000592 252059936 16264976
02:48:51 5552 8192000000 10365535952 10780688384 1196763664 12.746 100 65 130 1 1469683184 257644384 15275136
02:50:04 1611 8192000000 10419171728 10824925184 1199219184 12.769 100 89 178 0 1519566176 300212784 20134208
02:51:10 1914 8192000000 10425993712 10836426752 1167540048 12.474 99 81 158 1 1519546304 333814128 18192128
02:52:16 3425 8192000000 10480443968 10881728512 1180259104 12.593 100 91 181 1 1570621312 368861744 21500464
02:53:26 2486 8192000000 10469092928 10898980864 1161425248 12.417 99 89 178 0 1567048432 386099360 19523824
02:54:34 1256 8192000000 10504521584 10924257280 1181225360 12.602 99 78 155 1 1600134512 400683472 18225680
02:55:41 2400 8192000000 10538769440 10940841984 1179949632 12.590 100 81 163 1 1631571200 431895328 19726240
02:56:47 5061 8192000000 10544448832 10967072768 1180084960 12.591 100 45 90 0 1642680784 450962304 11633520
02:57:56 3190 8192000000 10537063920 10987872256 1177753184 12.569 100 61 120 2 1667289344 474688960 14847200
02:59:01 14272 8192000000 10524616176 10964840448 1148363456 12.294 100 36 72 1 1614409776 455428880 10617440
03:00:19 13672 8192000000 10440145136 10953113600 1144753680 12.260 100 30 60 1 1577679600 424220960 8704960
03:01:52 40430 8192000000 10198749120 10792456192 1118744208 12.015 100 16 32 0 1380392256 257632304 4015744
03:03:40 20051 8192000000 10052813792 10477092864 1115578064 11.985 99 37 73 2 1136248560 11723616 8946880
03:05:05 54110 8192000000 10059032176 10497388544 1105131696 11.886 99 27 54 0 1134302176 23838272 5332208
03:07:00 56709 8192000000 10085772352 10523353088 1134782576 12.166 100 30 60 0 1192911120 52297104 5831440
03:09:03 2788 8192000000 10085613072 10511917056 1141049104 12.225 100 50 98 1 1188190816 37596688 9545024
03:10:11 5658 8192000000 10113063632 10542301184 1153528768 12.343 100 54 104 1 1209377696 44283664 11565264
03:11:17 30034 8192000000 10120778016 10587021312 1154412624 12.351 100 16 32 0 1236428208 78632384 3383200
03:12:59 62927 8192000000 10096772912 10538766336 1132511584 12.145 98 24 48 0 1177490096 39697840 5280672
03:16:07 96989 8192000000 10161562352 10607742976 1150488944 12.314 99 25 50 2 1241021040 84755520 5776576
03:18:54 43458 8192000000 10115327184 10583916544 1146874304 12.280 99 9 18 0 1213231744 64555088 1802352
03:20:38 34623 8192000000 10105657200 10543235072 1144281696 12.256 99 35 68 0 1185940928 34331664 7327568
03:22:21 66105 8192000000 10150361264 10614845440 1131637888 12.137 97 13 26 1 1181153424 46796752 2718784
03:24:28 68215 8192000000 10113457312 10599149568 1117763968 12.006 98 7 14 0 1217534608 98099952 1670688
03:26:42 108219 8192000000 10112237520 10605301760 1067128432 11.525 93 19 38 0 1152845152 81939248 3777472
03:29:57 33208 8192000000 10099440240 10518851584 1133299392 12.152 100 30 58 1 1197837552 58461008 6077152
03:31:31 74422 8192000000 10073088032 10521878528 1111362368 11.945 98 48 96 0 1173262160 52065520 9834272
03:33:58 49812 8192000000 10086826288 10540773376 1131566960 12.136 100 9 18 0 1196044848 62433280 2044608
03:35:48 107473 8192000000 10122642272 10626183168 1130361824 12.125 99 13 26 1 1205431344 72214512 2855008
03:38:56 43102 8192000000 10127997904 10589966336 1145072304 12.263 99 18 36 0 1209773648 60595072 4106272
03:40:41 33880 8192000000 10172263744 10612895744 1143387552 12.247 99 46 91 0 1242792592 89154880 10250160
03:42:16 79789 8192000000 10048873984 10503647232 1058695264 11.444 93 13 26 2 1172866992 111012480 3159248
03:44:38 32595 8192000000 10071079600 10511560704 1106586800 11.900 98 34 68 0 1156051632 42411248 7053584
03:46:12 73547 8192000000 10086320208 10598850560 1111989600 11.951 99 27 54 0 1223450416 105619024 5841792
03:48:26 31612 8192000000 10088283984 10522791936 1117322768 12.002 98 17 34 0 1148395888 27773360 3299760
03:49:58 86806 8192000000 10044709424 10474012672 1107359120 11.907 98 28 56 0 1157933136 44633920 5940096
03:52:40 33109 8192000000 10043759168 10488233984 1108888272 11.922 98 27 51 2 1143109536 28995776 5225488
03:54:21 43744 8192000000 10068915728 10528882688 1130227952 12.124 100 9 19 0 1176371744 44242544 1901248
03:56:21 78265 8192000000 10095779616 10552115200 1103640768 11.872 97 11 22 2 1158189328 52119840 2428720
03:58:40 72700 8192000000 10085579184 10544599040 1100549104 11.843 98 30 61 0 1166519744 59134448 6836192
04:00:57 37056 8192000000 10075241424 10520948736 1121016720 12.037 99 22 44 0 1175411840 49942752 4452368
04:02:37 60933 8192000000 10065135632 10541993984 1116853648 11.997 99 28 56 1 1189508240 66744416 5910176
04:04:38 37081 8192000000 10155676048 10603954176 1140852448 12.224 100 51 102 0 1229182000 77682912 10646640
04:06:25 59458 8192000000 10102848704 10533384192 1124017392 12.065 98 26 52 0 1184445104 55349568 5078144
04:08:35 97486 8192000000 10102921200 10590343168 1151536160 12.324 100 15 30 0 1207593072 53157280 2899632
04:11:13 72723 8192000000 10083286368 10551926784 1124074096 12.065 98 12 24 0 1188192176 61825664 2292416
04:13:42 107812 8192000000 10104108640 10604548096 1147784656 12.289 100 14 28 0 1210477296 59677584 3015056
04:16:30 42551 8192000000 10124246480 10569068544 1150440976 12.314 100 25 50 1 1215262544 59502528 5319040
04:18:15 71042 8192000000 10110923440 10558390272 1119075792 12.018 98 25 50 1 1201532160 77346080 5110288
04:20:27 30844 8192000000 10068224944 10493267968 1125578256 12.080 100 31 63 1 1159116544 26828048 6710240
04:22:12 70006 8192000000 10149869312 10639937536 1126848352 12.092 99 13 26 1 1247382592 116781920 3752320
04:24:24 68599 8192000000 10055500880 10487042048 1102467600 11.861 97 19 36 1 1141320304 34907920 3944784
04:26:50 79489 8192000000 10082715248 10544926720 1105677504 11.891 97 22 44 0 1169777088 59773344 4326240
04:29:12 37979 8192000000 10071331872 10580070400 1126738112 12.091 100 7 14 0 1171563312 43354608 1470592
04:31:28 73063 8192000000 10100603568 10559643648 1130660112 12.128 100 13 26 0 1191184992 57797312 2727568
04:33:42 61113 8192000000 10065658624 10509656064 1120102800 12.028 99 17 34 0 1164445248 40669104 3673344
04:35:45 43744 8192000000 10088797376 10526756864 1116768208 11.996 99 41 82 0 1151316000 26139488 8408304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2021 04:38:35 to 21-10-2021 04:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2021 04:38:51 to 21-10-2021 04:38:53.

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          232228
MON$OLDEST_ACTIVE               235366
MON$OLDEST_SNAPSHOT             235366
MON$NEXT_TRANSACTION            235366
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                       2035808
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:417: 
:418: mscs-test-161.budzdorov.ru    Thu Oct 21 04:37:10 2021
:419:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:420: 
:421: 
:422: mscs-test-161.budzdorov.ru    Thu Oct 21 04:37:12 2021
:423:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:424: 
:425: 
:426: mscs-test-161.budzdorov.ru    Thu Oct 21 04:37:12 2021
:427:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:428: 
:429: 
:430: mscs-test-161.budzdorov.ru    Thu Oct 21 04:37:14 2021
:431:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:432: 
--- end of diff output ---

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

21.10.21 04:38:54. 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-10-21 04:38:56 No severe PSQL-related problems occured
21.10.21 04:38:57. End of report.