Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2022 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 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 16918112 kB
MemAvailable 31586784 kB
Buffers 0 kB
Cached 14831656 kB
SwapCached 2276 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-04-07 07:37:13
DTS_END                         2022-04-07 07:37:13.3550
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/39650
TRN_ID                          215309
ATT_ID                          587
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: 8819.73 22978 1058368
customer order: creation 164.38 460 19725
customer order: refuse 13.92 367 1670
order to supplier: creation 43.93 1194 5271
order to supplier: removal 3.23 152710 387
invoice (draft): creation 43.14 3174 5177
invoice (draft): removal 49.61 954 5953
invoice accept: apply 39.51 28926 4741
invoice accept: cancel 44.89 1003 5387
customer reserve: creation 3753.22 274 450386
customer reserve: removal 4384.56 88 526147
realization accept: apply 79.84 243 9581
realization accept: cancel 76.14 151 9137
payment from customer: creation 56.64 171 6797
payment from customer: removal 5.53 104 663
payment to supplier: creation 45.88 181 5506
payment to supplier: removal 3.45 132 414
service: total inventory turnovers 4.18 3107 501
service: total monetary turnovers 6.74 318 809
service: aggregate perf. data 0.93 14369 112
service: refresh index statistics 0.03 251625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2022 07:39:19 to 07-04-2022 07:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10686
TEST_TIME 2 7517
TEST_TIME 3 7055
TEST_TIME 4 6763
TEST_TIME 5 6442
TEST_TIME 6 6525
TEST_TIME 7 6544
TEST_TIME 8 6668
TEST_TIME 9 6710
TEST_TIME 10 6663
TEST_TIME 11 6848
TEST_TIME 12 6754
TEST_TIME 13 6791
TEST_TIME 14 6711
TEST_TIME 15 6776
TEST_TIME 16 6850
TEST_TIME 17 7343
TEST_TIME 18 8292
TEST_TIME 19 8502
TEST_TIME 20 8550
TEST_TIME 21 8547
TEST_TIME 22 8754
TEST_TIME 23 9211
TEST_TIME 24 9867
TEST_TIME 25 10341
TEST_TIME 26 10308
TEST_TIME 27 10409
TEST_TIME 28 10898
TEST_TIME 29 10627
TEST_TIME 30 10575
TEST_TIME 31 10430
TEST_TIME 32 10180
TEST_TIME 33 10344
TEST_TIME 34 10144
TEST_TIME 35 10108
TEST_TIME 36 9927
TEST_TIME 37 9823
TEST_TIME 38 9708
TEST_TIME 39 9731
TEST_TIME 40 9680
TEST_TIME 41 9541
TEST_TIME 42 9432
TEST_TIME 43 9324
TEST_TIME 44 9354
TEST_TIME 45 9368
TEST_TIME 46 9324
TEST_TIME 47 9239
TEST_TIME 48 9229
TEST_TIME 49 9178
TEST_TIME 50 9211
TEST_TIME 51 9249
TEST_TIME 52 9274
TEST_TIME 53 9256
TEST_TIME 54 9327
TEST_TIME 55 9359
TEST_TIME 56 9463
TEST_TIME 57 9364
TEST_TIME 58 9448
TEST_TIME 59 9318
TEST_TIME 60 9178
TEST_TIME 61 9182
TEST_TIME 62 9169
TEST_TIME 63 9134
TEST_TIME 64 9066
TEST_TIME 65 8952
TEST_TIME 66 9046
TEST_TIME 67 8997
TEST_TIME 68 8922
TEST_TIME 69 8899
TEST_TIME 70 8954
TEST_TIME 71 9043
TEST_TIME 72 9041
TEST_TIME 73 8992
TEST_TIME 74 9045
TEST_TIME 75 9082
TEST_TIME 76 9173
TEST_TIME 77 9105
TEST_TIME 78 9176
TEST_TIME 79 9307
TEST_TIME 80 9245
TEST_TIME 81 9180
TEST_TIME 82 9103
TEST_TIME 83 9165
TEST_TIME 84 9119
TEST_TIME 85 9070
TEST_TIME 86 9031
TEST_TIME 87 8971
TEST_TIME 88 8919
TEST_TIME 89 8885
TEST_TIME 90 8847
TEST_TIME 91 8868
TEST_TIME 92 8898
TEST_TIME 93 8864
TEST_TIME 94 8854
TEST_TIME 95 8822
TEST_TIME 96 8869
TEST_TIME 97 8887
TEST_TIME 98 8927
TEST_TIME 99 8960
TEST_TIME 100 9058
TEST_TIME 101 9035
TEST_TIME 102 8993
TEST_TIME 103 9006
TEST_TIME 104 8990
TEST_TIME 105 8929
TEST_TIME 106 8973
TEST_TIME 107 8975
TEST_TIME 108 9004
TEST_TIME 109 9007
TEST_TIME 110 9020
TEST_TIME 111 9003
TEST_TIME 112 9003
TEST_TIME 113 9012
TEST_TIME 114 9007
TEST_TIME 115 9030
TEST_TIME 116 9053
TEST_TIME 117 8990
TEST_TIME 118 8927
TEST_TIME 119 8903
TEST_TIME 120 8853

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-04-2022 07:39:22 to 07-04-2022 07:39:26.

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 1471905 1471905 0 0.00 0 18931 13 0 0
doc_list_biud 1471896 1471896 0 0.00 0 94090 37 0 0
sp_add_invoice_to_stock 4897 4741 156 3.18 39 274161 28926 0 156
sp_cancel_adding_invoice 5387 5387 0 0.00 5 68781 1004 0 0
sp_cancel_client_order 1670 1670 0 0.00 6 11309 367 0 0
sp_cancel_customer_reserve 526147 526147 0 0.00 1 35186 88 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 5207 105 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 6849 132 0 0
sp_cancel_supplier_invoice 5966 5953 13 0.21 2 101591 954 0 13
sp_cancel_supplier_order 387 387 0 0.00 21 1112032 152711 0 0
sp_cancel_write_off 9137 9137 0 0.00 2 11940 152 0 0
sp_client_order 19725 19725 0 0.00 6 27260 461 0 0
sp_customer_reserve 450386 450386 0 0.00 1 65419 274 0 0
sp_fill_shopping_cart 30158 30158 0 0.00 1 44750 73 0 0
sp_fill_shopping_cart_clo_res 450352 450352 0 0.00 0 15285 19 0 0
sp_get_clo_for_invoice 4689 4689 0 0.00 0 30538 351 0 0
sp_kill_cost_storno 15601 15601 0 0.00 0 7649 63 0 0
sp_kill_qstorno_ret_qs2qd 1067771 1067771 0 0.00 0 94089 40 0 0
sp_kill_qty_storno 562948 562948 0 0.00 0 94090 90 0 0
sp_lock_selected_doc 19784 19784 0 0.00 0 11041 8 0 0
sp_make_cost_storno 26573 26573 0 0.00 0 15397 76 0 0
sp_multiply_rows_for_pdistr 26571 26571 0 0.00 0 8024 32 0 0
sp_multiply_rows_for_qdistr 19724 19724 0 0.00 0 26748 186 0 0
sp_pay_from_customer 6797 6797 0 0.00 5 19874 172 0 0
sp_pay_to_supplier 5506 5506 0 0.00 7 20675 181 0 0
sp_payment_common 12303 12303 0 0.00 2 10041 43 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 8126 296 0 0
sp_qd_handle_on_invoice_upd_sts 10073 10073 0 0.00 0 31718 438 0 0
sp_qd_handle_on_reserve_upd_sts 9581 9581 0 0.00 0 16180 54 0 0
sp_reserve_write_off 9581 9581 0 0.00 9 17012 243 0 0
sp_supplier_invoice 5177 5177 0 0.00 8 151077 3174 0 0
sp_supplier_order 5271 5271 0 0.00 9 76456 1195 0 0
srv_aggregate_perf_data 112 112 0 0.00 2284 200501 14370 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 879 134724 39902 0 0
srv_find_qd_qs_mism 1010750 1010750 0 0.00 0 13238 11 0 0
srv_make_invnt_saldo 501 501 0 0.00 31 82491 3107 0 0
srv_make_money_saldo 809 809 0 0.00 2 21517 318 0 0
srv_recalc_idx_stat 4 4 0 0.00 120184 449667 251626 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4746 4746 0 0.00 1 30297 738 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 2 18035 370 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 5707 27 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 4254 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 337 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 256 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2282 21 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 1 94953 5992 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 5657 29 0 0
t$perf-norm:sp_client_order 19726 19726 0 0.00 0 13296 32 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 2008 12 0 0
t$perf-norm:sp_pay_from_customer 6798 6798 0 0.00 0 10693 20 0 0
t$perf-norm:sp_pay_to_supplier 5506 5506 0 0.00 0 9859 20 0 0
t$perf-norm:sp_reserve_write_off 9581 9581 0 0.00 0 9558 23 0 0
t$perf-norm:sp_supplier_invoice 5177 5177 0 0.00 0 8548 31 0 0
t$perf-norm:sp_supplier_order 5271 5271 0 0.00 0 8601 29 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 359 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 346 8 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 5050 36 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 2470 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 13 203 64 0 0
v_add_invoice_to_stock 9678 9366 312 3.22 0 19160 31 0 312
v_all_customers 36212 36212 0 0.00 0 60 3 0 0
v_all_suppliers 29304 29304 0 0.00 0 102 3 0 0
v_all_wares 39444 39444 0 0.00 0 66 3 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 5246 28 0 0
v_cancel_client_order 3340 3340 0 0.00 0 1708 8 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 4468 15 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 1198 9 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 4793 17 0 0
v_cancel_supplier_invoice 808 782 26 3.21 0 19397 47 0 26
v_cancel_supplier_order 774 774 0 0.00 0 1228 11 0 0
v_cancel_write_off 1944 1944 0 0.00 0 4481 27 0 0
v_max_id_clo_ord 5267 5267 0 0.00 0 1574 4 0 0
v_max_id_clo_res 994 994 0 0.00 0 2643 17 0 0
v_max_id_ord_sup 5167 5167 0 0.00 0 4247 9 0 0
v_max_non_paid_invoice 1287 1287 0 0.00 0 5118 10 0 0
v_max_non_paid_realizn 3473 3473 0 0.00 0 5119 6 0 0
v_min_id_clo_ord 5267 5267 0 0.00 0 2751 7 0 0
v_min_id_clo_res 994 994 0 0.00 0 4368 12 0 0
v_min_id_ord_sup 5167 5167 0 0.00 0 9700 11 0 0
v_min_non_paid_invoice 5506 5506 0 0.00 0 4150 15 0 0
v_min_non_paid_realizn 6797 6797 0 0.00 0 9858 22 0 0
v_our_firm 9880 9880 0 0.00 0 41 3 0 0
v_reserve_write_off 19162 19162 0 0.00 0 16826 8 0 0
x_lock_depdocs_on_canc_invoice 10949 10949 0 0.00 0 15242 111 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 11553 418 0 0
x_make_qstorno_1000_1200 5271 5271 0 0.00 3 76431 1060 0 0
x_make_qstorno_1200_2000 5177 5177 0 0.00 4 150994 2944 0 0
x_make_qstorno_2100_3300 450386 450386 0 0.00 0 65415 245 0 0

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

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:17 6 8192000000 8799252064 8810582016 5260816 0.064 2 1 2 0 5360240 63648 35776
05:08:18 26854 8192000000 9727765328 10027098112 801823328 8.915 100 85 170 0 829961424 7511584 20626512
05:09:50 25767 8192000000 9768249280 10081001472 842335456 9.323 100 86 170 1 875185920 12600576 20249888
05:11:20 21139 8192000000 9809776928 10132074496 874807232 9.648 100 80 160 0 913071248 20068752 18195264
05:12:47 12324 8192000000 9881665696 10218041344 924056432 10.136 100 86 170 1 989993712 47518576 18418704
05:14:02 8397 8192000000 9925156736 10271694848 965249584 10.540 100 84 167 1 1041461680 58833216 17378880
05:15:12 1960 8192000000 9961326928 10321920000 995265328 10.833 100 84 166 1 1074914544 63612096 16037120
05:16:18 3857 8192000000 9983755024 10347466752 1020724512 11.079 100 81 153 1 1103813776 68486224 14603040
05:17:24 1383 8192000000 10036202208 10413506560 1045559104 11.318 100 96 187 1 1153585456 88558720 19467632
05:18:27 829 8192000000 10043357376 10420170752 1056613616 11.424 100 95 183 0 1163316304 88752944 17949744
05:19:29 1198 8192000000 10040119824 10419499008 1066324640 11.517 100 95 183 0 1160337584 74500768 19512176
05:20:31 1097 8192000000 10052262960 10433814528 1074024368 11.590 100 95 186 0 1173911152 80945520 18941264
05:21:33 1289 8192000000 10065979200 10450149376 1080954640 11.657 100 97 186 1 1190644704 89946032 19744032
05:22:36 1103 8192000000 10045680336 10431877120 1082198640 11.668 100 92 182 0 1170602496 70892144 17511712
05:23:38 1162 8192000000 10054712464 10443182080 1085863456 11.703 100 96 184 2 1175998512 71564496 18570560
05:24:40 1651 8192000000 10052831248 10444410880 1087727728 11.721 100 90 171 1 1176334928 70974528 17632672
05:25:43 1029 8192000000 10061930112 10448355328 1095830352 11.798 100 99 185 0 1184509664 68869952 19809360
05:26:45 1118 8192000000 10046744736 10434211840 1101939280 11.856 100 98 186 2 1171820352 50723840 19157232
05:27:47 1236 8192000000 10055463632 10445680640 1094177584 11.782 99 91 174 3 1179287040 66763408 18346048
05:28:49 2457 8192000000 10051886544 10449285120 1100519184 11.843 100 69 131 2 1185197744 71968000 12710560
05:29:53 1309 8192000000 10052730512 10441256960 1091218528 11.754 100 98 184 4 1174687888 64446448 19022912
05:30:55 1074 8192000000 10019507680 10404925440 1059608832 11.453 99 83 152 2 1143262368 68065856 15587680
05:31:57 1214 8192000000 10005453760 10385211392 1060208192 11.458 100 92 176 4 1132681840 54781024 17692624
05:32:59 11622 8192000000 9980847200 10363760640 1045605280 11.319 99 67 128 4 1099587600 41159920 12822400
05:34:11 2715 8192000000 9992057680 10374537216 1042693696 11.291 100 68 121 1 1116443856 61591280 12158880
05:35:14 10624 8192000000 10004984208 10389286912 1040892368 11.273 100 58 114 0 1118773136 66802912 11077856
05:36:26 1272 8192000000 9996239920 10368036864 1029303632 11.162 100 96 182 3 1112421776 64823296 18294848
05:37:29 1682 8192000000 10031119168 10405654528 1047872656 11.340 100 91 176 0 1145698928 79523328 18302944
05:38:38 13256 8192000000 10013057280 10402123776 1061153168 11.468 100 56 109 0 1130244736 58130400 10961168
05:40:00 6785 8192000000 10050414608 10427674624 1080161680 11.649 100 91 163 0 1165021648 69359632 15500336
05:41:10 3026 8192000000 10104396224 10489434112 1092795440 11.769 100 85 166 2 1220579248 109754960 18028848
05:42:20 3160 8192000000 10120683680 10511540224 1096149168 11.801 100 68 134 2 1242560848 131879504 14532176
05:43:46 8375 8192000000 10136283744 10545283072 1121980768 12.046 100 62 119 1 1246171424 110032464 14158192
05:45:01 3276 8192000000 10170966272 10581032960 1135116896 12.170 100 73 143 0 1290448112 139747760 15583456
05:46:20 1161 8192000000 10240598272 10630176768 1153117392 12.339 100 92 180 1 1352496672 180327584 19051696
05:47:27 3430 8192000000 10281363760 10686246912 1169370816 12.491 100 91 176 0 1390406192 201819344 19216032
05:48:36 869 8192000000 10311608768 10723061760 1188662560 12.671 100 95 174 2 1422074560 211754400 21657600
05:49:41 1477 8192000000 10358396336 10767814656 1195673744 12.736 100 95 182 1 1468735520 251122512 21939264
05:50:45 1362 8192000000 10409206448 10818232320 1200104096 12.777 100 92 180 0 1518714272 297174368 21435808
05:51:48 1307 8192000000 10469751760 10873036800 1206773072 12.839 100 96 192 1 1579041264 349737328 22530864
05:52:56 1288 8192000000 10503592224 10931867648 1204811632 12.821 100 97 184 2 1612128800 385097456 22219712
05:54:02 1471 8192000000 10517919152 10970861568 1211681808 12.885 100 88 169 1 1624556992 392016032 20859152
05:55:05 1123 8192000000 10533952176 10993823744 1213615936 12.903 100 82 165 0 1640360960 406501168 20243856
05:56:07 14017 8192000000 10445911600 10910359552 1201523296 12.790 99 44 88 2 1550418192 337746784 11148112
05:57:51 29547 8192000000 10397484256 10863108096 1171479904 12.511 98 38 76 0 1529318736 347607808 10231024
05:59:30 11242 8192000000 10401615152 10900807680 1178140096 12.573 100 36 71 0 1507034576 319192816 9701664
06:00:42 25289 8192000000 10291087728 10820452352 1132843216 12.148 97 23 46 0 1419479504 280267872 6368416
06:02:43 68946 8192000000 10178645616 10682720256 1126228672 12.086 96 18 36 2 1271623696 141096704 4298320
06:05:19 49600 8192000000 10078960736 10613149696 1095315968 11.793 96 15 30 0 1153199792 53797360 4086464
06:07:09 15288 8192000000 10070315680 10518896640 1118861360 12.016 99 35 69 3 1160613840 33873264 7879216
06:08:36 83584 8192000000 10124820256 10571550720 1122383776 12.050 99 21 41 2 1168759648 42221792 4154080
06:11:17 35800 8192000000 10065832112 10500513792 1106149296 11.896 97 48 93 1 1146767424 29448256 11169872
06:12:53 70132 8192000000 10102817056 10538684416 1122446192 12.050 98 24 47 3 1161959296 34687776 4825328
06:15:05 128820 8192000000 10084378896 10608431104 1132821392 12.148 99 28 55 2 1225088368 85082544 7184432
06:18:15 44233 8192000000 10086800992 10543120384 1114298736 11.973 97 35 66 2 1173008976 52007312 6702928
06:20:00 61112 8192000000 10099351264 10587545600 1129568544 12.117 99 22 44 0 1223405104 88872304 4964256
06:22:04 43889 8192000000 10042547728 10564866048 1101860432 11.855 98 2 4 0 1137512192 35423264 228496
06:23:49 12855 8192000000 10018999216 10451775488 1089743488 11.740 97 26 42 2 1111166400 17229984 4192928
06:25:03 71490 8192000000 10073263184 10514132992 1091176752 11.754 97 32 64 1 1143897120 46418656 6301712
06:27:17 49355 8192000000 10088160576 10517168128 1094454176 11.785 97 19 37 1 1154138272 55627504 4056592
06:29:07 32802 8192000000 10115339808 10559827968 1131119680 12.132 100 37 74 1 1224040192 85009872 7910640
06:30:41 72540 8192000000 10077750880 10529198080 1076445808 11.614 96 17 34 0 1146712944 66799936 3467200
06:32:58 69331 8192000000 10067046576 10524200960 1108846416 11.921 99 22 40 0 1152499424 39302976 4350032
06:35:08 37063 8192000000 10146753344 10605772800 1134791552 12.167 100 48 96 0 1261484720 116783792 9909376
06:36:59 77160 8192000000 10142629648 10566873088 1113641760 11.967 99 45 89 2 1225871152 102467632 9761760
06:39:21 75595 8192000000 10046067968 10524917760 1126311648 12.087 100 9 18 0 1186147744 57864560 1971536
06:41:37 85720 8192000000 10101432352 10582052864 1122708592 12.053 99 13 26 0 1206147296 80553616 2885088
06:45:01 39757 8192000000 10085979312 10520768512 1114762128 11.977 98 23 46 0 1160653248 41353456 4537664
06:46:43 56891 8192000000 10154145104 10609430528 1135511088 12.173 99 33 66 1 1270859152 128144352 7203712
06:48:41 38671 8192000000 10080972624 10502025216 1097734176 11.816 96 39 74 2 1128562208 23149120 7678912
06:50:21 76126 8192000000 10124608048 10595328000 1135450048 12.173 100 33 66 0 1202888320 60638304 6799968
06:52:40 39389 8192000000 10150036928 10585862144 1136559184 12.183 99 28 56 2 1220656864 78257504 5840176
06:54:20 25899 8192000000 10113142416 10536611840 1108864112 11.922 98 53 105 0 1183113328 63774400 10474816
06:55:48 85467 8192000000 10061892144 10573004800 1101476688 11.852 97 11 22 0 1187776288 83986768 2312832
06:58:14 39301 8192000000 10122424176 10547769344 1113318256 11.964 99 62 120 1 1189559408 63348064 12893088
07:00:33 35240 8192000000 10051256016 10546434048 1120000544 12.027 100 13 26 0 1196820288 74281728 2538016
07:02:09 34271 8192000000 10029370272 10481106944 1100738208 11.845 98 10 16 1 1142843056 41067152 1037696
07:03:44 61233 8192000000 10054786992 10495537152 1125623552 12.080 100 20 40 0 1155582688 25720368 4238768
07:05:48 87115 8192000000 10058886288 10501758976 1078765456 11.636 95 16 30 2 1124164672 40960160 4439056
07:08:16 35588 8192000000 10120855200 10581196800 1133390048 12.153 100 33 66 0 1209645824 69418416 6837360
07:09:52 43372 8192000000 10149206112 10583023616 1129973200 12.121 99 40 79 2 1224777376 86188384 8615792
07:11:37 35080 8192000000 10186769776 10620112896 1133472528 12.154 99 29 57 3 1271282624 131528240 6281856
07:13:15 40911 8192000000 10182147952 10621906944 1105562128 11.890 97 37 72 3 1276863536 162594832 8706576
07:14:59 35560 8192000000 10160266336 10637324288 1126780688 12.091 100 35 68 1 1294109392 159473072 7855632
07:16:35 41594 8192000000 10095773136 10615906304 1123525280 12.060 100 33 66 0 1240007408 109741760 6740368
07:18:25 38986 8192000000 10138538096 10580475904 1137693216 12.194 100 62 124 1 1228208048 77979184 12535648
07:20:36 83679 8192000000 10115757776 10613587968 1141493088 12.230 100 9 19 1 1214645520 71286864 1865568
07:23:00 42445 8192000000 10116692528 10566270976 1132026560 12.140 99 41 82 0 1201853568 60797856 9029152
07:24:46 134723 8192000000 10112764448 10602012672 1110396704 11.936 97 20 40 0 1246451680 131565312 4489664
07:28:01 26924 8192000000 10081322064 10511179776 1128453888 12.107 99 38 76 0 1168330784 32300272 7576624
07:29:29 39574 8192000000 10107334576 10548822016 1105996528 11.894 98 45 90 4 1189468288 74345984 9125776
07:31:09 8240 8192000000 10081124544 10499235840 1116249776 11.992 100 50 97 1 1171505936 45789616 9466544
07:32:18 52221 8192000000 10114413536 10578423808 1090073264 11.743 97 41 81 0 1198004416 99883984 8047168
07:34:14 80647 8192000000 10028402672 10459324416 1097078144 11.810 98 26 52 2 1118531424 15921792 5531488
07:36:41 35816 8192000000 9429230288 9770332160 554626464 6.341 50 19 38 0 588493488 30541296 3325728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-04-2022 07:39:33 to 07-04-2022 07:39:49.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2022 07:39:50 to 07-04-2022 07:39:52.

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          215532
MON$OLDEST_ACTIVE               215533
MON$OLDEST_SNAPSHOT             215533
MON$NEXT_TRANSACTION            215533
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                       2005632
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2750 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2750 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2750 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2750 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 ---
--- end of diff output ---

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

07.04.22 07:39:53. 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-04-07 07:39:55 No severe PSQL-related problems occured
07.04.22 07:39:56. End of report.