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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16075308 kB
MemAvailable 30779924 kB
Buffers 0 kB
Cached 15623500 kB
SwapCached 2456 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-11-10 13:37:09
DTS_END                         2021-11-10 13:37:09.4910
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/41152
TRN_ID                          235007
ATT_ID                          614
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8921.38 19545 1070566
customer order: creation 170.84 423 20501
customer order: refuse 14.67 318 1760
order to supplier: creation 45.43 1393 5452
order to supplier: removal 3.38 101432 406
invoice (draft): creation 45.93 3290 5512
invoice (draft): removal 51.30 871 6156
invoice accept: apply 40.68 18983 4882
invoice accept: cancel 46.08 845 5530
customer reserve: creation 3701.96 192 444235
customer reserve: removal 4514.14 59 541697
realization accept: apply 82.31 201 9877
realization accept: cancel 76.50 127 9180
payment from customer: creation 59.23 126 7107
payment from customer: removal 5.97 72 716
payment to supplier: creation 48.38 156 5806
payment to supplier: removal 3.34 69 401
service: total inventory turnovers 4.17 3027 500
service: total monetary turnovers 6.09 468 731
service: aggregate perf. data 0.94 15366 113
service: refresh index statistics 0.03 243483 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 13:38:20 to 10-11-2021 13:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6463
TEST_TIME 2 5094
TEST_TIME 3 4817
TEST_TIME 4 4251
TEST_TIME 5 4665
TEST_TIME 6 4647
TEST_TIME 7 4979
TEST_TIME 8 5367
TEST_TIME 9 6072
TEST_TIME 10 6384
TEST_TIME 11 6318
TEST_TIME 12 6396
TEST_TIME 13 6561
TEST_TIME 14 7195
TEST_TIME 15 7224
TEST_TIME 16 7756
TEST_TIME 17 8013
TEST_TIME 18 8450
TEST_TIME 19 9072
TEST_TIME 20 9178
TEST_TIME 21 9201
TEST_TIME 22 8896
TEST_TIME 23 9270
TEST_TIME 24 9729
TEST_TIME 25 10688
TEST_TIME 26 10746
TEST_TIME 27 10774
TEST_TIME 28 10707
TEST_TIME 29 10431
TEST_TIME 30 10267
TEST_TIME 31 9941
TEST_TIME 32 10326
TEST_TIME 33 10159
TEST_TIME 34 10087
TEST_TIME 35 10049
TEST_TIME 36 9916
TEST_TIME 37 9873
TEST_TIME 38 9832
TEST_TIME 39 9972
TEST_TIME 40 9861
TEST_TIME 41 9959
TEST_TIME 42 9893
TEST_TIME 43 9875
TEST_TIME 44 9807
TEST_TIME 45 9824
TEST_TIME 46 9903
TEST_TIME 47 9842
TEST_TIME 48 9833
TEST_TIME 49 9929
TEST_TIME 50 9929
TEST_TIME 51 9775
TEST_TIME 52 9747
TEST_TIME 53 9689
TEST_TIME 54 9611
TEST_TIME 55 9678
TEST_TIME 56 9587
TEST_TIME 57 9529
TEST_TIME 58 9500
TEST_TIME 59 9390
TEST_TIME 60 9370
TEST_TIME 61 9408
TEST_TIME 62 9378
TEST_TIME 63 9345
TEST_TIME 64 9341
TEST_TIME 65 9453
TEST_TIME 66 9464
TEST_TIME 67 9447
TEST_TIME 68 9418
TEST_TIME 69 9492
TEST_TIME 70 9551
TEST_TIME 71 9551
TEST_TIME 72 9612
TEST_TIME 73 9633
TEST_TIME 74 9611
TEST_TIME 75 9508
TEST_TIME 76 9430
TEST_TIME 77 9494
TEST_TIME 78 9449
TEST_TIME 79 9372
TEST_TIME 80 9328
TEST_TIME 81 9243
TEST_TIME 82 9228
TEST_TIME 83 9233
TEST_TIME 84 9226
TEST_TIME 85 9253
TEST_TIME 86 9231
TEST_TIME 87 9245
TEST_TIME 88 9229
TEST_TIME 89 9231
TEST_TIME 90 9268
TEST_TIME 91 9229
TEST_TIME 92 9265
TEST_TIME 93 9249
TEST_TIME 94 9262
TEST_TIME 95 9239
TEST_TIME 96 9192
TEST_TIME 97 9175
TEST_TIME 98 9123
TEST_TIME 99 9067
TEST_TIME 100 9059
TEST_TIME 101 9064
TEST_TIME 102 9046
TEST_TIME 103 9050
TEST_TIME 104 9054
TEST_TIME 105 9045
TEST_TIME 106 9055
TEST_TIME 107 9035
TEST_TIME 108 9053
TEST_TIME 109 9052
TEST_TIME 110 9088
TEST_TIME 111 9147
TEST_TIME 112 9148
TEST_TIME 113 9080
TEST_TIME 114 9042
TEST_TIME 115 9005
TEST_TIME 116 8994
TEST_TIME 117 9001
TEST_TIME 118 8989
TEST_TIME 119 8985
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-11-2021 13:38:23 to 10-11-2021 13:38:27.

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 1483106 1483106 0 0.00 0 23963 7 0 0
doc_list_biud 1483106 1483106 0 0.00 0 90420 28 0 0
sp_add_invoice_to_stock 5018 4882 136 2.71 34 230378 18983 0 136
sp_cancel_adding_invoice 5530 5530 0 0.00 4 51235 845 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 14374 319 0 0
sp_cancel_customer_reserve 541697 541697 0 0.00 1 90420 60 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 6294 73 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 2792 69 0 0
sp_cancel_supplier_invoice 6171 6156 15 0.24 1 51164 872 0 15
sp_cancel_supplier_order 406 406 0 0.00 7 916527 101432 0 0
sp_cancel_write_off 9180 9180 0 0.00 1 24270 128 0 0
sp_client_order 20501 20501 0 0.00 4 27514 423 0 0
sp_customer_reserve 444235 444235 0 0.00 0 69842 192 0 0
sp_fill_shopping_cart 31435 31435 0 0.00 1 11235 39 0 0
sp_fill_shopping_cart_clo_res 444232 444232 0 0.00 0 18449 17 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 25959 358 0 0
sp_kill_cost_storno 15827 15827 0 0.00 0 14913 54 0 0
sp_kill_qstorno_ret_qs2qd 1099136 1099136 0 0.00 0 81735 33 0 0
sp_kill_qty_storno 579485 579485 0 0.00 0 90419 71 0 0
sp_lock_selected_doc 20505 20505 0 0.00 0 7712 6 0 0
sp_make_cost_storno 27669 27669 0 0.00 0 22325 71 0 0
sp_multiply_rows_for_pdistr 27669 27669 0 0.00 0 9195 30 0 0
sp_multiply_rows_for_qdistr 20501 20501 0 0.00 0 11008 210 0 0
sp_pay_from_customer 7107 7107 0 0.00 4 16627 126 0 0
sp_pay_to_supplier 5806 5806 0 0.00 5 25954 157 0 0
sp_payment_common 12913 12913 0 0.00 1 16759 28 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 14364 289 0 0
sp_qd_handle_on_invoice_upd_sts 10409 10409 0 0.00 0 43063 340 0 0
sp_qd_handle_on_reserve_upd_sts 9877 9877 0 0.00 0 24739 62 0 0
sp_reserve_write_off 9877 9877 0 0.00 7 24797 201 0 0
sp_supplier_invoice 5512 5512 0 0.00 8 138655 3291 0 0
sp_supplier_order 5452 5452 0 0.00 5 57775 1393 0 0
srv_aggregate_perf_data 113 113 0 0.00 2924 200604 15366 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1199 135863 48277 0 0
srv_find_qd_qs_mism 1024295 1024295 0 0.00 0 19587 7 0 0
srv_make_invnt_saldo 500 500 0 0.00 64 91592 3027 0 0
srv_make_money_saldo 731 731 0 0.00 0 13017 468 0 0
srv_recalc_idx_stat 4 4 0 0.00 109449 481686 243484 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4882 4882 0 0.00 0 15110 164 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 9682 112 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 1500 9 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 3080 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 9894 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 306 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 6253 30 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21178 1421 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 3262 11 0 0
t$perf-norm:sp_client_order 20501 20501 0 0.00 0 6582 17 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 4551 10 0 0
t$perf-norm:sp_pay_from_customer 7107 7107 0 0.00 0 7444 12 0 0
t$perf-norm:sp_pay_to_supplier 5806 5806 0 0.00 0 24637 17 0 0
t$perf-norm:sp_reserve_write_off 9877 9877 0 0.00 0 6173 10 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 9189 23 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 5563 17 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 674 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 4687 22 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 524 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10030 9758 272 2.71 0 3868 16 0 272
v_all_customers 37612 37612 0 0.00 0 27 1 0 0
v_all_suppliers 29990 29990 0 0.00 0 18 1 0 0
v_all_wares 41002 41002 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2112 12 0 0
v_cancel_client_order 3520 3520 0 0.00 0 4982 7 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 940 4 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2561 11 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 645 4 0 0
v_cancel_supplier_invoice 760 730 30 3.94 0 3256 22 0 30
v_cancel_supplier_order 812 812 0 0.00 0 5157 14 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2269 15 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 141 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 6122 28 0 0
v_max_id_ord_sup 5494 5494 0 0.00 0 4180 5 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 11139 10 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 11122 8 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 2500 5 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 7732 21 0 0
v_min_id_ord_sup 5494 5494 0 0.00 0 5312 10 0 0
v_min_non_paid_invoice 5806 5806 0 0.00 0 7781 20 0 0
v_min_non_paid_realizn 7107 7107 0 0.00 0 7908 18 0 0
v_our_firm 10208 10208 0 0.00 0 19 1 0 0
v_reserve_write_off 19754 19754 0 0.00 0 5010 8 0 0
x_lock_depdocs_on_canc_invoice 11321 11321 0 0.00 0 13397 104 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 8260 359 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 57760 1318 0 0
x_make_qstorno_1200_2000 5512 5512 0 0.00 4 137587 3151 0 0
x_make_qstorno_2100_3300 444235 444235 0 0.00 0 69837 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 13:38:28 to 10-11-2021 13:38:32.

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:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 35427 8192000000 9710333792 10006339584 784846384 8.743 100 89 178 0 815136544 7908016 22382144
11:09:51 14114 8192000000 9755182400 10058113024 833486384 9.234 100 91 182 0 868512976 13334960 21691632
11:11:14 13434 8192000000 9788580080 10111504384 859867728 9.499 100 78 156 1 903379280 25834640 17676912
11:12:31 8839 8192000000 9864010624 10194206720 911791840 10.015 100 79 158 0 971133824 41940208 17401776
11:13:43 8592 8192000000 9919082800 10262740992 956875104 10.458 100 83 165 0 1025391056 51549136 16966816
11:14:55 6158 8192000000 9952341312 10307346432 995695792 10.837 100 75 148 1 1063873504 53428400 14749312
11:16:03 3017 8192000000 9983119584 10342461440 1023707024 11.108 100 83 165 2 1098817280 58094160 17016096
11:17:08 2340 8192000000 10012074704 10378788864 1042259504 11.286 100 86 172 2 1121377008 61855168 17262336
11:18:11 1388 8192000000 10052502976 10422988800 1064732784 11.502 100 91 182 1 1160583232 76970144 18880304
11:19:14 1551 8192000000 10006896560 10390675456 1072473536 11.576 100 63 126 0 1129490864 44510224 12507104
11:20:17 6421 8192000000 9994077248 10394144768 1077685632 11.625 100 37 75 1 1125699008 40436752 7576624
11:21:25 4358 8192000000 10002130960 10392850432 1082841344 11.675 100 35 70 1 1113667488 23278816 7547328
11:22:32 31067 8192000000 10002698816 10391621632 1085103856 11.696 100 69 143 0 1110349744 10124912 15120976
11:24:04 6146 8192000000 10011127168 10410852352 1088681520 11.730 100 38 73 0 1123809440 27470224 7657696
11:25:10 6619 8192000000 10011887472 10413518848 1097312560 11.812 100 46 92 0 1116971520 8760928 10898032
11:26:20 15263 8192000000 9999521968 10398760960 1047121776 11.333 96 34 68 1 1069114480 13953376 8039328
11:27:37 5376 8192000000 9994767904 10376564736 1048325136 11.345 96 66 133 1 1076108784 12978848 14804800
11:28:43 3765 8192000000 9963872400 10352451584 1053764816 11.397 100 41 81 1 1082930656 20807840 8358000
11:29:48 9251 8192000000 9974034496 10365034496 1021610848 11.088 97 31 62 0 1044835584 15702240 7522496
11:30:58 68482 8192000000 9968932096 10363326464 991963424 10.801 96 30 60 1 1022640608 24229584 6447600
11:33:07 8551 8192000000 9958957712 10348662784 1030790160 11.176 99 50 99 2 1059915728 16284032 12841536
11:34:17 5191 8192000000 9986168336 10381447168 1065455120 11.509 100 53 107 1 1100977088 23413200 12108768
11:35:22 66204 8192000000 9995312608 10407497728 1071668464 11.568 100 32 64 0 1106412304 27878416 6865424
11:37:36 12265 8192000000 10014508320 10412437504 1099639376 11.834 100 44 88 0 1121068688 11730720 9698592
11:38:58 34129 8192000000 10078564464 10481967104 1124365328 12.068 100 56 112 1 1180671600 45235872 11070400
11:40:35 55296 8192000000 10146768976 10556338176 1145370256 12.266 100 38 76 1 1241943840 88000656 8572928
11:42:36 24910 8192000000 10174633376 10601033728 1149150032 12.302 100 43 86 0 1299054864 140475872 9428960
11:44:08 14222 8192000000 10236630160 10649026560 1167918512 12.477 100 47 94 0 1339255632 160376160 10960960
11:45:27 3571 8192000000 10253022992 10677735424 1170061536 12.497 100 61 121 0 1363167936 179904992 13201408
11:46:38 11051 8192000000 10235019552 10663346176 1169370768 12.491 99 42 84 0 1339323648 160201824 9751056
11:47:54 3678 8192000000 10238136496 10646683648 1164740736 12.448 99 56 109 1 1344016016 167411616 11863664
11:49:00 6600 8192000000 10282973856 10710519808 1183704896 12.625 100 42 83 0 1396281200 202273968 10302336
11:50:13 1371 8192000000 10314924768 10718875648 1170573264 12.502 100 84 168 2 1413728000 224362528 18792208
11:51:17 1194 8192000000 10332530608 10729713664 1169922720 12.496 100 95 187 1 1429120368 237357808 21839840
11:52:23 1461 8192000000 10339883872 10748121088 1165546976 12.455 99 85 170 0 1437275984 252714352 19014656
11:53:29 7268 8192000000 10419767376 10834857984 1175143184 12.545 100 62 124 0 1517160880 327420976 14596720
11:54:43 15138 8192000000 10375240544 10811838464 1154208928 12.349 100 38 75 1 1484673952 320667744 9797280
11:56:02 11655 8192000000 10326975600 10777767936 1155021008 12.357 99 37 74 1 1453255840 287979664 10255168
11:57:20 2143 8192000000 10324385904 10736041984 1149280608 12.303 99 71 141 2 1409902176 243886112 16735456
11:58:26 28812 8192000000 10299752624 10728812544 1118186736 12.010 98 28 56 2 1380636032 255705712 6743584
12:00:24 28312 8192000000 10343626336 10794053632 1135074656 12.169 100 18 36 0 1448893296 309139072 4679568
12:01:53 24926 8192000000 10241173376 10677383168 1130311936 12.124 100 25 50 0 1369002768 232021232 6669600
12:03:19 30199 8192000000 10083568720 10492276736 1100574880 11.843 98 37 71 3 1170156832 62107472 7474480
12:04:57 58695 8192000000 10129131376 10589458432 1135639872 12.174 100 22 43 1 1241370064 101151360 4578832
12:06:57 135862 8192000000 10158831504 10590384128 1103094768 11.867 98 45 89 1 1240799616 128011840 9693008
12:10:15 21606 8192000000 10069658320 10499764224 1126428480 12.088 100 36 70 0 1173989408 39907136 7653792
12:11:41 34392 8192000000 10085110864 10568003584 1132981840 12.149 100 12 24 0 1199663968 64484272 2197856
12:13:17 108374 8192000000 10095842320 10561716224 1090656960 11.749 96 17 34 0 1175158528 80995120 3506448
12:16:15 49942 8192000000 10052868048 10488057856 1117568416 12.004 99 27 55 1 1160161936 36835616 5757904
12:18:11 51673 8192000000 10060284752 10517389312 1129993328 12.121 100 26 50 1 1196341888 61077680 5270880
12:20:04 62877 8192000000 10103953104 10556743680 1144914576 12.262 100 24 48 0 1190370336 40756736 4699024
12:22:16 72113 8192000000 10095916320 10552475648 1108940272 11.922 97 22 44 0 1166872352 53308144 4623936
12:24:39 67040 8192000000 10137394784 10593632256 1121367504 12.040 98 28 56 1 1180813344 53974224 5471616
12:26:58 72036 8192000000 10118790144 10589683712 1139068736 12.207 99 20 40 2 1222778704 78694592 5015376
12:30:13 47468 8192000000 10081561440 10532683776 1115616384 11.986 97 21 42 0 1157968976 38562336 3790256
12:32:01 42768 8192000000 10080921616 10584653824 1132640096 12.146 99 17 34 0 1210123088 73331936 4151056
12:33:51 18023 8192000000 10074176736 10511970304 1126828256 12.091 99 22 43 0 1164553888 33418592 4307040
12:35:16 56354 8192000000 10098515136 10560094208 1138391968 12.200 100 20 40 2 1210053696 66720400 4941328
12:37:12 51304 8192000000 10072256416 10590445568 1138875872 12.205 100 16 32 0 1205975488 64108736 2990880
12:39:13 56828 8192000000 10066989248 10499870720 1097163632 11.811 96 15 30 0 1120438896 20654112 2621152
12:41:10 72273 8192000000 10088561952 10580176896 1126535600 12.089 99 15 31 0 1200941744 70911856 3494288
12:43:23 26052 8192000000 10097407360 10546176000 1140180352 12.217 99 38 77 0 1178567552 29844416 8542784
12:44:52 52095 8192000000 10126367056 10566565888 1112831712 11.959 96 24 48 0 1193565424 75437760 5295952
12:46:48 93806 8192000000 10077288000 10535362560 1103121888 11.867 97 15 29 1 1184287424 78181168 2984368
12:49:29 124425 8192000000 10037503424 10494873600 1045283248 11.315 93 12 24 0 1137828976 90268480 2277248
12:52:37 121574 8192000000 10092918848 10587553792 1111443376 11.946 99 30 60 0 1191984512 72996384 7544752
12:55:39 45461 8192000000 10020192608 10465525760 1076133696 11.611 96 14 28 0 1105743648 26519840 3090112
12:57:27 54509 8192000000 10060273328 10529628160 1108740528 11.920 99 9 18 0 1151703024 40925920 2036576
12:59:23 47152 8192000000 10061092560 10530062336 1100788160 11.845 99 9 18 0 1178626208 76001424 1836624
13:01:15 33435 8192000000 10066175024 10502524928 1104342064 11.879 98 34 68 0 1153461456 41958560 7160832
13:02:53 40563 8192000000 10067362480 10512850944 1118189312 12.010 100 23 47 0 1158207248 35149312 4868624
13:04:40 54019 8192000000 10081301344 10516410368 1129158704 12.113 100 24 48 1 1168824320 34622560 5043056
13:06:40 57385 8192000000 10076083392 10522963968 1129351984 12.115 99 26 52 1 1182749184 48519744 4877456
13:08:41 60422 8192000000 10103087072 10558246912 1134586448 12.165 99 37 74 0 1193660768 51753888 7320432
13:10:46 22824 8192000000 10125034720 10543775744 1120210048 12.029 98 54 108 1 1192841616 62042112 10589456
13:12:13 82904 8192000000 10079133776 10538135552 1119910368 12.026 99 41 82 0 1193938656 66024688 8003600
13:15:29 51659 8192000000 10056578752 10509447168 1120551712 12.032 98 8 16 2 1147270496 25295680 1423104
13:17:25 118669 8192000000 10064683632 10543263744 1119039184 12.018 99 13 24 1 1160607104 39096752 2471168
13:20:24 74614 8192000000 10098867792 10544656384 1125073600 12.075 99 27 54 0 1178937328 48267760 5595968
13:22:52 45984 8192000000 10074732624 10533289984 1094507344 11.785 96 41 83 1 1162112624 59078800 8526480
13:24:40 106869 8192000000 10122409392 10574848000 1115833904 11.988 97 26 52 1 1191382272 70287936 5260432
13:28:18 110539 8192000000 10062646832 10497945600 1046292864 11.325 92 7 14 0 1071874784 24429728 1152192
13:31:10 84850 8192000000 10080007664 10533679104 1107972048 11.913 98 21 42 1 1166680304 54299872 4408384
13:33:40 48461 8192000000 10062575312 10568114176 1119864544 12.026 99 7 14 0 1172831200 51656544 1310112
13:36:41 36979 8192000000 8904568880 9077383168 74375376 0.899 7 3 6 0 92658624 17775040 508208

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-11-2021 13:38:33 to 10-11-2021 13:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235666
MON$OLDEST_ACTIVE               235667
MON$OLDEST_SNAPSHOT             235667
MON$NEXT_TRANSACTION            235667
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                       2025376
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2656 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2656 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2656 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2656 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Wed Nov 10 13:37:19 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
--- end of diff output ---

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

10.11.21 13:38:52. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-10 13:38:54 No severe PSQL-related problems occured
10.11.21 13:38:55. End of report.