Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 13:39
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 16277024 kB
MemAvailable 30866364 kB
Buffers 0 kB
Cached 15461476 kB
SwapCached 416 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-09-17 13:37:07
DTS_END                         2021-09-17 13:37:07.0880
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/46082
TRN_ID                          239912
ATT_ID                          672
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: 8801.28 23637 1056153
customer order: creation 171.27 474 20552
customer order: refuse 14.23 303 1708
order to supplier: creation 44.55 1518 5346
order to supplier: removal 3.18 120297 382
invoice (draft): creation 46.79 3669 5615
invoice (draft): removal 46.88 1091 5626
invoice accept: apply 41.48 18879 4978
invoice accept: cancel 42.47 921 5096
customer reserve: creation 3869.59 184 464351
customer reserve: removal 4232.10 72 507852
realization accept: apply 84.30 167 10116
realization accept: cancel 77.18 128 9261
payment from customer: creation 57.83 91 6940
payment from customer: removal 5.61 69 673
payment to supplier: creation 48.93 113 5872
payment to supplier: removal 3.61 122 433
service: total inventory turnovers 4.04 2888 485
service: total monetary turnovers 6.18 393 741
service: aggregate perf. data 1.02 11921 122
service: refresh index statistics 0.03 309442 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 13:39:38 to 17-09-2021 13:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2341
TEST_TIME 2 2638
TEST_TIME 3 3715
TEST_TIME 4 4259
TEST_TIME 5 4247
TEST_TIME 6 4201
TEST_TIME 7 4453
TEST_TIME 8 5096
TEST_TIME 9 5135
TEST_TIME 10 6012
TEST_TIME 11 6248
TEST_TIME 12 7073
TEST_TIME 13 7225
TEST_TIME 14 7970
TEST_TIME 15 8519
TEST_TIME 16 8531
TEST_TIME 17 8776
TEST_TIME 18 9053
TEST_TIME 19 9156
TEST_TIME 20 9192
TEST_TIME 21 9469
TEST_TIME 22 9435
TEST_TIME 23 9242
TEST_TIME 24 9170
TEST_TIME 25 10416
TEST_TIME 26 11557
TEST_TIME 27 11290
TEST_TIME 28 10962
TEST_TIME 29 10910
TEST_TIME 30 10715
TEST_TIME 31 10544
TEST_TIME 32 10376
TEST_TIME 33 10120
TEST_TIME 34 10073
TEST_TIME 35 9928
TEST_TIME 36 9814
TEST_TIME 37 9764
TEST_TIME 38 9640
TEST_TIME 39 9537
TEST_TIME 40 9511
TEST_TIME 41 9517
TEST_TIME 42 9435
TEST_TIME 43 9283
TEST_TIME 44 9183
TEST_TIME 45 9094
TEST_TIME 46 8980
TEST_TIME 47 9078
TEST_TIME 48 9050
TEST_TIME 49 9001
TEST_TIME 50 8971
TEST_TIME 51 8978
TEST_TIME 52 9053
TEST_TIME 53 9009
TEST_TIME 54 9160
TEST_TIME 55 9182
TEST_TIME 56 9187
TEST_TIME 57 9116
TEST_TIME 58 9278
TEST_TIME 59 9243
TEST_TIME 60 9314
TEST_TIME 61 9350
TEST_TIME 62 9248
TEST_TIME 63 9194
TEST_TIME 64 9129
TEST_TIME 65 9095
TEST_TIME 66 9082
TEST_TIME 67 9040
TEST_TIME 68 9051
TEST_TIME 69 9059
TEST_TIME 70 9002
TEST_TIME 71 8985
TEST_TIME 72 8977
TEST_TIME 73 9107
TEST_TIME 74 9130
TEST_TIME 75 9103
TEST_TIME 76 9133
TEST_TIME 77 9174
TEST_TIME 78 9234
TEST_TIME 79 9211
TEST_TIME 80 9232
TEST_TIME 81 9186
TEST_TIME 82 9091
TEST_TIME 83 9084
TEST_TIME 84 9031
TEST_TIME 85 8990
TEST_TIME 86 8937
TEST_TIME 87 8918
TEST_TIME 88 8882
TEST_TIME 89 8872
TEST_TIME 90 8890
TEST_TIME 91 8867
TEST_TIME 92 8888
TEST_TIME 93 8955
TEST_TIME 94 8930
TEST_TIME 95 8956
TEST_TIME 96 9008
TEST_TIME 97 9037
TEST_TIME 98 9013
TEST_TIME 99 9038
TEST_TIME 100 9067
TEST_TIME 101 9028
TEST_TIME 102 8943
TEST_TIME 103 8928
TEST_TIME 104 8898
TEST_TIME 105 8830
TEST_TIME 106 8782
TEST_TIME 107 8797
TEST_TIME 108 8820
TEST_TIME 109 8877
TEST_TIME 110 8875
TEST_TIME 111 8859
TEST_TIME 112 8848
TEST_TIME 113 8864
TEST_TIME 114 8879
TEST_TIME 115 8887
TEST_TIME 116 8948
TEST_TIME 117 8916
TEST_TIME 118 8904
TEST_TIME 119 8874
TEST_TIME 120 8803

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 13:39:42 to 17-09-2021 13:39:45.

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 1486337 1486337 0 0.00 0 12992 7 0 0
doc_list_biud 1486337 1486337 0 0.00 0 56858 32 0 0
sp_add_invoice_to_stock 5139 4978 161 3.13 32 192544 18879 0 161
sp_cancel_adding_invoice 5096 5096 0 0.00 4 73713 921 0 0
sp_cancel_client_order 1708 1708 0 0.00 4 9366 303 0 0
sp_cancel_customer_reserve 507852 507852 0 0.00 1 36454 72 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 4050 69 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 10726 122 0 0
sp_cancel_supplier_invoice 5639 5626 13 0.23 2 43891 1092 0 13
sp_cancel_supplier_order 382 382 0 0.00 7 879028 120297 0 0
sp_cancel_write_off 9261 9261 0 0.00 1 11772 129 0 0
sp_client_order 20552 20552 0 0.00 4 27544 475 0 0
sp_customer_reserve 464351 464351 0 0.00 0 49825 185 0 0
sp_fill_shopping_cart 31504 31504 0 0.00 1 24252 54 0 0
sp_fill_shopping_cart_clo_res 464350 464350 0 0.00 0 28725 20 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 22169 436 0 0
sp_kill_cost_storno 15463 15463 0 0.00 0 11769 56 0 0
sp_kill_qstorno_ret_qs2qd 1030973 1030973 0 0.00 0 43884 40 0 0
sp_kill_qty_storno 545016 545016 0 0.00 0 56858 85 0 0
sp_lock_selected_doc 20819 20819 0 0.00 0 10671 5 0 0
sp_make_cost_storno 27903 27903 0 0.00 0 8686 45 0 0
sp_multiply_rows_for_pdistr 27903 27903 0 0.00 0 7696 25 0 0
sp_multiply_rows_for_qdistr 20552 20552 0 0.00 0 24558 262 0 0
sp_pay_from_customer 6940 6940 0 0.00 4 10933 92 0 0
sp_pay_to_supplier 5872 5872 0 0.00 5 15190 114 0 0
sp_payment_common 12812 12812 0 0.00 1 6761 16 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 9357 271 0 0
sp_qd_handle_on_invoice_upd_sts 10071 10071 0 0.00 0 56857 381 0 0
sp_qd_handle_on_reserve_upd_sts 10116 10116 0 0.00 0 26801 63 0 0
sp_reserve_write_off 10116 10116 0 0.00 6 26842 167 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 76878 3670 0 0
sp_supplier_order 5346 5346 0 0.00 6 53952 1518 0 0
srv_aggregate_perf_data 122 122 0 0.00 2611 184325 11922 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1331 111791 48441 0 0
srv_find_qd_qs_mism 1008721 1008721 0 0.00 0 12990 6 0 0
srv_make_invnt_saldo 485 485 0 0.00 29 97266 2888 0 0
srv_make_money_saldo 741 741 0 0.00 1 13177 394 0 0
srv_recalc_idx_stat 4 4 0 0.00 240949 411106 309443 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 0 22570 162 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 2427 68 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 2977 12 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 3176 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2999 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 628 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1275 11 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 15741 1582 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 6344 16 0 0
t$perf-norm:sp_client_order 20552 20552 0 0.00 0 10356 15 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 937 4 0 0
t$perf-norm:sp_pay_from_customer 6940 6940 0 0.00 0 5255 9 0 0
t$perf-norm:sp_pay_to_supplier 5872 5872 0 0.00 0 7047 11 0 0
t$perf-norm:sp_reserve_write_off 10116 10116 0 0.00 0 8732 13 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 6328 16 0 0
t$perf-norm:sp_supplier_order 5346 5346 0 0.00 0 4743 14 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 127 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 4737 16 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 237 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10272 9950 322 3.13 0 3763 16 0 322
v_all_customers 36984 36984 0 0.00 0 85 1 0 0
v_all_suppliers 30424 30424 0 0.00 0 16 1 0 0
v_all_wares 41104 41104 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 290 6 0 0
v_cancel_client_order 3416 3416 0 0.00 0 5339 6 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3980 11 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2298 8 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3812 12 0 0
v_cancel_supplier_invoice 844 818 26 3.08 0 1925 17 0 26
v_cancel_supplier_order 764 764 0 0.00 0 3412 7 0 0
v_cancel_write_off 2078 2078 0 0.00 0 3178 18 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 793 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 2669 16 0 0
v_max_id_ord_sup 5605 5605 0 0.00 0 2989 4 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 49 2 0 0
v_max_non_paid_realizn 3800 3800 0 0.00 0 293 2 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 4008 5 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 6566 15 0 0
v_min_id_ord_sup 5605 5605 0 0.00 0 14031 14 0 0
v_min_non_paid_invoice 5872 5872 0 0.00 0 4878 9 0 0
v_min_non_paid_realizn 6940 6940 0 0.00 0 4893 9 0 0
v_our_firm 10400 10400 0 0.00 0 19 1 0 0
v_reserve_write_off 20232 20232 0 0.00 0 3417 6 0 0
x_lock_depdocs_on_canc_invoice 10313 10313 0 0.00 0 27844 115 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 16041 387 0 0
x_make_qstorno_1000_1200 5346 5346 0 0.00 2 53855 1447 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 3 75522 3447 0 0
x_make_qstorno_2100_3300 464351 464351 0 0.00 0 49706 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 13:39:46 to 17-09-2021 13:39:50.

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
11:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 25694 8192000000 9723142352 10020724736 804627968 8.943 100 86 172 1 832172608 7139920 20404720
11:09:44 24095 8192000000 9758840640 10064068608 835041024 9.250 100 85 170 0 867571248 12133040 20397184
11:11:12 18361 8192000000 9809367040 10129657856 869623552 9.596 100 72 145 0 912358448 26429088 16305808
11:12:39 17213 8192000000 9873159680 10210693120 925088144 10.146 100 82 164 0 982587936 40000800 17498992
11:13:59 4775 8192000000 9939730128 10283708416 973021872 10.616 100 85 168 1 1050249216 59833488 17393856
11:15:11 4546 8192000000 9983876720 10339880960 1006267744 10.939 100 85 170 0 1103801968 80671520 16862704
11:16:18 2456 8192000000 10012707936 10375225344 1028329280 11.152 100 93 185 1 1120224768 73330240 18565248
11:17:22 1502 8192000000 10049901824 10418487296 1051468528 11.375 100 92 183 0 1150631552 80467024 18696000
11:18:24 26978 8192000000 10008813264 10392276992 1067326704 11.527 100 71 141 2 1128407856 46527952 14553200
11:19:53 7071 8192000000 9999388912 10389626880 1070365344 11.556 100 43 86 1 1112542224 33188464 8988416
11:21:03 8790 8192000000 9977847776 10385428480 1081062448 11.658 100 33 66 0 1115454640 26713856 7678336
11:22:13 6904 8192000000 10013212672 10414727168 1088548800 11.729 100 44 88 1 1123488128 25839408 9099920
11:23:22 61380 8192000000 10008632608 10419970048 1097488800 11.814 100 26 52 0 1128473248 25265472 5718976
11:25:24 9459 8192000000 10023696720 10428534784 1099016608 11.828 100 26 52 0 1132740592 27863712 5860272
11:26:36 7206 8192000000 10009655664 10405642240 1095455600 11.795 100 34 68 0 1117981200 14913552 7612048
11:27:45 6755 8192000000 9971134672 10373025792 1042506112 11.289 97 34 67 3 1061572336 11045104 8021120
11:28:53 11536 8192000000 9961999008 10347814912 1044512336 11.308 99 53 106 0 1070128944 14577312 11039296
11:30:07 3486 8192000000 9956871520 10328858624 1017504608 11.048 98 38 78 1 1049723728 23959184 8259936
11:31:14 8381 8192000000 9926104384 10316390400 993543392 10.816 97 31 63 1 1019237360 18471872 7222096
11:32:23 69907 8192000000 9955469216 10367922176 1042056144 11.284 100 35 68 0 1073326784 23916672 7353968
11:34:33 6028 8192000000 9970866880 10359177216 1056449152 11.422 100 32 64 0 1082600480 17894992 8256336
11:35:40 5635 8192000000 9987933152 10381565952 1070409616 11.556 100 37 74 1 1089914400 10592256 8912528
11:36:48 28122 8192000000 9989110672 10399928320 1082075280 11.667 100 29 56 1 1105065536 16766112 6224144
11:38:51 69215 8192000000 10071495072 10481627136 1106226192 11.897 100 33 66 1 1163945120 51116304 6602624
11:41:17 64819 8192000000 10106251840 10525470720 1136290496 12.181 100 35 70 3 1214159536 69727872 8141168
11:43:27 15041 8192000000 10161947616 10574299136 1150036064 12.310 100 45 90 2 1269977712 109429408 10512240
11:44:47 11854 8192000000 10223462400 10646810624 1163711088 12.438 100 47 92 1 1329211248 154357920 11142240
11:46:10 1329 8192000000 10261479472 10665885696 1170173152 12.498 100 92 183 1 1367682080 177658208 19850720
11:47:15 4086 8192000000 10309252272 10719744000 1177538512 12.567 100 69 138 0 1413766096 220132672 16094912
11:48:24 1580 8192000000 10324325760 10725031936 1167485296 12.473 100 80 156 0 1420635936 235929776 17220864
11:49:27 7080 8192000000 10256712432 10660696064 1139736976 12.213 98 63 126 0 1352815504 198364064 14714464
11:50:39 2334 8192000000 10318025952 10719911936 1147398176 12.285 99 81 158 0 1410102784 244615104 18089504
11:51:47 3386 8192000000 10315985616 10740240384 1134509792 12.164 98 80 157 2 1403565008 251360784 17694432
11:52:54 6703 8192000000 10290409056 10726350848 1155940656 12.365 100 45 88 2 1388440496 222006512 10493328
11:54:05 2607 8192000000 10339580272 10742001664 1160414240 12.407 100 69 136 0 1433748432 257433696 15900496
11:55:11 4010 8192000000 10325364032 10728222720 1143084176 12.245 100 68 136 0 1425048128 265813648 16150304
11:56:17 8299 8192000000 10310362032 10765979648 1137937264 12.196 99 34 68 1 1406446192 259931472 8577456
11:57:35 6839 8192000000 10351885312 10787110912 1147801200 12.289 99 37 74 0 1447354800 290836800 8716800
11:58:52 28036 8192000000 10350202432 10818932736 1147899584 12.290 100 27 54 0 1453742560 298688208 7154768
12:00:21 23431 8192000000 10432621712 10864566272 1138451632 12.201 100 29 58 2 1524962080 378623744 7886704
12:01:46 14045 8192000000 10473555616 10911223808 1119728944 12.024 100 38 75 0 1555230624 424896208 10605472
12:03:08 24315 8192000000 10087972880 10566250496 1102671200 11.863 99 17 34 0 1208033264 101232352 4129712
12:04:34 105455 8192000000 10089566880 10529599488 1121393968 12.040 100 22 44 0 1164875056 38229904 5251184
12:07:25 27331 8192000000 10052850224 10532904960 1115221200 11.982 99 12 24 0 1165063616 47714736 2127680
12:08:53 57546 8192000000 10069470416 10526203904 1131684720 12.137 100 19 38 0 1181793520 46130944 3977856
12:10:53 90336 8192000000 10062001760 10513321984 1124140496 12.066 99 22 43 1 1178067472 49618576 4308400
12:13:36 100221 8192000000 10082046768 10552074240 1110615552 11.938 98 28 56 2 1164358128 47733600 6008976
12:16:17 37680 8192000000 10087370160 10529943552 1108105008 11.914 98 19 37 1 1172491600 60136368 4250224
12:18:07 53181 8192000000 10063640448 10534027264 1109795968 11.930 98 28 56 0 1185483264 69519648 6167648
12:20:02 107810 8192000000 10078206704 10529378304 1120458208 12.031 99 30 60 0 1159715680 32860416 6397056
12:23:07 45715 8192000000 10109665760 10569781248 1137342784 12.191 100 7 14 0 1215722608 76839920 1539904
12:25:47 59922 8192000000 10095439088 10547363840 1141606496 12.231 99 17 34 0 1184277808 39266048 3405264
12:27:48 65693 8192000000 10128382624 10584358912 1158165216 12.386 100 19 38 0 1234658032 72465488 4027328
12:29:54 32038 8192000000 10132732688 10582855680 1170218192 12.499 100 35 70 0 1231586992 54259264 7109536
12:31:29 47096 8192000000 10088887216 10602721280 1124145856 12.066 97 8 16 0 1174399216 48677376 1575984
12:33:16 33689 8192000000 10078639088 10522574848 1127504224 12.098 98 21 42 1 1165232080 33615696 4112160
12:34:51 75701 8192000000 10096290144 10593652736 1139187280 12.208 98 23 46 1 1239098912 95224256 4687376
12:37:07 46573 8192000000 10150508800 10592808960 1096744384 11.807 95 28 56 1 1159251600 57019424 5487792
12:38:56 91802 8192000000 10056345200 10492362752 1097192192 11.811 97 28 54 0 1140223152 37850800 5180160
12:41:28 22572 8192000000 10063064864 10491588608 1115431376 11.984 99 41 82 1 1157061744 33178112 8452256
12:42:52 81708 8192000000 10059485728 10540089344 1088520672 11.729 96 18 36 0 1165480112 72974016 3985424
12:45:14 52768 8192000000 10065437008 10548895744 1125755872 12.081 99 22 44 0 1195575104 65262048 4557184
12:47:15 65256 8192000000 10072588736 10524360704 1080549824 11.653 95 31 58 1 1136239280 48934752 6754704
12:49:21 58163 8192000000 10122849952 10630586368 1128116048 12.104 99 25 50 2 1227588432 93856928 5615456
12:51:25 46693 8192000000 10086643712 10524491776 1101855504 11.855 97 30 60 1 1139861360 32114832 5891024
12:53:12 49625 8192000000 10104709856 10538647552 1108859536 11.922 98 43 86 0 1180105168 62234224 9011408
12:55:07 51090 8192000000 10057503536 10495098880 1106647040 11.901 98 15 30 0 1135052992 25238528 3167424
12:57:04 57758 8192000000 10058997696 10510155776 1097504304 11.814 97 40 80 1 1171582720 66401680 7676736
12:59:02 99113 8192000000 10071853888 10521456640 1112278976 11.954 99 21 40 1 1175123072 58460912 4383184
13:01:53 44793 8192000000 10035068832 10489860096 1107464176 11.908 99 9 18 0 1147258912 37764544 2030192
13:05:02 39769 8192000000 10073741760 10516176896 1127335520 12.096 100 10 20 0 1162419856 33299056 1785280
13:06:46 36464 8192000000 10099579776 10539671552 1131897056 12.139 99 29 58 1 1199426928 61014992 6514880
13:08:23 30138 8192000000 10120472384 10564837376 1131008224 12.131 99 26 52 1 1193832240 57295808 5528208
13:09:53 64699 8192000000 10109945856 10602561536 1126627344 12.090 98 23 46 0 1228149408 96043904 5478160
13:12:04 64945 8192000000 10132847952 10573324288 1123025056 12.056 98 15 30 1 1211327872 85015504 3287312
13:14:09 69345 8192000000 10081364480 10521227264 1108424672 11.918 97 27 53 0 1158952272 43998048 6529552
13:16:21 74090 8192000000 10081246016 10557386752 1144433808 12.257 100 28 55 1 1213873984 63664976 5775200
13:18:37 111791 8192000000 10117695504 10556788736 1134635136 12.165 100 50 100 1 1206612736 61348512 10629088
13:21:30 93299 8192000000 10129556544 10598944768 1131265904 12.133 99 7 14 0 1220694656 87830496 1598256
13:24:11 51088 8192000000 10133671520 10582818816 1134946960 12.168 99 18 36 0 1231221168 91982672 4291536
13:26:07 97919 8192000000 10049662160 10508394496 1060519984 11.461 93 19 37 0 1100044624 34964704 4559936
13:28:59 57625 8192000000 10106026224 10549141504 1104406752 11.879 97 17 34 1 1167148096 59082960 3658384
13:30:58 72680 8192000000 10158530848 10622398464 1139698016 12.213 100 31 62 0 1238421904 91942048 6781840
13:33:22 59923 8192000000 10063892496 10496200704 1107795136 11.912 98 23 45 1 1139118112 27026240 4296736
13:35:24 93436 8192000000 10073176064 10548748288 1096559584 11.805 98 39 78 1 1164966944 60425680 7981680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 13:39:52 to 17-09-2021 13:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-09-2021 13:40:08 to 17-09-2021 13:40:10.

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          239444
MON$OLDEST_ACTIVE               240150
MON$OLDEST_SNAPSHOT             240150
MON$NEXT_TRANSACTION            240150
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                       2024576
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2604 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2604 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2604 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2604 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Fri Sep 17 13:37:14 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Sep 17 13:37:21 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

17.09.21 13:40:11. 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-09-17 13:40:13 No severe PSQL-related problems occured
17.09.21 13:40:14. End of report.