Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.06.2021 07:28
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 17074808 kB
MemAvailable 31436532 kB
Buffers 0 kB
Cached 14590208 kB
SwapCached 80 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-06-10 07:26:59
DTS_END                         2021-06-10 07:26:59.9640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38046
TRN_ID                          220282
ATT_ID                          566
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: 8662.09 25715 1039451
customer order: creation 160.79 471 19295
customer order: refuse 13.34 357 1601
order to supplier: creation 42.53 1433 5103
order to supplier: removal 3.43 131338 412
invoice (draft): creation 42.24 3240 5069
invoice (draft): removal 48.67 1206 5840
invoice accept: apply 39.06 17938 4687
invoice accept: cancel 43.87 1040 5264
customer reserve: creation 3661.39 171 439367
customer reserve: removal 4327.36 81 519283
realization accept: apply 78.72 176 9447
realization accept: cancel 80.03 139 9603
payment from customer: creation 55.34 106 6641
payment from customer: removal 5.41 112 649
payment to supplier: creation 46.43 119 5572
payment to supplier: removal 3.13 113 376
service: total inventory turnovers 3.88 3364 466
service: total monetary turnovers 5.60 565 672
service: aggregate perf. data 0.83 20041 100
service: refresh index statistics 0.03 332297 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-06-2021 07:28:24 to 10-06-2021 07:28:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3174
TEST_TIME 2 2672
TEST_TIME 3 4169
TEST_TIME 4 4452
TEST_TIME 5 4224
TEST_TIME 6 4137
TEST_TIME 7 4721
TEST_TIME 8 4515
TEST_TIME 9 4467
TEST_TIME 10 4474
TEST_TIME 11 4483
TEST_TIME 12 4610
TEST_TIME 13 4719
TEST_TIME 14 5040
TEST_TIME 15 5096
TEST_TIME 16 5295
TEST_TIME 17 5327
TEST_TIME 18 5681
TEST_TIME 19 5945
TEST_TIME 20 6210
TEST_TIME 21 6434
TEST_TIME 22 6730
TEST_TIME 23 6818
TEST_TIME 24 7495
TEST_TIME 25 7818
TEST_TIME 26 8373
TEST_TIME 27 9274
TEST_TIME 28 10492
TEST_TIME 29 10598
TEST_TIME 30 10695
TEST_TIME 31 10457
TEST_TIME 32 10258
TEST_TIME 33 10030
TEST_TIME 34 9893
TEST_TIME 35 9868
TEST_TIME 36 9911
TEST_TIME 37 9771
TEST_TIME 38 9665
TEST_TIME 39 9559
TEST_TIME 40 9451
TEST_TIME 41 9417
TEST_TIME 42 9418
TEST_TIME 43 9354
TEST_TIME 44 9275
TEST_TIME 45 9353
TEST_TIME 46 9266
TEST_TIME 47 9408
TEST_TIME 48 9349
TEST_TIME 49 9333
TEST_TIME 50 9280
TEST_TIME 51 9321
TEST_TIME 52 9292
TEST_TIME 53 9431
TEST_TIME 54 9397
TEST_TIME 55 9400
TEST_TIME 56 9243
TEST_TIME 57 9118
TEST_TIME 58 9093
TEST_TIME 59 9016
TEST_TIME 60 8938
TEST_TIME 61 8840
TEST_TIME 62 8836
TEST_TIME 63 8818
TEST_TIME 64 8906
TEST_TIME 65 8873
TEST_TIME 66 8917
TEST_TIME 67 8943
TEST_TIME 68 8924
TEST_TIME 69 9000
TEST_TIME 70 9010
TEST_TIME 71 8998
TEST_TIME 72 9039
TEST_TIME 73 9143
TEST_TIME 74 9130
TEST_TIME 75 9108
TEST_TIME 76 9008
TEST_TIME 77 8930
TEST_TIME 78 8889
TEST_TIME 79 8878
TEST_TIME 80 8887
TEST_TIME 81 8877
TEST_TIME 82 8841
TEST_TIME 83 8852
TEST_TIME 84 8825
TEST_TIME 85 8815
TEST_TIME 86 8839
TEST_TIME 87 8877
TEST_TIME 88 8908
TEST_TIME 89 8914
TEST_TIME 90 8912
TEST_TIME 91 8898
TEST_TIME 92 8858
TEST_TIME 93 8832
TEST_TIME 94 8840
TEST_TIME 95 8794
TEST_TIME 96 8771
TEST_TIME 97 8751
TEST_TIME 98 8735
TEST_TIME 99 8745
TEST_TIME 100 8747
TEST_TIME 101 8719
TEST_TIME 102 8763
TEST_TIME 103 8782
TEST_TIME 104 8867
TEST_TIME 105 8847
TEST_TIME 106 8865
TEST_TIME 107 8876
TEST_TIME 108 8879
TEST_TIME 109 8918
TEST_TIME 110 8895
TEST_TIME 111 8853
TEST_TIME 112 8821
TEST_TIME 113 8789
TEST_TIME 114 8745
TEST_TIME 115 8699
TEST_TIME 116 8665
TEST_TIME 117 8692
TEST_TIME 118 8659
TEST_TIME 119 8657
TEST_TIME 120 8664
TEST_TIME 121 8592

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-06-2021 07:28:28 to 10-06-2021 07:28:31.

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 1444737 1444737 0 0.00 0 18941 7 0 0
doc_list_biud 1444735 1444735 0 0.00 0 44006 37 0 0
sp_add_invoice_to_stock 4779 4687 92 1.92 29 223057 17939 0 92
sp_cancel_adding_invoice 5264 5264 0 0.00 5 119731 1041 0 0
sp_cancel_client_order 1601 1601 0 0.00 4 18655 357 0 0
sp_cancel_customer_reserve 519283 519283 0 0.00 1 44008 82 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 17781 113 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 3609 114 0 0
sp_cancel_supplier_invoice 5844 5840 4 0.06 3 30541 1206 0 4
sp_cancel_supplier_order 412 412 0 0.00 6 1363679 131338 0 0
sp_cancel_write_off 9603 9603 0 0.00 1 8115 139 0 0
sp_client_order 19295 19295 0 0.00 4 18952 471 0 0
sp_customer_reserve 439367 439367 0 0.00 0 99029 172 0 0
sp_fill_shopping_cart 29435 29435 0 0.00 1 12351 41 0 0
sp_fill_shopping_cart_clo_res 439358 439358 0 0.00 0 27335 21 0 0
sp_get_clo_for_invoice 4677 4677 0 0.00 0 36439 449 0 0
sp_kill_cost_storno 15892 15892 0 0.00 0 5764 74 0 0
sp_kill_qstorno_ret_qs2qd 1054421 1054421 0 0.00 0 38487 44 0 0
sp_kill_qty_storno 556126 556126 0 0.00 0 44006 95 0 0
sp_lock_selected_doc 19570 19570 0 0.00 0 10462 6 0 0
sp_make_cost_storno 26337 26337 0 0.00 0 11009 50 0 0
sp_multiply_rows_for_pdistr 26336 26336 0 0.00 0 9364 34 0 0
sp_multiply_rows_for_qdistr 19295 19295 0 0.00 0 18315 285 0 0
sp_pay_from_customer 6641 6641 0 0.00 4 15236 107 0 0
sp_pay_to_supplier 5572 5572 0 0.00 5 14817 119 0 0
sp_payment_common 12213 12213 0 0.00 1 9233 20 0 0
sp_qd_handle_on_cancel_clo 1601 1601 0 0.00 0 18648 331 0 0
sp_qd_handle_on_invoice_upd_sts 9941 9941 0 0.00 0 21693 423 0 0
sp_qd_handle_on_reserve_upd_sts 9446 9446 0 0.00 0 11250 57 0 0
sp_reserve_write_off 9447 9447 0 0.00 7 11927 176 0 0
sp_supplier_invoice 5069 5069 0 0.00 6 84203 3241 0 0
sp_supplier_order 5103 5103 0 0.00 5 60895 1433 0 0
srv_aggregate_perf_data 100 100 0 0.00 2319 469424 20041 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1569 139768 45992 0 0
srv_find_qd_qs_mism 993825 993825 0 0.00 0 18940 6 0 0
srv_make_invnt_saldo 466 466 0 0.00 25 117120 3365 0 0
srv_make_money_saldo 672 672 0 0.00 1 16724 566 0 0
srv_recalc_idx_stat 4 4 0 0.00 187956 454920 332298 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 0 10643 158 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 11127 144 0 0
t$perf-norm:sp_cancel_client_order 1601 1601 0 0.00 0 7249 18 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 7046 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1031 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 794 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 5419 47 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27744 1560 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 3644 11 0 0
t$perf-norm:sp_client_order 19295 19295 0 0.00 0 6093 18 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 1765 7 0 0
t$perf-norm:sp_pay_from_customer 6641 6641 0 0.00 0 7871 9 0 0
t$perf-norm:sp_pay_to_supplier 5572 5572 0 0.00 0 4320 10 0 0
t$perf-norm:sp_reserve_write_off 9447 9447 0 0.00 0 5065 9 0 0
t$perf-norm:sp_supplier_invoice 5069 5069 0 0.00 0 3129 16 0 0
t$perf-norm:sp_supplier_order 5103 5103 0 0.00 0 7672 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1474 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 472 8 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 1354 10 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 3922 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9536 9352 184 1.92 0 10025 15 0 184
v_all_customers 35572 35572 0 0.00 0 175 1 0 0
v_all_suppliers 28668 28668 0 0.00 0 175 1 0 0
v_all_wares 38584 38584 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 2202 9 0 0
v_cancel_client_order 3202 3202 0 0.00 0 2510 4 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 7311 18 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 5914 11 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 3594 10 0 0
v_cancel_supplier_invoice 730 722 8 1.09 0 3737 24 0 8
v_cancel_supplier_order 824 824 0 0.00 0 856 4 0 0
v_cancel_write_off 1992 1992 0 0.00 0 587 10 0 0
v_max_id_clo_ord 5093 5093 0 0.00 0 4791 2 0 0
v_max_id_clo_res 963 963 0 0.00 0 2834 16 0 0
v_max_id_ord_sup 5049 5049 0 0.00 0 3586 4 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 234 2 0 0
v_max_non_paid_realizn 3386 3386 0 0.00 0 2425 4 0 0
v_min_id_clo_ord 5093 5093 0 0.00 0 1906 5 0 0
v_min_id_clo_res 963 963 0 0.00 0 6480 23 0 0
v_min_id_ord_sup 5049 5049 0 0.00 0 4432 4 0 0
v_min_non_paid_invoice 5572 5572 0 0.00 0 5438 14 0 0
v_min_non_paid_realizn 6641 6641 0 0.00 0 6189 15 0 0
v_our_firm 9522 9522 0 0.00 0 26 1 0 0
v_reserve_write_off 18892 18892 0 0.00 0 5939 7 0 0
x_lock_depdocs_on_canc_invoice 10743 10743 0 0.00 0 16461 117 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5360 356 0 0
x_make_qstorno_1000_1200 5103 5103 0 0.00 2 60879 1358 0 0
x_make_qstorno_1200_2000 5069 5069 0 0.00 2 84196 3081 0 0
x_make_qstorno_2100_3300 439367 439367 0 0.00 0 99028 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-06-2021 07:28:32 to 10-06-2021 07:28:36.

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
04:57:03 7 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
04:58:04 34597 8192000000 9740136320 10040328192 814822608 9.046 100 82 164 0 842468192 7809296 19836288
04:59:44 18731 8192000000 9777219376 10085855232 849992640 9.400 100 88 176 0 882173536 11959104 20221792
05:01:09 17195 8192000000 9810893936 10130235392 874895472 9.649 100 81 162 2 919693088 26226384 18571232
05:02:31 16744 8192000000 9869233968 10201120768 919650576 10.093 100 80 160 1 975751744 38436528 17664640
05:03:55 9668 8192000000 9912862176 10260148224 954018400 10.430 100 76 148 1 1027450432 58060704 15371328
05:05:10 6218 8192000000 9946063024 10297327616 989747584 10.779 100 71 142 0 1056628016 52778176 14102256
05:06:19 3936 8192000000 10012419664 10369921024 1009394528 10.970 100 82 164 2 1118993520 93499008 16099984
05:07:25 2460 8192000000 10013985616 10382106624 1034558704 11.212 100 86 170 2 1129697520 78438640 16700176
05:08:29 4953 8192000000 10019452752 10387193856 1049707968 11.358 100 88 176 0 1121589568 54097856 17783744
05:09:36 33896 8192000000 9982980560 10371391488 1061476000 11.471 100 51 102 1 1114053920 41934928 10642992
05:11:11 4513 8192000000 9988426160 10379890688 1070259456 11.555 100 40 78 1 1108421952 29320864 8841632
05:12:19 10214 8192000000 9994027888 10393911296 1075526512 11.605 100 42 84 0 1120997872 37009216 8462144
05:13:29 7424 8192000000 9989379872 10388135936 1087642688 11.720 100 33 64 2 1115425520 19714496 8068336
05:14:38 58174 8192000000 9997910368 10398302208 1091091360 11.753 100 45 88 2 1110145840 8847568 10206912
05:16:37 3906 8192000000 10011210976 10413211648 1098393904 11.822 100 53 106 1 1129861440 19885824 11581712
05:17:43 15253 8192000000 10011001120 10426355712 1095941424 11.799 100 34 69 0 1125906320 22189056 7775840
05:18:59 10277 8192000000 9994198272 10400428032 1050997024 11.370 97 29 57 2 1090423328 33793376 5632928
05:20:13 46127 8192000000 9928507280 10321477632 933150288 10.226 87 23 46 0 950716480 12594736 4971456
05:22:00 12454 8192000000 9939411472 10327961600 981425664 10.698 95 35 68 3 1022483408 33353984 7703760
05:23:16 5279 8192000000 9942438720 10326024192 1028500160 11.154 100 48 95 2 1053263840 13798448 10965232
05:24:25 6405 8192000000 9966584560 10356576256 1036880576 11.235 99 32 63 2 1066010160 21785136 7344448
05:25:33 31302 8192000000 9953728656 10362159104 1050110512 11.362 100 15 30 0 1073235328 20102400 3022416
05:27:05 57169 8192000000 9990891232 10382635008 1074821184 11.598 100 50 97 2 1104263120 18773952 10667984
05:29:30 6855 8192000000 10075150528 10471518208 1101453232 11.851 100 58 117 0 1181360656 67946624 11960800
05:30:43 6494 8192000000 10093813168 10498179072 1126733824 12.091 100 42 83 0 1196892560 60828336 9330400
05:31:52 46201 8192000000 10166543456 10578591744 1134276064 12.162 100 47 93 0 1269344960 124618064 10450832
05:34:13 25362 8192000000 10189370080 10600787968 1148049088 12.291 100 47 92 1 1285634752 127458688 10126976
05:35:56 15429 8192000000 10237881280 10649317376 1170002032 12.497 100 54 108 0 1340469920 158007888 12460000
05:37:17 3044 8192000000 10297064384 10703835136 1177590464 12.568 100 70 136 1 1400344224 207360048 15393712
05:38:23 8980 8192000000 10348155664 10771746816 1183521776 12.623 100 61 122 1 1455871056 258586336 13762944
05:39:52 6987 8192000000 10372068752 10789978112 1195501456 12.735 100 71 141 2 1469817872 257529072 16787344
05:41:13 2176 8192000000 10419317776 10831597568 1205446048 12.827 100 90 178 0 1520367920 294307136 20614736
05:42:21 3250 8192000000 10479443280 10891632640 1214333920 12.909 100 83 165 0 1575676352 342338992 19003440
05:43:30 1565 8192000000 10540419184 10952372224 1222039056 12.981 100 89 179 1 1642397008 399318224 21039728
05:44:38 1853 8192000000 10556044560 10971140096 1214002848 12.906 100 85 170 1 1655602448 421699936 19899664
05:45:52 2400 8192000000 10597467792 11016011776 1216092240 12.926 100 82 164 0 1691324192 455258336 19973616
05:46:59 3630 8192000000 10623779504 11043405824 1208055072 12.851 100 73 146 1 1720003456 493713728 18234656
05:48:24 3405 8192000000 10608448096 11041955840 1179659840 12.587 100 77 153 0 1705634224 505864784 20109600
05:49:34 12893 8192000000 10614709408 11057340416 1136000576 12.178 98 48 96 1 1710895952 561737184 13158192
05:50:55 5569 8192000000 10539051184 10978066432 1131227056 12.133 99 51 103 0 1636321920 491465952 13628912
05:52:09 20216 8192000000 10478049776 10973782016 1106088064 11.895 99 26 52 0 1595878992 481828976 7961952
05:53:32 17644 8192000000 10364259168 10854588416 1130686560 12.128 99 30 60 0 1485932032 347331632 7913840
05:54:51 46248 8192000000 10110776896 10634293248 1120108400 12.028 100 5 10 1 1207589792 86592400 888992
05:56:40 93910 8192000000 10060188752 10499268608 1101979216 11.856 98 17 33 0 1141234832 35871088 3384528
05:59:34 19788 8192000000 10058057360 10481156096 1110532656 11.937 99 41 79 1 1143276960 24347312 8396992
06:00:55 44874 8192000000 10043094464 10503069696 1078986448 11.638 96 24 48 0 1134651120 50729104 4935568
06:02:43 72910 8192000000 10066147600 10509037568 1110285856 11.935 99 14 28 1 1166396928 53413344 2697728
06:05:07 39011 8192000000 10075432784 10524262400 1125356144 12.078 99 23 45 0 1174417056 44171104 4889808
06:06:46 38805 8192000000 10096973504 10554957824 1109272704 11.926 98 27 54 1 1195742848 79874368 6595776
06:08:25 62022 8192000000 10070616912 10567888896 1089076096 11.734 97 11 22 0 1126447728 35021616 2350016
06:11:06 69779 8192000000 10081618752 10516160512 1102650064 11.863 98 22 43 2 1155568912 48367312 4551536
06:13:22 40747 8192000000 10047157280 10532069376 1117088912 11.999 100 3 6 0 1163641136 46072320 479904
06:15:04 55891 8192000000 10027188480 10453016576 1068412896 11.537 95 23 46 0 1101085456 27325056 5347504
06:17:03 39245 8192000000 10098255648 10546655232 1121523632 12.041 100 39 78 0 1177713616 48311840 7878144
06:18:47 53372 8192000000 10110993136 10571632640 1113596624 11.966 98 29 52 2 1180198128 60476080 6125424
06:20:47 139767 8192000000 10064916432 10531155968 1136893680 12.186 100 9 18 1 1166951840 28238432 1819728
06:24:32 64252 8192000000 10044033344 10513657856 1124027440 12.065 99 17 34 1 1171816496 44262224 3526832
06:26:37 60164 8192000000 10084168176 10561789952 1130931456 12.130 99 6 11 0 1175229008 43124752 1172800
06:28:38 79036 8192000000 10120991840 10578182144 1143674928 12.250 100 17 34 0 1196716704 49328880 3712896
06:30:57 84752 8192000000 10072141248 10559541248 1129751792 12.119 98 11 22 1 1173169264 41569600 1847872
06:33:24 44802 8192000000 10074260992 10521976832 1131558752 12.136 98 17 34 1 1167424448 31839872 4025824
06:35:09 102288 8192000000 10081894208 10581225472 1093937296 11.780 95 18 35 1 1136221568 38374048 3910224
06:37:54 44233 8192000000 10136343184 10581630976 1149480752 12.305 100 24 48 1 1225439360 71017408 4941200
06:39:39 93015 8192000000 10053231248 10477813760 1010927248 10.984 89 10 20 1 1031964704 19158048 1879408
06:42:12 113389 8192000000 10081976016 10520223744 1103180672 11.868 98 33 66 1 1180118880 70036800 6901408
06:45:10 60991 8192000000 10077686256 10555912192 1096422592 11.804 97 9 18 0 1146368848 47921888 2024368
06:47:13 52317 8192000000 10048353040 10558623744 1109818976 11.931 99 6 12 0 1162814368 51565680 1429712
06:49:06 54161 8192000000 10061893616 10515709952 1100188768 11.839 97 19 38 1 1143586000 39093424 4303808
06:51:02 71615 8192000000 10079283072 10546683904 1107590864 11.910 97 18 35 1 1184913968 72779280 4543824
06:53:18 59074 8192000000 10070094192 10531807232 1088640944 11.730 96 27 54 1 1160584768 65618288 6325536
06:55:17 75098 8192000000 10086658816 10546388992 1095008912 11.790 97 16 32 1 1137131680 38507792 3614976
06:57:44 66464 8192000000 10135404768 10593116160 1139321920 12.209 100 45 89 2 1208071072 59547872 9201280
07:00:29 59743 8192000000 10066424480 10549510144 1131198912 12.133 99 9 18 0 1177839872 44939568 1701392
07:03:01 49935 8192000000 10086672384 10543288320 1118107632 12.009 98 18 36 0 1159842112 38058768 3675712
07:04:51 42387 8192000000 10068898144 10528768000 1112977536 11.961 99 32 64 1 1182675408 63144480 6553392
07:06:39 72563 8192000000 10142452368 10606837760 1112897616 11.960 98 22 44 0 1214926320 97043488 4985216
07:09:37 62374 8192000000 10091671952 10592149504 1116284048 11.992 98 11 22 1 1166504992 48035584 2185360
07:11:39 54753 8192000000 10094351024 10563641344 1124153408 12.066 99 21 42 0 1176719920 48470896 4095616
07:13:40 37015 8192000000 10078951264 10523832320 1113569520 11.966 98 20 39 0 1144345296 26323744 4452032
07:15:17 46347 8192000000 10126914336 10606231552 1126612496 12.089 99 29 54 3 1220434832 87451216 6371120
07:17:05 65806 8192000000 10062501264 10559778816 1133323040 12.153 100 13 26 1 1198517056 62370336 2823680
07:19:11 63808 8192000000 10040342000 10495201280 1131810352 12.138 100 8 16 0 1157287024 23801712 1674960
07:21:15 77569 8192000000 10091986096 10557480960 1144329184 12.256 100 16 32 0 1186485488 38832640 3323664
07:23:43 35391 8192000000 10088402304 10538160128 1147051232 12.282 100 11 21 2 1174445280 25227104 2166944
07:25:19 36585 8192000000 10130851552 10585731072 1139809024 12.214 98 26 52 0 1203633088 58315808 5508256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-06-2021 07:28:38 to 10-06-2021 07:28:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-06-2021 07:28:53 to 10-06-2021 07:28:55.

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          219561
MON$OLDEST_ACTIVE               222479
MON$OLDEST_SNAPSHOT             222479
MON$NEXT_TRANSACTION            222479
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                       2064992
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:66: 
:67: mscs-test-161.budzdorov.ru    Thu Jun 10 07:27:11 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

10.06.21 07:28:56. 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-06-10 07:28:59 No severe PSQL-related problems occured
10.06.21 07:29:00. End of report.