Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2021 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15774488 kB
MemAvailable 30779324 kB
Buffers 0 kB
Cached 15936456 kB
SwapCached 2964 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-29 07:37:07
DTS_END                         2021-09-29 07:37:07.9130
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/60678
TRN_ID                          240516
ATT_ID                          690
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: 9243.89 15826 1109267
customer order: creation 179.76 358 21571
customer order: refuse 14.60 328 1752
order to supplier: creation 46.48 1502 5578
order to supplier: removal 3.38 100809 405
invoice (draft): creation 48.63 3374 5836
invoice (draft): removal 50.57 901 6068
invoice accept: apply 43.05 15985 5166
invoice accept: cancel 45.38 850 5446
customer reserve: creation 3979.74 156 477569
customer reserve: removal 4535.88 58 544306
realization accept: apply 86.12 184 10334
realization accept: cancel 77.73 112 9328
payment from customer: creation 61.86 127 7423
payment from customer: removal 6.08 121 729
payment to supplier: creation 50.38 142 6045
payment to supplier: removal 3.42 96 410
service: total inventory turnovers 3.88 3308 465
service: total monetary turnovers 5.98 536 718
service: aggregate perf. data 0.95 13676 114
service: refresh index statistics 0.03 173900 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2021 07:38:30 to 29-09-2021 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7302
TEST_TIME 2 5913
TEST_TIME 3 4532
TEST_TIME 4 4298
TEST_TIME 5 5156
TEST_TIME 6 4971
TEST_TIME 7 5125
TEST_TIME 8 5417
TEST_TIME 9 5831
TEST_TIME 10 6047
TEST_TIME 11 5979
TEST_TIME 12 5877
TEST_TIME 13 6382
TEST_TIME 14 6945
TEST_TIME 15 7314
TEST_TIME 16 7363
TEST_TIME 17 7488
TEST_TIME 18 7982
TEST_TIME 19 8488
TEST_TIME 20 8620
TEST_TIME 21 8749
TEST_TIME 22 9123
TEST_TIME 23 9508
TEST_TIME 24 10991
TEST_TIME 25 10822
TEST_TIME 26 10819
TEST_TIME 27 10958
TEST_TIME 28 11035
TEST_TIME 29 10840
TEST_TIME 30 10697
TEST_TIME 31 10518
TEST_TIME 32 10604
TEST_TIME 33 10641
TEST_TIME 34 10764
TEST_TIME 35 10878
TEST_TIME 36 10756
TEST_TIME 37 10765
TEST_TIME 38 10805
TEST_TIME 39 10796
TEST_TIME 40 10671
TEST_TIME 41 10482
TEST_TIME 42 10373
TEST_TIME 43 10378
TEST_TIME 44 10260
TEST_TIME 45 10257
TEST_TIME 46 10138
TEST_TIME 47 10145
TEST_TIME 48 10216
TEST_TIME 49 10169
TEST_TIME 50 10128
TEST_TIME 51 10005
TEST_TIME 52 9964
TEST_TIME 53 9874
TEST_TIME 54 9836
TEST_TIME 55 9702
TEST_TIME 56 9715
TEST_TIME 57 9647
TEST_TIME 58 9758
TEST_TIME 59 9850
TEST_TIME 60 9772
TEST_TIME 61 9879
TEST_TIME 62 9871
TEST_TIME 63 9934
TEST_TIME 64 9967
TEST_TIME 65 9948
TEST_TIME 66 10006
TEST_TIME 67 10003
TEST_TIME 68 10003
TEST_TIME 69 10001
TEST_TIME 70 10087
TEST_TIME 71 10032
TEST_TIME 72 9949
TEST_TIME 73 9838
TEST_TIME 74 9884
TEST_TIME 75 9815
TEST_TIME 76 9750
TEST_TIME 77 9701
TEST_TIME 78 9604
TEST_TIME 79 9606
TEST_TIME 80 9523
TEST_TIME 81 9451
TEST_TIME 82 9433
TEST_TIME 83 9568
TEST_TIME 84 9598
TEST_TIME 85 9616
TEST_TIME 86 9597
TEST_TIME 87 9580
TEST_TIME 88 9662
TEST_TIME 89 9640
TEST_TIME 90 9618
TEST_TIME 91 9631
TEST_TIME 92 9715
TEST_TIME 93 9635
TEST_TIME 94 9559
TEST_TIME 95 9512
TEST_TIME 96 9441
TEST_TIME 97 9411
TEST_TIME 98 9485
TEST_TIME 99 9435
TEST_TIME 100 9412
TEST_TIME 101 9408
TEST_TIME 102 9415
TEST_TIME 103 9470
TEST_TIME 104 9485
TEST_TIME 105 9479
TEST_TIME 106 9480
TEST_TIME 107 9511
TEST_TIME 108 9501
TEST_TIME 109 9455
TEST_TIME 110 9588
TEST_TIME 111 9508
TEST_TIME 112 9447
TEST_TIME 113 9443
TEST_TIME 114 9413
TEST_TIME 115 9362
TEST_TIME 116 9334
TEST_TIME 117 9325
TEST_TIME 118 9308
TEST_TIME 119 9318
TEST_TIME 120 9244

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2021 07:38:33 to 29-09-2021 07:38:37.

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 1550554 1550554 0 0.00 0 30899 6 0 0
doc_list_biud 1550553 1550553 0 0.00 0 24621 27 0 0
sp_add_invoice_to_stock 5283 5166 117 2.21 43 189120 15986 0 117
sp_cancel_adding_invoice 5446 5446 0 0.00 5 84211 850 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 14141 328 0 0
sp_cancel_customer_reserve 544306 544306 0 0.00 1 17091 59 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 7435 122 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6769 96 0 0
sp_cancel_supplier_invoice 6072 6068 4 0.06 2 19605 901 0 4
sp_cancel_supplier_order 405 405 0 0.00 11 1181675 100810 0 0
sp_cancel_write_off 9328 9328 0 0.00 1 9861 112 0 0
sp_client_order 21571 21571 0 0.00 3 19580 359 0 0
sp_customer_reserve 477569 477569 0 0.00 0 47799 157 0 0
sp_fill_shopping_cart 32977 32977 0 0.00 1 16493 44 0 0
sp_fill_shopping_cart_clo_res 477568 477568 0 0.00 0 18284 15 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 16782 312 0 0
sp_kill_cost_storno 15913 15913 0 0.00 0 13049 56 0 0
sp_kill_qstorno_ret_qs2qd 1104413 1104413 0 0.00 0 19600 32 0 0
sp_kill_qty_storno 582804 582804 0 0.00 0 24621 70 0 0
sp_lock_selected_doc 21381 21381 0 0.00 0 7429 6 0 0
sp_make_cost_storno 28967 28967 0 0.00 0 30830 62 0 0
sp_multiply_rows_for_pdistr 28967 28967 0 0.00 0 28652 32 0 0
sp_multiply_rows_for_qdistr 21570 21570 0 0.00 0 19369 186 0 0
sp_pay_from_customer 7423 7423 0 0.00 4 29914 127 0 0
sp_pay_to_supplier 6045 6045 0 0.00 5 18456 142 0 0
sp_payment_common 13468 13468 0 0.00 1 15439 27 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 14134 298 0 0
sp_qd_handle_on_invoice_upd_sts 10611 10611 0 0.00 0 24619 332 0 0
sp_qd_handle_on_reserve_upd_sts 10334 10334 0 0.00 0 19623 54 0 0
sp_reserve_write_off 10334 10334 0 0.00 6 19722 184 0 0
sp_supplier_invoice 5836 5836 0 0.00 7 77138 3374 0 0
sp_supplier_order 5578 5578 0 0.00 6 39961 1502 0 0
srv_aggregate_perf_data 114 114 0 0.00 2691 190732 13676 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2421 122594 45715 0 0
srv_find_qd_qs_mism 1059380 1059380 0 0.00 0 12742 5 0 0
srv_make_invnt_saldo 465 465 0 0.00 39 86168 3309 0 0
srv_make_money_saldo 718 718 0 0.00 1 17522 536 0 0
srv_recalc_idx_stat 4 4 0 0.00 118962 277751 173901 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 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 5166 5166 0 0.00 0 12880 137 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 4490 102 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 6114 18 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 4845 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 21821 40 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1346 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 6076 31 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25022 1382 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 5619 17 0 0
t$perf-norm:sp_client_order 21571 21571 0 0.00 0 8108 17 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 628 4 0 0
t$perf-norm:sp_pay_from_customer 7423 7423 0 0.00 0 4591 8 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 6742 12 0 0
t$perf-norm:sp_reserve_write_off 10334 10334 0 0.00 0 12615 14 0 0
t$perf-norm:sp_supplier_invoice 5836 5836 0 0.00 0 23513 22 0 0
t$perf-norm:sp_supplier_order 5578 5578 0 0.00 0 6876 15 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 244 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1870 65 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 454 6 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 839 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 6 4 0 0
v_add_invoice_to_stock 10564 10330 234 2.21 0 6807 19 0 234
v_all_customers 39124 39124 0 0.00 0 34 1 0 0
v_all_suppliers 31910 31910 0 0.00 0 52 1 0 0
v_all_wares 43136 43136 0 0.00 0 266 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 984 7 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3440 5 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 4434 11 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 2807 9 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3114 11 0 0
v_cancel_supplier_invoice 848 840 8 0.94 0 3796 19 0 8
v_cancel_supplier_order 810 810 0 0.00 0 550 5 0 0
v_cancel_write_off 2066 2066 0 0.00 0 6887 19 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 7726 3 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 1546 8 0 0
v_max_id_ord_sup 5833 5833 0 0.00 0 3588 3 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 1495 3 0 0
v_max_non_paid_realizn 4144 4144 0 0.00 0 2064 3 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 9362 10 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 6484 11 0 0
v_min_id_ord_sup 5833 5833 0 0.00 0 6417 10 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 15435 22 0 0
v_min_non_paid_realizn 7423 7423 0 0.00 0 6594 17 0 0
v_our_firm 10570 10570 0 0.00 0 18 1 0 0
v_reserve_write_off 20668 20668 0 0.00 0 4441 6 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 16996 104 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 27314 384 0 0
x_make_qstorno_1000_1200 5578 5578 0 0.00 2 39954 1421 0 0
x_make_qstorno_1200_2000 5836 5836 0 0.00 2 77132 3208 0 0
x_make_qstorno_2100_3300 477569 477569 0 0.00 0 47798 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2021 07:38:38 to 29-09-2021 07:38:42.

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
05:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 35426 8192000000 9729447504 10025390080 796645840 8.862 100 82 164 0 823729360 7232960 19850560
05:09:50 20578 8192000000 9757794832 10062614528 832155424 9.221 100 87 174 1 865508576 12219168 21133984
05:11:14 17019 8192000000 9809397712 10126303232 869836720 9.598 100 81 162 0 910850480 21682384 19331376
05:12:34 11533 8192000000 9847837584 10184085504 907760320 9.975 100 72 144 0 960961136 36839184 16361632
05:13:49 9903 8192000000 9920874592 10265784320 966444944 10.552 100 80 160 0 1034410960 51208976 16757040
05:15:03 5035 8192000000 9963452544 10317426688 996669728 10.846 100 76 152 0 1081060160 68911280 15479152
05:16:11 3354 8192000000 9992537936 10353410048 1020165072 11.074 100 79 157 1 1109855312 73877424 15812816
05:17:24 1945 8192000000 10013831744 10377232384 1043782672 11.301 100 88 176 2 1122818592 61530240 17505680
05:18:28 4228 8192000000 10022472208 10393026560 1058750704 11.445 100 90 178 1 1140395664 63212448 18432512
05:19:36 13797 8192000000 9972246448 10375757824 1067895136 11.532 100 53 105 0 1122629392 43705072 11029184
05:20:51 7728 8192000000 9969222848 10372571136 1068137056 11.534 100 26 52 1 1101880432 28576672 5166704
05:21:59 10879 8192000000 9981036752 10385387520 1072677952 11.578 100 30 60 1 1102893776 23851984 6363840
05:23:11 5189 8192000000 9993046496 10382778368 1083672944 11.682 100 41 83 1 1104215264 11616416 8925904
05:24:16 9838 8192000000 9995086656 10395877376 1086425120 11.709 100 35 70 2 1136750944 43172992 7152832
05:25:29 9537 8192000000 10019928752 10413481984 1090139648 11.744 100 56 109 2 1123290160 21269744 11880768
05:26:39 4820 8192000000 10025194592 10421354496 1068845200 11.541 98 48 96 3 1112237424 32834576 10557648
05:27:46 7427 8192000000 9990617520 10390052864 1054048384 11.399 97 33 63 3 1088116800 26762624 7305792
05:28:56 10827 8192000000 9941422688 10328203264 987936976 10.761 93 18 36 0 1006355792 14458960 3959856
05:30:07 22557 8192000000 9957514608 10353303552 1007499232 10.951 97 53 106 0 1037641888 18423312 11719344
05:31:43 7153 8192000000 9939890336 10327343104 1022889856 11.100 100 41 80 2 1048065984 16431024 8745104
05:32:51 8173 8192000000 9954374256 10343223296 1035024752 11.217 100 34 69 2 1063462784 21508240 6929792
05:34:00 11762 8192000000 9951667808 10351304704 1039858240 11.263 99 51 106 1 1074805856 22932784 12014832
05:35:15 12597 8192000000 9980840448 10387955712 1053394160 11.393 100 30 59 4 1086097472 26375424 6327888
05:36:28 6638 8192000000 9979837616 10384433152 1066004128 11.514 100 47 91 2 1090947424 13914848 11028448
05:37:38 15425 8192000000 10021225392 10411425792 1089312528 11.736 100 60 118 2 1117457104 15831184 12313392
05:38:57 8163 8192000000 10067866800 10472263680 1112113952 11.952 100 51 103 0 1184480736 62284256 10082528
05:40:09 55886 8192000000 10098264080 10512072704 1114545136 11.975 100 38 76 1 1199120976 76271280 8304560
05:42:40 25035 8192000000 10088986320 10511196160 1130311872 12.124 100 30 60 1 1210375936 73737728 6326336
05:44:09 7761 8192000000 10177294320 10583027712 1147770480 12.289 100 57 112 2 1277446272 118101600 11574192
05:45:21 6700 8192000000 10189818160 10610991104 1145174368 12.264 100 50 99 0 1299504048 143289728 11039952
05:46:35 8726 8192000000 10219291424 10632548352 1166032656 12.460 100 65 127 3 1324908192 145255712 13619824
05:47:48 4302 8192000000 10261118208 10681958400 1165072944 12.451 100 66 131 0 1370564768 190600896 14890928
05:48:57 6304 8192000000 10290205632 10690555904 1151352576 12.322 99 66 131 1 1385340224 218531328 15456320
05:50:14 3239 8192000000 10333022416 10747875328 1150406032 12.313 99 90 175 4 1436813760 266722672 19685056
05:51:25 3101 8192000000 10339835856 10747994112 1181073920 12.600 100 84 168 1 1442496160 242313040 19109200
05:52:39 2419 8192000000 10352002592 10767921152 1160794864 12.411 100 64 127 1 1452353664 276176240 15382560
05:53:46 2430 8192000000 10390382000 10794385408 1167509248 12.474 100 91 179 0 1483177088 295029360 20638480
05:54:52 6559 8192000000 10385014768 10817691648 1160329152 12.406 100 54 108 2 1500834960 326672656 13833152
05:56:04 3221 8192000000 10349012624 10781839360 1118117920 12.009 98 63 120 4 1437285264 304580656 14586688
05:57:12 17382 8192000000 10350285824 10784796672 1127498112 12.098 99 33 64 1 1441808768 306029840 8280816
05:58:38 16008 8192000000 10353800528 10784440320 1130963200 12.130 100 25 51 1 1450886064 312993024 6929840
05:59:55 29892 8192000000 10295155040 10737065984 1096448816 11.804 99 22 44 1 1409876768 307289376 6138576
06:01:29 13826 8192000000 10146328512 10690998272 1115823584 11.988 100 22 44 1 1361057536 239728512 5505440
06:03:15 30577 8192000000 10080234736 10535215104 1108752672 11.921 100 27 54 0 1194799872 79945472 6101728
06:05:01 70650 8192000000 10049110960 10520358912 1091543840 11.757 98 4 8 0 1136581968 44206704 831424
06:07:12 48758 8192000000 10045619504 10464538624 1089931280 11.742 97 26 52 0 1109767744 15104976 4731488
06:09:08 61429 8192000000 10110680992 10546368512 1117774080 12.006 98 18 34 0 1205987200 84220208 3992912
06:11:10 104287 8192000000 10104066480 10615341056 1122395040 12.050 99 8 16 1 1211157328 87552752 1209536
06:13:55 122593 8192000000 10109721184 10539626496 1118338608 12.011 97 18 36 3 1161308432 39240320 3729504
06:18:54 56161 8192000000 10110065248 10540982272 1150802016 12.317 100 52 101 0 1205079792 43024544 11253232
06:20:53 48097 8192000000 10097503152 10543697920 1151801184 12.326 100 16 32 0 1196321520 41356352 3163984
06:22:44 32610 8192000000 10115327296 10542354432 1109137008 11.924 95 36 72 1 1177901184 60465472 8298704
06:24:20 19812 8192000000 10122593872 10553757696 1143201456 12.246 99 45 89 2 1213605632 60853824 9550352
06:25:40 61776 8192000000 10094012720 10563448832 1158268992 12.387 100 18 36 0 1221305120 58461232 4574896
06:28:24 52053 8192000000 10098715056 10551779328 1144135968 12.254 99 12 24 0 1189940384 42557408 3247008
06:30:42 51182 8192000000 10095595456 10540306432 1120818928 12.035 97 14 28 0 1162278224 38509632 2949664
06:32:35 50683 8192000000 10101509152 10575785984 1134381952 12.163 99 17 34 0 1208377312 69572832 4422528
06:34:34 35639 8192000000 10113877728 10583748608 1135651424 12.175 99 19 38 1 1227882000 87726528 4504048
06:36:14 57699 8192000000 10077927360 10520559616 1132228000 12.142 99 17 31 3 1171670176 36601216 2840960
06:38:39 48818 8192000000 10094221696 10531532800 1099613056 11.834 97 21 42 1 1165173152 61464096 4096000
06:40:30 54282 8192000000 10087411072 10538815488 1095044544 11.791 97 30 60 0 1151022032 49358304 6619184
06:42:24 96497 8192000000 10065140128 10533302272 1049361728 11.355 94 14 28 1 1116187776 64136880 2689168
06:45:01 72911 8192000000 10065824736 10503884800 1057715920 11.435 95 28 55 0 1119271664 55658128 5897616
06:47:20 46625 8192000000 10050797664 10558402560 1075753600 11.607 97 20 40 1 1146943568 66740336 4449632
06:49:08 108813 8192000000 10069789296 10514096128 1091729200 11.759 99 37 74 0 1168889904 69148912 8011792
06:52:01 55738 8192000000 10020876640 10488131584 1076764912 11.617 97 9 18 0 1100037312 20794992 2477408
06:53:57 38932 8192000000 10038350112 10484363264 1086371808 11.708 98 11 22 0 1113381584 24464624 2545152
06:55:38 98414 8192000000 10077958336 10567643136 1118289696 12.011 100 12 24 1 1185021280 64029728 2701856
06:58:24 39848 8192000000 10156319568 10606100480 1131203728 12.133 100 19 34 1 1239811664 104650816 3957120
07:00:17 89877 8192000000 10083558000 10533281792 1118904384 12.017 99 24 48 1 1229001664 104947568 5149712
07:02:58 64228 8192000000 10091480896 10526191616 1075181760 11.602 95 28 56 1 1140003200 59220720 5600720
07:05:03 75786 8192000000 10065397968 10526715904 1136738288 12.185 100 11 22 0 1188576608 50005040 1833280
07:07:19 26082 8192000000 10122847344 10558533632 1143335376 12.247 100 38 76 2 1193588864 42961232 7292256
07:08:46 29906 8192000000 10137415136 10570686464 1137449488 12.192 99 42 84 0 1216246576 70096448 8700640
07:10:20 83179 8192000000 10058696864 10508161024 1136682128 12.184 100 12 24 0 1181266512 42304272 2280112
07:12:45 68493 8192000000 10128632560 10591453184 1140086416 12.216 100 11 22 0 1197081968 53766752 3228800
07:14:55 74730 8192000000 10073021744 10645741568 1140499280 12.220 100 7 14 1 1228105584 85706544 1899760
07:17:33 59890 8192000000 10090194464 10563997696 1124166688 12.066 98 21 42 1 1192767232 63943936 4656608
07:19:36 73117 8192000000 10102953616 10559959040 1113191408 11.963 97 12 25 0 1186547504 70833984 2522112
07:22:31 42182 8192000000 10082772512 10512564224 1100929712 11.846 97 48 96 3 1154487056 43548480 10008864
07:24:15 81528 8192000000 10071861200 10528894976 1077890176 11.627 96 13 26 0 1138704544 58180560 2633808
07:26:37 35496 8192000000 10176124448 10627936256 1116384192 11.993 99 30 60 0 1240628624 117922320 6322112
07:29:11 63518 8192000000 10074188912 10516832256 1112341568 11.955 99 38 76 2 1156960832 36590496 8028768
07:31:17 63496 8192000000 10061723680 10521776128 1093313168 11.774 97 12 25 0 1132335904 37055808 1966928
07:35:28 55785 8192000000 10050402704 10536992768 1053820416 11.397 95 24 47 0 1143363952 83781312 5762224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2021 07:38:43 to 29-09-2021 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 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 29-09-2021 07:38:59 to 29-09-2021 07:39:01.

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          240515
MON$OLDEST_ACTIVE               241883
MON$OLDEST_SNAPSHOT             241883
MON$NEXT_TRANSACTION            241883
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                       2065312
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             818
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:242: 
:243: mscs-test-161.budzdorov.ru    Wed Sep 29 07:37:10 2021
:244:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:245: 
:246: 
:247: mscs-test-161.budzdorov.ru    Wed Sep 29 07:37:13 2021
:248:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:249: 
:250: 
:251: mscs-test-161.budzdorov.ru    Wed Sep 29 07:37:14 2021
:252:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:253: 
--- end of diff output ---

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

29.09.21 07:39:02. 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-29 07:39:05 No severe PSQL-related problems occured
29.09.21 07:39:06. End of report.