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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16041484 kB
MemAvailable 31059580 kB
Buffers 0 kB
Cached 15706120 kB
SwapCached 2516 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.2
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 2022-07-27 07:37:17
DTS_END                         2022-07-27 07:37:17.7700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39600
TRN_ID                          216791
ATT_ID                          576
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: 8475.86 28908 1017103
customer order: creation 166.14 505 19937
customer order: refuse 14.93 474 1791
order to supplier: creation 44.43 1389 5331
order to supplier: removal 3.23 166935 387
invoice (draft): creation 45.97 3594 5516
invoice (draft): removal 45.42 1075 5450
invoice accept: apply 41.47 28591 4976
invoice accept: cancel 40.78 1376 4893
customer reserve: creation 3802.78 277 456333
customer reserve: removal 3991.67 106 479000
realization accept: apply 81.02 290 9722
realization accept: cancel 69.34 178 8321
payment from customer: creation 58.82 196 7058
payment from customer: removal 5.43 129 651
payment to supplier: creation 48.10 191 5772
payment to supplier: removal 3.73 123 447
service: total inventory turnovers 4.08 4232 490
service: total monetary turnovers 7.47 470 896
service: aggregate perf. data 1.07 12966 128
service: refresh index statistics 0.03 355068 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-07-2022 07:39:37 to 27-07-2022 07:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6759
TEST_TIME 2 5361
TEST_TIME 3 5639
TEST_TIME 4 5738
TEST_TIME 5 6459
TEST_TIME 6 6236
TEST_TIME 7 6097
TEST_TIME 8 5889
TEST_TIME 9 6006
TEST_TIME 10 5880
TEST_TIME 11 5856
TEST_TIME 12 6132
TEST_TIME 13 6483
TEST_TIME 14 6527
TEST_TIME 15 6809
TEST_TIME 16 6773
TEST_TIME 17 6990
TEST_TIME 18 6965
TEST_TIME 19 7141
TEST_TIME 20 7119
TEST_TIME 21 7133
TEST_TIME 22 7245
TEST_TIME 23 7578
TEST_TIME 24 7639
TEST_TIME 25 7802
TEST_TIME 26 8258
TEST_TIME 27 8691
TEST_TIME 28 9044
TEST_TIME 29 9115
TEST_TIME 30 9503
TEST_TIME 31 9648
TEST_TIME 32 9934
TEST_TIME 33 9979
TEST_TIME 34 10023
TEST_TIME 35 9855
TEST_TIME 36 9853
TEST_TIME 37 9993
TEST_TIME 38 9876
TEST_TIME 39 9836
TEST_TIME 40 9687
TEST_TIME 41 9560
TEST_TIME 42 9385
TEST_TIME 43 9302
TEST_TIME 44 9367
TEST_TIME 45 9241
TEST_TIME 46 9101
TEST_TIME 47 9130
TEST_TIME 48 8980
TEST_TIME 49 8882
TEST_TIME 50 8918
TEST_TIME 51 8902
TEST_TIME 52 8884
TEST_TIME 53 8870
TEST_TIME 54 8846
TEST_TIME 55 8763
TEST_TIME 56 8668
TEST_TIME 57 8609
TEST_TIME 58 8620
TEST_TIME 59 8664
TEST_TIME 60 8717
TEST_TIME 61 8648
TEST_TIME 62 8682
TEST_TIME 63 8652
TEST_TIME 64 8620
TEST_TIME 65 8712
TEST_TIME 66 8768
TEST_TIME 67 8816
TEST_TIME 68 8850
TEST_TIME 69 8891
TEST_TIME 70 8837
TEST_TIME 71 8749
TEST_TIME 72 8775
TEST_TIME 73 8797
TEST_TIME 74 8719
TEST_TIME 75 8735
TEST_TIME 76 8713
TEST_TIME 77 8667
TEST_TIME 78 8619
TEST_TIME 79 8548
TEST_TIME 80 8503
TEST_TIME 81 8489
TEST_TIME 82 8494
TEST_TIME 83 8491
TEST_TIME 84 8479
TEST_TIME 85 8457
TEST_TIME 86 8477
TEST_TIME 87 8480
TEST_TIME 88 8475
TEST_TIME 89 8524
TEST_TIME 90 8529
TEST_TIME 91 8647
TEST_TIME 92 8652
TEST_TIME 93 8697
TEST_TIME 94 8749
TEST_TIME 95 8719
TEST_TIME 96 8648
TEST_TIME 97 8624
TEST_TIME 98 8627
TEST_TIME 99 8597
TEST_TIME 100 8572
TEST_TIME 101 8519
TEST_TIME 102 8498
TEST_TIME 103 8519
TEST_TIME 104 8498
TEST_TIME 105 8486
TEST_TIME 106 8489
TEST_TIME 107 8514
TEST_TIME 108 8512
TEST_TIME 109 8525
TEST_TIME 110 8610
TEST_TIME 111 8630
TEST_TIME 112 8628
TEST_TIME 113 8651
TEST_TIME 114 8660
TEST_TIME 115 8648
TEST_TIME 116 8612
TEST_TIME 117 8588
TEST_TIME 118 8567
TEST_TIME 119 8525
TEST_TIME 120 8500

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-07-2022 07:39:41 to 27-07-2022 07:39:44.

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 1436503 1436503 0 0.00 0 19779 15 0 0
doc_list_biud 1436497 1436497 0 0.00 0 36965 42 0 0
sp_add_invoice_to_stock 5089 4976 113 2.22 51 205604 28591 0 113
sp_cancel_adding_invoice 4893 4893 0 0.00 5 102373 1377 0 0
sp_cancel_client_order 1791 1791 0 0.00 6 11865 475 0 0
sp_cancel_customer_reserve 479000 479000 0 0.00 1 37026 107 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 3821 130 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 4 1419 124 0 0
sp_cancel_supplier_invoice 5454 5450 4 0.07 2 35206 1075 0 4
sp_cancel_supplier_order 387 387 0 0.00 23 1409194 166935 0 0
sp_cancel_write_off 8321 8321 0 0.00 2 7554 179 0 0
sp_client_order 19937 19937 0 0.00 6 39391 505 0 0
sp_customer_reserve 456333 456333 0 0.00 0 56121 278 0 0
sp_fill_shopping_cart 30772 30772 0 0.00 1 20269 84 0 0
sp_fill_shopping_cart_clo_res 456305 456305 0 0.00 0 15928 21 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 31370 317 0 0
sp_kill_cost_storno 14312 14312 0 0.00 0 7482 80 0 0
sp_kill_qstorno_ret_qs2qd 972158 972158 0 0.00 0 36936 47 0 0
sp_kill_qty_storno 514504 514504 0 0.00 0 36961 108 0 0
sp_lock_selected_doc 20380 20380 0 0.00 0 10673 10 0 0
sp_make_cost_storno 27494 27494 0 0.00 0 19773 82 0 0
sp_multiply_rows_for_pdistr 27493 27493 0 0.00 0 6151 41 0 0
sp_multiply_rows_for_qdistr 19937 19937 0 0.00 0 10610 181 0 0
sp_pay_from_customer 7058 7058 0 0.00 5 14123 197 0 0
sp_pay_to_supplier 5772 5772 0 0.00 6 10673 191 0 0
sp_payment_common 12829 12829 0 0.00 2 14113 48 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 11851 388 0 0
sp_qd_handle_on_invoice_upd_sts 9834 9834 0 0.00 0 23903 549 0 0
sp_qd_handle_on_reserve_upd_sts 9721 9721 0 0.00 0 25514 69 0 0
sp_reserve_write_off 9722 9722 0 0.00 8 25852 291 0 0
sp_supplier_invoice 5516 5516 0 0.00 11 105982 3594 0 0
sp_supplier_order 5331 5331 0 0.00 7 51616 1389 0 0
srv_aggregate_perf_data 128 128 0 0.00 2578 173834 12967 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 732 121754 30697 0 0
srv_find_qd_qs_mism 968552 968552 0 0.00 0 12379 12 0 0
srv_make_invnt_saldo 490 490 0 0.00 45 114079 4233 0 0
srv_make_money_saldo 896 896 0 0.00 1 13666 471 0 0
srv_recalc_idx_stat 4 4 0 0.00 261717 513407 355069 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 4982 4982 0 0.00 1 14014 910 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 2 7524 514 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 4313 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1231 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 1837 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 613 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1210 37 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 109652 7184 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 6496 24 0 0
t$perf-norm:sp_client_order 19938 19938 0 0.00 0 7219 37 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 532 12 0 0
t$perf-norm:sp_pay_from_customer 7058 7058 0 0.00 0 6644 17 0 0
t$perf-norm:sp_pay_to_supplier 5772 5772 0 0.00 0 5637 20 0 0
t$perf-norm:sp_reserve_write_off 9723 9723 0 0.00 0 10106 24 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 5669 30 0 0
t$perf-norm:sp_supplier_order 5331 5331 0 0.00 0 5412 32 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 1139 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 331 7 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 6864 45 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 372 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 193 67 0 0
v_add_invoice_to_stock 10102 9876 226 2.23 0 10170 28 0 226
v_all_customers 36612 36612 0 0.00 0 59 4 0 0
v_all_suppliers 29982 29982 0 0.00 0 103 4 0 0
v_all_wares 39865 39865 0 0.00 0 55 4 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 2911 21 0 0
v_cancel_client_order 3582 3582 0 0.00 0 2443 10 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 3756 16 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 1 2821 13 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 643 11 0 0
v_cancel_supplier_invoice 790 782 8 1.01 0 2512 25 0 8
v_cancel_supplier_order 774 774 0 0.00 0 3993 17 0 0
v_cancel_write_off 1980 1980 0 0.00 0 2596 28 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 495 5 0 0
v_max_id_clo_res 967 967 0 0.00 0 951 11 0 0
v_max_id_ord_sup 5508 5508 0 0.00 0 1880 6 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 1015 8 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 5780 10 0 0
v_min_id_clo_ord 5327 5327 0 0.00 0 2056 9 0 0
v_min_id_clo_res 967 967 0 0.00 0 1964 13 0 0
v_min_id_ord_sup 5508 5508 0 0.00 0 4961 10 0 0
v_min_non_paid_invoice 5771 5771 0 0.00 0 5260 15 0 0
v_min_non_paid_realizn 7058 7058 0 0.00 0 14107 22 0 0
v_our_firm 9890 9890 0 0.00 0 51 4 0 0
v_reserve_write_off 19441 19441 0 0.00 0 7337 10 0 0
x_lock_depdocs_on_canc_invoice 9952 9952 0 0.00 0 12429 138 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 17548 526 0 0
x_make_qstorno_1000_1200 5331 5331 0 0.00 2 51543 1222 0 0
x_make_qstorno_1200_2000 5515 5515 0 0.00 5 103560 3351 0 0
x_make_qstorno_2100_3300 456332 456332 0 0.00 0 56115 244 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-07-2022 07:39:45 to 27-07-2022 07:39:49.

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:21 2 8192000000 8798536512 8809795584 4557200 0.055 2 1 2 0 4656624 63648 35776
05:08:22 5950 8192000000 9717398384 10012078080 800527808 8.902 100 91 182 0 830098816 7132336 22438672
05:09:40 24021 8192000000 9765650416 10071400448 840145376 9.301 100 93 186 0 873980688 12084272 21751040
05:11:12 13549 8192000000 9800541856 10118021120 873202800 9.632 100 89 179 0 914166496 20334880 20628816
05:12:36 6804 8192000000 9846915520 10177912832 899964096 9.898 100 73 145 0 962523488 46421648 16137744
05:13:46 4406 8192000000 9885525456 10224599040 943590544 10.328 100 84 165 0 1003404960 42471472 17342944
05:14:54 1825 8192000000 9938175456 10288861184 977399040 10.659 100 92 176 0 1053410832 58231680 17780112
05:15:58 2126 8192000000 10006745184 10361847808 1003588384 10.913 100 93 184 0 1123203424 100556992 19058048
05:17:03 1616 8192000000 10010778256 10377347072 1025547744 11.126 100 96 186 0 1125433248 81217152 18668352
05:18:06 939 8192000000 10017637680 10386796544 1047986960 11.341 100 97 188 2 1135256928 68285792 18984176
05:19:09 1206 8192000000 10029236848 10405781504 1062190560 11.477 100 96 184 2 1146912336 66495744 18226032
05:20:12 8753 8192000000 10043701120 10418245632 1069776784 11.550 100 95 176 4 1160485280 72219024 18489472
05:21:24 1740 8192000000 10043701312 10429853696 1073114160 11.582 100 78 142 2 1168131360 79974528 15042672
05:22:27 951 8192000000 10044786224 10425929728 1079325968 11.641 100 96 190 5 1168080240 70032992 18721280
05:23:29 1195 8192000000 10051803760 10433114112 1083029280 11.676 100 96 190 2 1174737248 73038224 18669744
05:24:31 951 8192000000 10039091680 10421043200 1087684880 11.721 100 96 188 1 1161375024 53481648 20208496
05:25:35 1685 8192000000 10057090096 10447331328 1091613072 11.758 100 86 166 0 1187309616 78179584 17516960
05:26:37 1450 8192000000 10060188672 10448216064 1090071696 11.743 100 98 184 5 1179655488 70389632 19194160
05:27:40 1306 8192000000 10074328192 10462162944 1091408128 11.756 100 98 183 4 1193108992 83862144 17838720
05:28:42 1443 8192000000 10048155824 10438189056 1090921024 11.751 100 90 166 5 1155876992 48283456 16672512
05:29:45 1089 8192000000 10059726528 10446835712 1089339568 11.736 100 95 172 3 1176948144 70114528 17494048
05:30:48 1879 8192000000 10037038416 10426671104 1084361040 11.689 100 87 165 5 1158912288 57034832 17516416
05:31:50 1324 8192000000 10028615488 10411847680 1069842112 11.551 100 94 172 0 1155544704 68139568 17563024
05:32:53 1211 8192000000 10041026832 10423795712 1072514352 11.576 100 90 173 0 1163196256 73502464 17179440
05:33:55 1194 8192000000 10004802192 10378653696 1035236528 11.219 99 97 176 3 1127577056 74975952 17364576
05:34:57 2095 8192000000 10018682464 10399043584 1061385456 11.470 100 89 163 4 1149489312 70800592 17303264
05:36:00 967 8192000000 10008528672 10389708800 1045051872 11.313 100 78 152 1 1131828624 71886752 14890000
05:37:02 1083 8192000000 9972990416 10342584320 1009796976 10.973 99 78 146 1 1097327856 72831792 14699088
05:38:10 1193 8192000000 9978998832 10350895104 1036505200 11.231 100 86 164 0 1100493088 47606288 16381600
05:39:17 20147 8192000000 10011233056 10393346048 1054846032 11.407 100 63 125 1 1133860992 66646736 12368224
05:40:43 2095 8192000000 10050058592 10432802816 1078251312 11.631 100 74 142 1 1169847312 77255376 14340624
05:41:48 2820 8192000000 10082424800 10468536320 1082729280 11.673 100 77 148 0 1204101104 106081472 15290352
05:42:57 2987 8192000000 10102020960 10490224640 1101648752 11.853 100 73 132 1 1220776032 105323728 13803552
05:44:06 1198 8192000000 10142083216 10531319808 1112445600 11.956 100 92 171 3 1260040448 128225664 19369184
05:45:11 7437 8192000000 10173232976 10568220672 1125819984 12.082 100 72 138 2 1286738032 146575616 14342432
05:46:22 1570 8192000000 10205797520 10599473152 1132712192 12.147 100 84 158 0 1320445072 169454496 18278384
05:47:28 3616 8192000000 10247322608 10646605824 1141508208 12.230 100 72 132 2 1363639216 207484256 14646752
05:48:37 5692 8192000000 10286389248 10683691008 1155015200 12.357 100 78 155 0 1399863536 227829920 17018416
05:49:46 1611 8192000000 10307081456 10735054848 1160875488 12.411 100 91 179 1 1413900832 233612784 19412560
05:50:50 729 8192000000 10331669504 10734338048 1170301840 12.500 100 88 167 1 1442120720 252603440 19215440
05:51:55 1641 8192000000 10342330864 10759557120 1182742272 12.616 100 80 158 0 1453303456 251677216 18883968
05:53:01 2001 8192000000 10348627584 10753507328 1186847872 12.654 100 86 168 0 1463354848 257887504 18619472
05:54:08 1040 8192000000 10387411504 10810454016 1196165920 12.741 100 97 190 1 1493716304 275879648 21670736
05:55:11 1367 8192000000 10415630800 10821947392 1196320448 12.742 100 86 168 0 1524378624 308599472 19458704
05:56:18 3065 8192000000 10434202560 10856886272 1189260656 12.676 100 87 174 1 1541558672 331303296 20994720
05:57:27 891 8192000000 10475620992 10880143360 1190975808 12.692 100 93 179 2 1578735312 366723760 21035744
05:58:33 873 8192000000 10503375472 10926317568 1199153488 12.768 100 97 189 3 1608662768 385807760 23701520
05:59:38 938 8192000000 10497761808 10933272576 1187254624 12.658 100 96 187 0 1603293904 394581152 21458128
06:00:43 1202 8192000000 10517217984 10931249152 1188418128 12.669 100 96 184 0 1620787872 409958640 22411104
06:01:46 1751 8192000000 10514564368 10941280256 1180761104 12.597 100 93 186 1 1621337920 417742416 22834400
06:02:52 3154 8192000000 10502746720 10959593472 1176004912 12.553 100 68 131 1 1608112176 414897488 17209776
06:04:00 20419 8192000000 10417747152 10909261824 1171238896 12.508 100 36 72 0 1516494448 335797936 9457616
06:05:59 5524 8192000000 10282580224 10761023488 1136858576 12.186 100 39 79 0 1393998048 247314144 9825328
06:07:13 5178 8192000000 10220442592 10682515456 1138061952 12.197 100 32 62 1 1340527408 194332304 8133152
06:08:27 34004 8192000000 10211781232 10684002304 1113868288 11.969 98 23 46 0 1296387024 176660544 5858192
06:10:04 24451 8192000000 10130382816 10562785280 1106888672 11.903 98 28 53 0 1219728288 107064176 5775440
06:11:48 40242 8192000000 10130314496 10564943872 1133707040 12.156 100 65 123 1 1234660240 87049136 13904064
06:13:30 33609 8192000000 10152061152 10602254336 1125200576 12.076 100 24 46 1 1252132576 121520656 5411344
06:15:22 44432 8192000000 10074597888 10544709632 1123256768 12.058 100 9 18 0 1176479648 51556256 1666624
06:17:07 36730 8192000000 10060192048 10495602688 1115435312 11.984 98 26 53 0 1142227296 21257312 5534672
06:19:16 74925 8192000000 10139016592 10578964480 1119299440 12.020 98 43 86 0 1201282160 72483072 9499648
06:21:47 82686 8192000000 10070683696 10504953856 1113321904 11.964 98 46 86 2 1155522128 31333520 10866704
06:24:31 72107 8192000000 10090455728 10532880384 1117282096 12.001 98 22 42 0 1203257872 81492944 4482832
06:27:08 1024 8192000000 10110458672 10551689216 1138804912 12.204 100 82 159 3 1222292768 66267184 17220672
06:28:16 14033 8192000000 10095941184 10539069440 1135971216 12.178 100 33 62 0 1201391808 59064000 6356592
06:29:38 15793 8192000000 10114296560 10555281408 1145536496 12.268 100 34 68 0 1216098704 63622640 6939568
06:31:01 79060 8192000000 10093998624 10571591680 1148578848 12.296 100 15 30 0 1225457392 73441312 3437232
06:34:34 19132 8192000000 10096035216 10560626688 1144808912 12.261 100 30 60 0 1205304480 54026128 6469440
06:35:59 76579 8192000000 10078873264 10547400704 1115459200 11.984 97 18 36 1 1165390128 45490496 4440432
06:38:16 14698 8192000000 10065641744 10506678272 1143997536 12.253 100 52 104 0 1175288592 19930544 11360512
06:39:53 18702 8192000000 10102493024 10538737664 1149054960 12.301 100 46 90 0 1203456176 45350624 9050592
06:41:12 80139 8192000000 10150927760 10647080960 1160551952 12.408 100 23 46 0 1253883232 88684384 4646896
06:43:39 52437 8192000000 10140826704 10586341376 1150117584 12.311 99 26 51 0 1217409600 62222112 5069904
06:45:32 82044 8192000000 10071396800 10525048832 1097605936 11.815 95 14 25 0 1137652384 37694512 2351936
06:47:57 96239 8192000000 10116152848 10585489408 1127447664 12.097 99 32 64 0 1221222784 86866960 6908160
06:50:35 54942 8192000000 10054722336 10518999040 1115542208 11.985 98 18 36 0 1147259376 28205968 3511200
06:52:30 46265 8192000000 10072738896 10543861760 1142256720 12.237 100 29 58 0 1222268800 73990320 6021760
06:54:38 45369 8192000000 10053531040 10489618432 1093546320 11.776 96 27 52 1 1124582496 25960976 5075200
06:56:25 45987 8192000000 10090681472 10548699136 1130916832 12.130 100 15 28 0 1173979696 39408416 3654448
06:58:17 28533 8192000000 10068031984 10545631232 1122685008 12.052 100 8 16 0 1176720272 52240224 1795040
07:00:03 66039 8192000000 10068603312 10509795328 1090437728 11.747 97 19 33 1 1131776624 37068512 4270384
07:02:09 24479 8192000000 10106052320 10534461440 1105706304 11.892 99 31 62 0 1189643792 77461664 6475824
07:03:35 20278 8192000000 10113896640 10547539968 1120542192 12.032 99 31 60 0 1213153440 85984624 6626624
07:04:56 21404 8192000000 10172080336 10601140224 1130791648 12.129 100 37 74 2 1264936976 125933824 8211504
07:06:18 21097 8192000000 10161282928 10599915520 1118931344 12.017 99 34 68 0 1261424688 135218064 7275280
07:07:40 30948 8192000000 10138126192 10623614976 1112068080 11.952 99 38 76 0 1228855072 108746080 8040912
07:09:16 21045 8192000000 10089828480 10509262848 1104145984 11.877 98 37 73 0 1190123216 78181424 7795808
07:10:41 58945 8192000000 10092406832 10608603136 1090630576 11.749 97 25 50 0 1189412288 93868896 4912816
07:12:40 121753 8192000000 10072631952 10531848192 1116584656 11.995 100 38 70 1 1171883600 46937760 8361184
07:15:43 51885 8192000000 10060780464 10532478976 1127017216 12.093 100 8 16 0 1167543936 38760656 1766064
07:17:51 34684 8192000000 10060580480 10542833664 1128671952 12.109 100 4 8 0 1172314928 43086272 556704
07:19:25 64257 8192000000 10063521456 10535182336 1117950000 12.008 98 23 45 0 1167768576 45010992 4807584
07:21:30 29437 8192000000 10099645824 10539237376 1111975504 11.951 97 27 54 0 1171616272 53935888 5704880
07:23:03 46691 8192000000 10129604096 10577952768 1137429120 12.191 100 36 70 0 1237956672 92779152 7748400
07:24:56 65686 8192000000 10144064848 10586525696 1136624816 12.184 99 30 58 0 1237978112 95020304 6332992
07:27:09 32406 8192000000 10149750944 10617901056 1138141232 12.198 99 37 72 1 1255030576 109098944 7790400
07:28:51 34971 8192000000 10135587056 10565718016 1148493648 12.295 99 37 72 1 1229818048 74461056 6863344
07:30:32 33614 8192000000 10136236000 10561208320 1144878448 12.261 99 63 118 3 1222544160 64740944 12924768
07:32:09 7985 8192000000 10118155456 10535772160 1141866496 12.233 99 57 113 0 1195007680 42319248 10821936
07:33:25 86715 8192000000 10103184240 10560425984 1113744480 11.968 97 49 97 0 1203076352 79660176 9671696
07:35:54 51983 8192000000 10090731712 10556477440 1133010112 12.150 99 10 20 0 1188698976 53600528 2088336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-07-2022 07:39:51 to 27-07-2022 07:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 27-07-2022 07:40:08 to 27-07-2022 07:40:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          216149
MON$OLDEST_ACTIVE               216915
MON$OLDEST_SNAPSHOT             216915
MON$NEXT_TRANSACTION            216915
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                       2036320
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Wed Jul 27 07:37:23 2022
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
--- end of diff output ---

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

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