Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2021 10:37
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 15991844 kB
MemAvailable 30647656 kB
Buffers 0 kB
Cached 15694824 kB
SwapCached 3768 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-16 10:37:08
DTS_END                         2021-10-16 10:37:08.3610
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/44590
TRN_ID                          223376
ATT_ID                          614
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: 8642.74 23385 1037129
customer order: creation 158.23 446 18987
customer order: refuse 13.50 362 1620
order to supplier: creation 41.91 1568 5029
order to supplier: removal 3.51 111711 421
invoice (draft): creation 43.08 4232 5170
invoice (draft): removal 49.36 1071 5923
invoice accept: apply 39.42 20588 4731
invoice accept: cancel 44.30 955 5316
customer reserve: creation 3639.88 203 436786
customer reserve: removal 4336.55 70 520386
realization accept: apply 75.54 220 9065
realization accept: cancel 76.11 131 9133
payment from customer: creation 56.50 133 6780
payment from customer: removal 5.18 114 621
payment to supplier: creation 46.57 145 5588
payment to supplier: removal 2.88 79 346
service: total inventory turnovers 3.46 4108 415
service: total monetary turnovers 5.83 546 699
service: aggregate perf. data 0.91 14731 109
service: refresh index statistics 0.03 306285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2021 10:38:19 to 16-10-2021 10:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4512
TEST_TIME 2 4678
TEST_TIME 3 5886
TEST_TIME 4 6129
TEST_TIME 5 5707
TEST_TIME 6 5639
TEST_TIME 7 5128
TEST_TIME 8 5310
TEST_TIME 9 5270
TEST_TIME 10 5582
TEST_TIME 11 6298
TEST_TIME 12 6726
TEST_TIME 13 7080
TEST_TIME 14 7760
TEST_TIME 15 7922
TEST_TIME 16 8049
TEST_TIME 17 8235
TEST_TIME 18 8352
TEST_TIME 19 8348
TEST_TIME 20 8450
TEST_TIME 21 8286
TEST_TIME 22 8378
TEST_TIME 23 8484
TEST_TIME 24 8457
TEST_TIME 25 8937
TEST_TIME 26 9229
TEST_TIME 27 9479
TEST_TIME 28 9923
TEST_TIME 29 10440
TEST_TIME 30 10353
TEST_TIME 31 10104
TEST_TIME 32 9987
TEST_TIME 33 9862
TEST_TIME 34 9799
TEST_TIME 35 9719
TEST_TIME 36 9705
TEST_TIME 37 9743
TEST_TIME 38 9531
TEST_TIME 39 9623
TEST_TIME 40 9426
TEST_TIME 41 9401
TEST_TIME 42 9264
TEST_TIME 43 9261
TEST_TIME 44 9170
TEST_TIME 45 9156
TEST_TIME 46 9097
TEST_TIME 47 8978
TEST_TIME 48 8844
TEST_TIME 49 8890
TEST_TIME 50 8873
TEST_TIME 51 8972
TEST_TIME 52 8978
TEST_TIME 53 8915
TEST_TIME 54 8900
TEST_TIME 55 8838
TEST_TIME 56 8927
TEST_TIME 57 9010
TEST_TIME 58 9045
TEST_TIME 59 9116
TEST_TIME 60 9119
TEST_TIME 61 9151
TEST_TIME 62 9212
TEST_TIME 63 9310
TEST_TIME 64 9190
TEST_TIME 65 9167
TEST_TIME 66 9084
TEST_TIME 67 9069
TEST_TIME 68 8993
TEST_TIME 69 8949
TEST_TIME 70 8887
TEST_TIME 71 8790
TEST_TIME 72 8802
TEST_TIME 73 8784
TEST_TIME 74 8855
TEST_TIME 75 8941
TEST_TIME 76 8916
TEST_TIME 77 8885
TEST_TIME 78 8977
TEST_TIME 79 8943
TEST_TIME 80 8956
TEST_TIME 81 8984
TEST_TIME 82 9010
TEST_TIME 83 9117
TEST_TIME 84 9084
TEST_TIME 85 9060
TEST_TIME 86 9002
TEST_TIME 87 8946
TEST_TIME 88 8877
TEST_TIME 89 8877
TEST_TIME 90 8807
TEST_TIME 91 8741
TEST_TIME 92 8768
TEST_TIME 93 8787
TEST_TIME 94 8761
TEST_TIME 95 8763
TEST_TIME 96 8783
TEST_TIME 97 8791
TEST_TIME 98 8756
TEST_TIME 99 8780
TEST_TIME 100 8873
TEST_TIME 101 8856
TEST_TIME 102 8880
TEST_TIME 103 8843
TEST_TIME 104 8830
TEST_TIME 105 8920
TEST_TIME 106 8918
TEST_TIME 107 8848
TEST_TIME 108 8808
TEST_TIME 109 8796
TEST_TIME 110 8807
TEST_TIME 111 8764
TEST_TIME 112 8736
TEST_TIME 113 8702
TEST_TIME 114 8709
TEST_TIME 115 8683
TEST_TIME 116 8672
TEST_TIME 117 8744
TEST_TIME 118 8712
TEST_TIME 119 8705
TEST_TIME 120 8643

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2021 10:38:22 to 16-10-2021 10:38:26.

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 1441385 1441385 0 0.00 0 24952 7 0 0
doc_list_biud 1441385 1441385 0 0.00 0 50982 33 0 0
sp_add_invoice_to_stock 4875 4731 144 2.95 59 195875 20589 0 144
sp_cancel_adding_invoice 5316 5316 0 0.00 6 69972 955 0 0
sp_cancel_client_order 1620 1620 0 0.00 5 10648 362 0 0
sp_cancel_customer_reserve 520386 520386 0 0.00 1 42867 71 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 3 5801 115 0 0
sp_cancel_pay_to_supplier 346 346 0 0.00 3 3857 80 0 0
sp_cancel_supplier_invoice 5941 5923 18 0.30 3 50991 1072 0 18
sp_cancel_supplier_order 421 421 0 0.00 7 1082821 111712 0 0
sp_cancel_write_off 9133 9133 0 0.00 1 18370 132 0 0
sp_client_order 18987 18987 0 0.00 4 21196 446 0 0
sp_customer_reserve 436786 436786 0 0.00 0 40246 203 0 0
sp_fill_shopping_cart 29174 29174 0 0.00 1 35847 54 0 0
sp_fill_shopping_cart_clo_res 436781 436781 0 0.00 0 22470 17 0 0
sp_get_clo_for_invoice 4725 4725 0 0.00 0 36206 444 0 0
sp_kill_cost_storno 15416 15416 0 0.00 0 10099 67 0 0
sp_kill_qstorno_ret_qs2qd 1056249 1056249 0 0.00 0 50981 39 0 0
sp_kill_qty_storno 556585 556585 0 0.00 0 50982 84 0 0
sp_lock_selected_doc 19056 19056 0 0.00 0 6435 4 0 0
sp_make_cost_storno 26154 26154 0 0.00 0 21723 59 0 0
sp_multiply_rows_for_pdistr 26154 26154 0 0.00 0 11364 37 0 0
sp_multiply_rows_for_qdistr 18987 18987 0 0.00 0 21181 208 0 0
sp_pay_from_customer 6780 6780 0 0.00 4 20573 134 0 0
sp_pay_to_supplier 5588 5588 0 0.00 5 20569 145 0 0
sp_payment_common 12368 12368 0 0.00 1 11365 24 0 0
sp_qd_handle_on_cancel_clo 1620 1620 0 0.00 0 10638 334 0 0
sp_qd_handle_on_invoice_upd_sts 10037 10037 0 0.00 0 26981 398 0 0
sp_qd_handle_on_reserve_upd_sts 9065 9065 0 0.00 0 17407 76 0 0
sp_reserve_write_off 9065 9065 0 0.00 7 21809 221 0 0
sp_supplier_invoice 5170 5170 0 0.00 7 123297 4233 0 0
sp_supplier_order 5029 5029 0 0.00 6 28875 1568 0 0
srv_aggregate_perf_data 109 109 0 0.00 2714 105025 14732 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2363 117201 41809 0 0
srv_find_qd_qs_mism 992324 992324 0 0.00 0 24950 6 0 0
srv_make_invnt_saldo 415 415 0 0.00 63 84195 4109 0 0
srv_make_money_saldo 699 699 0 0.00 1 14118 546 0 0
srv_recalc_idx_stat 4 4 0 0.00 155561 423569 306285 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4731 4731 0 0.00 0 17857 199 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 4724 131 0 0
t$perf-norm:sp_cancel_client_order 1620 1620 0 0.00 0 513 8 0 0
t$perf-norm:sp_cancel_customer_reserve 605 605 0 0.00 0 1800 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 1742 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 346 346 0 0.00 0 202 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 372 8 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 18041 1579 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 5211 21 0 0
t$perf-norm:sp_client_order 18987 18987 0 0.00 0 9988 20 0 0
t$perf-norm:sp_customer_reserve 866 866 0 0.00 0 1355 5 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 9992 13 0 0
t$perf-norm:sp_pay_to_supplier 5588 5588 0 0.00 0 6091 11 0 0
t$perf-norm:sp_reserve_write_off 9065 9065 0 0.00 0 6786 12 0 0
t$perf-norm:sp_supplier_invoice 5170 5170 0 0.00 0 5197 17 0 0
t$perf-norm:sp_supplier_order 5029 5029 0 0.00 0 4910 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 548 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 55 2 0 0
t$perf-norm:srv_make_invnt_saldo 415 415 0 0.00 0 3384 21 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 551 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9730 9442 288 2.95 0 15565 28 0 288
v_all_customers 35222 35222 0 0.00 0 16 1 0 0
v_all_suppliers 28578 28578 0 0.00 0 25 1 0 0
v_all_wares 37974 37974 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 565 8 0 0
v_cancel_client_order 3240 3240 0 0.00 0 4308 8 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 5789 17 0 0
v_cancel_customer_reserve 1210 1210 0 0.00 0 2417 8 0 0
v_cancel_payment_to_supplier 692 692 0 0.00 0 1108 7 0 0
v_cancel_supplier_invoice 770 734 36 4.67 0 2360 19 0 36
v_cancel_supplier_order 842 842 0 0.00 0 1572 8 0 0
v_cancel_write_off 1834 1834 0 0.00 0 2116 17 0 0
v_max_id_clo_ord 5029 5029 0 0.00 0 1514 2 0 0
v_max_id_clo_res 866 866 0 0.00 0 2034 15 0 0
v_max_id_ord_sup 5157 5157 0 0.00 0 2894 4 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 1083 4 0 0
v_max_non_paid_realizn 3428 3428 0 0.00 0 1086 2 0 0
v_min_id_clo_ord 5029 5029 0 0.00 0 4349 7 0 0
v_min_id_clo_res 866 866 0 0.00 0 4216 7 0 0
v_min_id_ord_sup 5157 5157 0 0.00 0 6736 9 0 0
v_min_non_paid_invoice 5588 5588 0 0.00 0 7804 17 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 5705 14 0 0
v_our_firm 9456 9456 0 0.00 0 19 1 0 0
v_reserve_write_off 18130 18130 0 0.00 0 8605 8 0 0
x_lock_depdocs_on_canc_invoice 10872 10872 0 0.00 0 18038 122 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 8552 362 0 0
x_make_qstorno_1000_1200 5029 5029 0 0.00 2 28852 1475 0 0
x_make_qstorno_1200_2000 5170 5170 0 0.00 3 122062 4018 0 0
x_make_qstorno_2100_3300 436786 436786 0 0.00 0 34502 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2021 10:38:27 to 16-10-2021 10:38:31.

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:12 38847 8192000000 9721330928 10019389440 795562256 8.851 100 85 169 2 824176256 8249840 20364160
08:09:59 22745 8192000000 9761853904 10068606976 834291328 9.242 100 80 160 0 866278320 12899872 19087120
08:11:28 18267 8192000000 9793398496 10113789952 861637008 9.517 100 83 166 0 909364064 28420016 19307040
08:12:53 10436 8192000000 9846489168 10178686976 903301376 9.931 100 83 166 0 962405280 40819408 18284496
08:14:09 8632 8192000000 9896634992 10238267392 934649424 10.240 100 77 153 2 1009718736 59155184 15914128
08:15:23 5364 8192000000 9947480144 10298863616 979274576 10.677 100 85 170 2 1057041440 60742544 17024320
08:16:30 3178 8192000000 9981003040 10337812480 1002635360 10.904 100 78 158 1 1094890016 76616000 15638656
08:17:36 2801 8192000000 9991294816 10352111616 1023545360 11.106 100 84 167 1 1096995104 56117456 17332288
08:18:40 1529 8192000000 10030831904 10400780288 1051034752 11.371 100 96 190 2 1138459568 67350448 20074368
08:19:42 8283 8192000000 9984296320 10374066176 1061458480 11.470 100 46 92 1 1122905136 51376640 10070016
08:20:52 4149 8192000000 10005189728 10403467264 1073573264 11.586 100 41 82 0 1121229248 39424576 8231408
08:21:57 5904 8192000000 9985609680 10378313728 1082529872 11.672 100 47 97 3 1114924640 21815024 10579744
08:23:04 48468 8192000000 10011151152 10402545664 1086612720 11.710 100 47 94 0 1109881296 12700816 10567760
08:24:53 6350 8192000000 9998653760 10404601856 1091749952 11.759 100 36 72 1 1119955600 18809056 9396592
08:26:00 5653 8192000000 10006654752 10402922496 1096503936 11.804 100 39 80 2 1124178432 19324672 8349824
08:27:06 9292 8192000000 10013252976 10422104064 1097070160 11.810 100 38 75 3 1120326016 14855344 8400512
08:28:16 36062 8192000000 9993977792 10408415232 1069272720 11.545 98 30 60 1 1110761440 35285824 6202896
08:29:53 3753 8192000000 9976830080 10358595584 1043237184 11.296 99 68 139 4 1075399680 17618336 14544160
08:31:00 10140 8192000000 9916173216 10308882432 941265152 10.305 91 21 42 2 971459760 25584400 4610208
08:32:11 67791 8192000000 9951004880 10341978112 1005345664 10.930 98 36 72 1 1019604480 6549600 7709216
08:34:24 5679 8192000000 9961709648 10354716672 1046858928 11.331 100 47 94 0 1074385328 17523344 10003056
08:35:33 6455 8192000000 9972502304 10372464640 1060112336 11.458 100 44 88 0 1083837584 13392288 10332960
08:36:42 53269 8192000000 9976680864 10370076672 1068776496 11.540 100 38 75 3 1090795072 13839216 8179360
08:38:38 14866 8192000000 10051601360 10447089664 1095819280 11.798 100 53 104 1 1146945216 41046352 10079584
08:40:01 4753 8192000000 10069028752 10467459072 1111714544 11.949 100 56 112 1 1180115456 57124288 11276624
08:41:10 21147 8192000000 10077722656 10489753600 1124549200 12.070 100 37 74 1 1195110384 62222816 8338368
08:42:35 10465 8192000000 10129804240 10532114432 1136888544 12.186 100 54 107 1 1235612464 87663504 11060416
08:43:50 20769 8192000000 10152157296 10560835584 1146329008 12.275 100 48 95 1 1253069952 96365440 10375504
08:45:14 7292 8192000000 10212826944 10625433600 1151704768 12.325 100 61 122 0 1326164288 161163184 13296336
08:46:27 5877 8192000000 10254235872 10657951744 1158852192 12.393 100 69 138 1 1353411184 179986848 14572144
08:47:40 5395 8192000000 10271691584 10687901696 1172684080 12.522 100 55 110 1 1376681136 191044096 12952960
08:49:02 3531 8192000000 10303740368 10713026560 1175088400 12.544 100 85 169 1 1398293504 204439200 18765904
08:50:10 4220 8192000000 10297014224 10704838656 1177543952 12.567 100 79 156 1 1388190288 193289776 17356560
08:51:21 2361 8192000000 10302387616 10734641152 1175746304 12.551 100 86 170 3 1396944688 202340288 18858096
08:52:27 2457 8192000000 10257816992 10660634624 1158973824 12.394 99 80 159 3 1354606176 177755808 17876544
08:53:33 9762 8192000000 10290187680 10705166336 1164703680 12.447 100 67 133 1 1403296320 223871104 14721536
08:54:48 14613 8192000000 10221681808 10661572608 1127789408 12.101 99 32 64 1 1312887520 177204592 7893520
08:56:06 10146 8192000000 10280375136 10701950976 1157904256 12.384 100 44 86 0 1388194096 220591472 9698368
08:57:34 2674 8192000000 10301761984 10716426240 1144279888 12.256 99 66 130 0 1395017984 235936144 14801952
08:58:42 3562 8192000000 10335647760 10740473856 1137959840 12.196 98 75 148 1 1414271968 259260448 17051680
08:59:59 8794 8192000000 10349420544 10768257024 1139588752 12.212 99 49 98 0 1438168464 286248496 12331216
09:01:11 6585 8192000000 10377595168 10798743552 1147588736 12.287 100 41 81 2 1475979024 318664832 9725456
09:02:20 8254 8192000000 10323500608 10747494400 1134734768 12.166 100 33 65 0 1419175472 276510400 7930304
09:03:35 11201 8192000000 10317207216 10775764992 1131733984 12.138 100 30 60 5 1431961264 292594784 7632496
09:05:15 21485 8192000000 10270119920 10749841408 1123763776 12.063 100 19 38 0 1383364944 254323136 5278032
09:06:43 17466 8192000000 10078312176 10492895232 1124626992 12.071 100 61 122 2 1174142368 35852128 13663248
09:08:05 47423 8192000000 10099348544 10524880896 1120377840 12.031 100 44 89 0 1177815520 48335328 9102352
09:10:00 45475 8192000000 10095562304 10547773440 1098423632 11.823 98 15 30 0 1192493744 91100256 2969856
09:11:48 51560 8192000000 10142697280 10580258816 1119044240 12.018 99 13 26 1 1254754064 132483936 3225888
09:14:06 94405 8192000000 10101292048 10573959168 1119900288 12.026 99 21 42 1 1182434768 57380096 5154384
09:17:21 40271 8192000000 10070971344 10491621376 1109765968 11.930 98 45 86 0 1154001296 34964048 9271280
09:19:02 37554 8192000000 10055915056 10484047872 1121585008 12.042 99 16 32 0 1157337024 32007888 3744128
09:20:41 40669 8192000000 10074446960 10506600448 1141782416 12.232 100 26 51 1 1185163040 37582688 5797936
09:22:23 53315 8192000000 10098617536 10555662336 1128292240 12.105 98 20 40 2 1192706240 59650560 4763440
09:25:09 99809 8192000000 10134238464 10616033280 1146860384 12.280 100 22 43 0 1250068656 98601568 4606704
09:27:53 70794 8192000000 10077954256 10574561280 1150000032 12.309 100 11 22 0 1200098176 47730880 2367264
09:30:04 41484 8192000000 10116847104 10554818560 1155498752 12.361 100 29 58 0 1191423968 29556016 6369200
09:32:01 48858 8192000000 10172579088 10614222848 1138404608 12.201 98 24 48 0 1216032208 72475824 5151776
09:34:05 65689 8192000000 10124817216 10586718208 1157034112 12.375 100 14 29 0 1248076192 88016192 3025888
09:36:13 52817 8192000000 10121205152 10575806464 1156620096 12.372 99 20 40 1 1231169616 70468096 4081424
09:38:13 16449 8192000000 10112287952 10543562752 1143040240 12.244 98 37 72 3 1174363120 23908416 7414464
09:39:36 56445 8192000000 10097705200 10563571712 1109061376 11.924 96 24 48 0 1167949552 54306112 4582064
09:41:33 64121 8192000000 10091273184 10523492352 1125474016 12.079 98 39 78 0 1169037840 35470464 8093360
09:43:44 2433 8192000000 10088691040 10535727104 1151456416 12.323 100 42 83 1 1195793584 35603168 8734000
09:44:47 56442 8192000000 10068403584 10523426816 1099851328 11.836 96 21 43 0 1156147536 52554704 3741504
09:46:44 66269 8192000000 10109041696 10545995776 1109642688 11.929 97 20 40 1 1164895984 50872704 4380592
09:49:35 92168 8192000000 10159587104 10669957120 1124400208 12.069 99 14 28 1 1260860448 133239088 3221152
09:52:07 78156 8192000000 10057997552 10504671232 1096793680 11.807 97 11 22 1 1142619632 43695680 2130272
09:54:28 20886 8192000000 10094846128 10528120832 1125702224 12.081 99 47 95 1 1194530176 58884224 9943728
09:55:49 49775 8192000000 10053960080 10489892864 1094464544 11.785 97 24 48 0 1130454816 31038976 4951296
09:57:39 69688 8192000000 10125880000 10573578240 1099398112 11.832 97 20 40 0 1169470864 65461824 4610928
09:59:50 112693 8192000000 10076588928 10532175872 1096301840 11.803 97 14 28 0 1136574928 37704192 2568896
10:02:49 73575 8192000000 10049057440 10477719552 1092882912 11.770 97 39 78 0 1129147536 28272400 7992224
10:05:13 43301 8192000000 10059598368 10478809088 1093551712 11.776 97 38 76 0 1132657616 30980048 8125856
10:07:00 65337 8192000000 10088800544 10540474368 1126402480 12.087 100 11 22 0 1198907552 70094432 2410640
10:09:05 61982 8192000000 10069705984 10519826432 1116864672 11.997 99 11 22 0 1155930656 36809600 2256384
10:11:44 22441 8192000000 10058644112 10487656448 1117231072 12.001 99 21 42 0 1145530768 24419616 3880080
10:13:28 27738 8192000000 10062721232 10503614464 1110945504 11.941 98 17 34 0 1150422384 36025424 3451456
10:15:00 30639 8192000000 10118724736 10543185920 1125892752 12.083 99 34 68 3 1196534960 63398144 7244064
10:16:37 96493 8192000000 10055372736 10528354304 1117499376 12.003 98 17 34 0 1206310720 84584528 4226816
10:19:14 78534 8192000000 10110841072 10566238208 1137979408 12.197 100 25 50 1 1190397600 47259808 5158384
10:21:33 112149 8192000000 10090771904 10585694208 1134420656 12.163 99 16 32 0 1224833536 87188768 3224112
10:24:35 117200 8192000000 10092812240 10527989760 1114790592 11.978 97 21 43 0 1197449328 78093024 4565712
10:27:33 52179 8192000000 10063905744 10524237824 1129859456 12.120 99 12 24 1 1158911488 26710608 2341424
10:29:31 49313 8192000000 10077045104 10530213888 1122289680 12.049 98 9 19 0 1178420400 54434416 1696304
10:31:21 63518 8192000000 10068066208 10533990400 1085062080 11.696 95 12 22 1 1121700832 34475808 2162944
10:33:28 62236 8192000000 10116408992 10584256512 1132253216 12.143 99 25 48 3 1207234576 69565008 5416352
10:35:39 56010 8192000000 10117450832 10568957952 1139599296 12.212 100 31 62 1 1205383584 59490448 6293840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2021 10:38:33 to 16-10-2021 10:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

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

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          220895
MON$OLDEST_ACTIVE               223707
MON$OLDEST_SNAPSHOT             223707
MON$NEXT_TRANSACTION            223707
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                       2026656
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             777
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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Sat Oct 16 10:37:12 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Sat Oct 16 10:37:17 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Sat Oct 16 10:37:17 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Sat Oct 16 10:37:19 2021
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:198: 
--- end of diff output ---

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

16.10.21 10:38:52. 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-16 10:38:54 No severe PSQL-related problems occured
16.10.21 10:38:56. End of report.