Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.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 14545520 kB
MemAvailable 30051376 kB
Buffers 0 kB
Cached 17129900 kB
SwapCached 3468 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.3
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-12-19 07:37:39
DTS_END                         2022-12-19 07:37:20.4910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48192
TRN_ID                          217584
ATT_ID                          259
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: 9229.36 28603 1107523
customer order: creation 163.99 469 19679
customer order: refuse 13.50 426 1620
order to supplier: creation 45.38 1356 5446
order to supplier: removal 3.38 181939 406
invoice (draft): creation 44.62 3182 5354
invoice (draft): removal 52.18 1076 6262
invoice accept: apply 40.98 25962 4918
invoice accept: cancel 47.52 1210 5702
customer reserve: creation 3858.42 243 463010
customer reserve: removal 4663.43 101 559612
realization accept: apply 81.13 295 9735
realization accept: cancel 88.76 177 10651
payment from customer: creation 57.83 179 6940
payment from customer: removal 5.27 158 632
payment to supplier: creation 47.76 204 5731
payment to supplier: removal 3.46 118 415
service: total inventory turnovers 3.67 4716 440
service: total monetary turnovers 7.10 433 852
service: aggregate perf. data 0.95 17068 114
service: refresh index statistics 0.03 332743 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2022 07:38:22 to 19-12-2022 07:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5702
TEST_TIME 2 5499
TEST_TIME 3 4946
TEST_TIME 4 4868
TEST_TIME 5 4578
TEST_TIME 6 4642
TEST_TIME 7 4687
TEST_TIME 8 4654
TEST_TIME 9 4945
TEST_TIME 10 5187
TEST_TIME 11 5451
TEST_TIME 12 5639
TEST_TIME 13 5716
TEST_TIME 14 6154
TEST_TIME 15 6059
TEST_TIME 16 6320
TEST_TIME 17 6592
TEST_TIME 18 6961
TEST_TIME 19 7179
TEST_TIME 20 7201
TEST_TIME 21 7444
TEST_TIME 22 7590
TEST_TIME 23 7699
TEST_TIME 24 7839
TEST_TIME 25 7751
TEST_TIME 26 7878
TEST_TIME 27 8248
TEST_TIME 28 8581
TEST_TIME 29 8646
TEST_TIME 30 9153
TEST_TIME 31 9654
TEST_TIME 32 9931
TEST_TIME 33 10043
TEST_TIME 34 10325
TEST_TIME 35 10579
TEST_TIME 36 10726
TEST_TIME 37 10940
TEST_TIME 38 10834
TEST_TIME 39 10657
TEST_TIME 40 10631
TEST_TIME 41 10897
TEST_TIME 42 10838
TEST_TIME 43 10686
TEST_TIME 44 10563
TEST_TIME 45 10621
TEST_TIME 46 10582
TEST_TIME 47 10568
TEST_TIME 48 10630
TEST_TIME 49 10478
TEST_TIME 50 10337
TEST_TIME 51 10267
TEST_TIME 52 10230
TEST_TIME 53 10145
TEST_TIME 54 10093
TEST_TIME 55 10015
TEST_TIME 56 9969
TEST_TIME 57 9959
TEST_TIME 58 9843
TEST_TIME 59 9922
TEST_TIME 60 9935
TEST_TIME 61 9880
TEST_TIME 62 9818
TEST_TIME 63 9705
TEST_TIME 64 9711
TEST_TIME 65 9944
TEST_TIME 66 9905
TEST_TIME 67 9884
TEST_TIME 68 9866
TEST_TIME 69 9986
TEST_TIME 70 9976
TEST_TIME 71 9933
TEST_TIME 72 9966
TEST_TIME 73 9908
TEST_TIME 74 9958
TEST_TIME 75 9929
TEST_TIME 76 9902
TEST_TIME 77 9884
TEST_TIME 78 9912
TEST_TIME 79 9830
TEST_TIME 80 9835
TEST_TIME 81 9776
TEST_TIME 82 9741
TEST_TIME 83 9747
TEST_TIME 84 9715
TEST_TIME 85 9662
TEST_TIME 86 9609
TEST_TIME 87 9569
TEST_TIME 88 9495
TEST_TIME 89 9431
TEST_TIME 90 9408
TEST_TIME 91 9486
TEST_TIME 92 9448
TEST_TIME 93 9527
TEST_TIME 94 9497
TEST_TIME 95 9506
TEST_TIME 96 9521
TEST_TIME 97 9501
TEST_TIME 98 9539
TEST_TIME 99 9592
TEST_TIME 100 9649
TEST_TIME 101 9657
TEST_TIME 102 9642
TEST_TIME 103 9611
TEST_TIME 104 9575
TEST_TIME 105 9561
TEST_TIME 106 9514
TEST_TIME 107 9504
TEST_TIME 108 9468
TEST_TIME 109 9437
TEST_TIME 110 9390
TEST_TIME 111 9371
TEST_TIME 112 9347
TEST_TIME 113 9283
TEST_TIME 114 9302
TEST_TIME 115 9317
TEST_TIME 116 9293
TEST_TIME 117 9289
TEST_TIME 118 9332
TEST_TIME 119 9316
TEST_TIME 120 9257

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2022 07:38:26 to 19-12-2022 07:38:29.

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 1533001 1533001 0 0.00 0 54968 13 0 0
doc_list_biud 1532985 1532985 0 0.00 0 22814 43 0 0
sp_add_invoice_to_stock 5071 4918 153 3.01 25 200758 25963 0 153
sp_cancel_adding_invoice 5702 5702 0 0.00 6 81993 1211 0 0
sp_cancel_client_order 1620 1620 0 0.00 6 9521 427 0 0
sp_cancel_customer_reserve 559612 559612 0 0.00 1 22828 102 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 11875 159 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 2799 119 0 0
sp_cancel_supplier_invoice 6268 6262 6 0.09 2 15513 1076 0 6
sp_cancel_supplier_order 406 406 0 0.00 10 1636787 181939 0 0
sp_cancel_write_off 10651 10651 0 0.00 2 11075 177 0 0
sp_client_order 19679 19679 0 0.00 5 36486 470 0 0
sp_customer_reserve 463010 463010 0 0.00 0 58084 243 0 0
sp_fill_shopping_cart 30469 30469 0 0.00 1 16115 83 0 0
sp_fill_shopping_cart_clo_res 462973 462973 0 0.00 0 12761 18 0 0
sp_get_clo_for_invoice 4869 4869 0 0.00 0 37401 269 0 0
sp_kill_cost_storno 17400 17400 0 0.00 0 42007 86 0 0
sp_kill_qstorno_ret_qs2qd 1136543 1136543 0 0.00 0 22776 45 0 0
sp_kill_qty_storno 598854 598854 0 0.00 0 22807 102 0 0
sp_lock_selected_doc 20005 20005 0 0.00 0 9338 7 0 0
sp_make_cost_storno 27274 27274 0 0.00 0 54943 89 0 0
sp_multiply_rows_for_pdistr 27273 27273 0 0.00 0 12495 44 0 0
sp_multiply_rows_for_qdistr 19679 19679 0 0.00 0 11071 166 0 0
sp_pay_from_customer 6940 6940 0 0.00 6 12738 179 0 0
sp_pay_to_supplier 5731 5731 0 0.00 6 13848 204 0 0
sp_payment_common 12670 12670 0 0.00 2 11384 44 0 0
sp_qd_handle_on_cancel_clo 1620 1620 0 0.00 0 9144 350 0 0
sp_qd_handle_on_invoice_upd_sts 10571 10571 0 0.00 0 17466 537 0 0
sp_qd_handle_on_reserve_upd_sts 9732 9732 0 0.00 0 19954 69 0 0
sp_reserve_write_off 9735 9735 0 0.00 9 20123 296 0 0
sp_supplier_invoice 5354 5354 0 0.00 12 97162 3183 0 0
sp_supplier_order 5446 5446 0 0.00 7 30096 1356 0 0
srv_aggregate_perf_data 114 114 0 0.00 2039 163215 17068 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 869 154520 33889 0 0
srv_find_qd_qs_mism 1059078 1059078 0 0.00 0 14635 10 0 0
srv_make_invnt_saldo 440 440 0 0.00 45 194570 4717 0 0
srv_make_money_saldo 852 852 0 0.00 1 28765 434 0 0
srv_recalc_idx_stat 4 4 0 0.00 78137 475937 332743 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4924 4924 0 0.00 0 10661 915 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6204 431 0 0
t$perf-norm:sp_cancel_client_order 1620 1620 0 0.00 0 1840 24 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 4101 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 5301 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 3284 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1839 41 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 106974 7881 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 5144 27 0 0
t$perf-norm:sp_client_order 19679 19679 0 0.00 0 5238 37 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 958 15 0 0
t$perf-norm:sp_pay_from_customer 6940 6940 0 0.00 0 3460 18 0 0
t$perf-norm:sp_pay_to_supplier 5731 5731 0 0.00 0 3987 21 0 0
t$perf-norm:sp_reserve_write_off 9735 9735 0 0.00 0 6796 24 0 0
t$perf-norm:sp_supplier_invoice 5354 5354 0 0.00 0 8244 31 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 7697 35 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 5417 54 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 31 3 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 821 13 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 1029 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 290 94 0 0
v_add_invoice_to_stock 10038 9732 306 3.04 0 6548 27 0 306
v_all_customers 36488 36488 0 0.00 0 67 4 0 0
v_all_suppliers 30318 30318 0 0.00 0 133 4 0 0
v_all_wares 39352 39352 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 4478 26 0 0
v_cancel_client_order 3240 3240 0 0.00 0 2391 9 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 2208 16 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 1 705 10 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2754 12 0 0
v_cancel_supplier_invoice 762 750 12 1.57 0 2917 32 0 12
v_cancel_supplier_order 812 812 0 0.00 0 1904 15 0 0
v_cancel_write_off 1840 1840 0 0.00 0 3592 25 0 0
v_max_id_clo_ord 5444 5444 0 0.00 0 1189 5 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3287 17 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 2624 7 0 0
v_max_non_paid_invoice 1361 1361 0 0.00 0 330 8 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 1936 8 0 0
v_min_id_clo_ord 5444 5444 0 0.00 0 3871 9 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1455 11 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 5186 6 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 11379 21 0 0
v_min_non_paid_realizn 6939 6939 0 0.00 0 3820 13 0 0
v_our_firm 9850 9850 0 0.00 0 68 5 0 0
v_reserve_write_off 19460 19460 0 0.00 0 4640 11 0 0
x_lock_depdocs_on_canc_invoice 11589 11589 0 0.00 0 13003 136 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 12752 442 0 0
x_make_qstorno_1000_1200 5446 5446 0 0.00 2 29637 1188 0 0
x_make_qstorno_1200_2000 5354 5354 0 0.00 5 95542 2963 0 0
x_make_qstorno_2100_3300 463009 463009 0 0.00 0 51450 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2022 07:38:30 to 19-12-2022 07:38:34.

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:24 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 34796 8192000000 9724306384 10022154240 799578640 8.892 100 89 178 0 829133136 7602768 21951728
05:10:04 23237 8192000000 9766998512 10077007872 836983856 9.269 100 88 176 0 870975248 12926128 21065264
05:11:31 15054 8192000000 9810288960 10131214336 868308240 9.583 100 82 164 0 914514000 27393264 18812496
05:12:56 6340 8192000000 9866982912 10201800704 917940128 10.076 100 85 167 0 978960608 42767328 18253152
05:14:09 3982 8192000000 9933569248 10276528128 963648672 10.525 100 94 185 0 1045776976 62420800 19707504
05:15:16 3168 8192000000 9974426944 10329296896 994815120 10.828 100 90 174 0 1086685328 73673824 18196384
05:16:22 1936 8192000000 9979194272 10339356672 1019172208 11.064 100 89 177 0 1097448336 60713792 17562336
05:17:26 1578 8192000000 10015773184 10379608064 1032844720 11.196 100 91 176 0 1132400992 82148928 17407344
05:18:29 1226 8192000000 10012187232 10387300352 1050367904 11.364 100 88 168 0 1135163472 67528768 17266800
05:19:31 1105 8192000000 10026241712 10399662080 1067142304 11.525 100 97 190 3 1142965792 57172480 18651008
05:20:34 1090 8192000000 10035893280 10417164288 1070474816 11.557 100 94 186 0 1156589104 67579040 18535248
05:21:36 1202 8192000000 10055202480 10435907584 1074559440 11.596 100 82 154 3 1176768736 87802672 14406624
05:22:38 942 8192000000 10046845568 10437251072 1077751024 11.626 100 87 169 0 1170643472 74349808 18542640
05:23:40 1082 8192000000 10053068224 10437558272 1081415024 11.661 100 93 179 1 1171010624 70991984 18603616
05:24:42 2961 8192000000 10068301104 10451619840 1089890000 11.742 100 86 170 4 1192937920 87502096 15545824
05:25:47 1096 8192000000 10071657120 10459697152 1092385088 11.765 100 96 181 3 1193945456 82914800 18645568
05:26:49 1041 8192000000 10071025552 10461224960 1089106320 11.734 100 91 172 3 1193011792 85893504 18011968
05:27:52 1072 8192000000 10044024208 10435436544 1100246160 11.840 100 95 180 0 1170000352 50349040 19405152
05:28:54 1088 8192000000 10054679952 10444763136 1103409504 11.870 100 94 181 1 1174182112 53235568 17537040
05:29:55 1176 8192000000 10047135888 10440421376 1092990080 11.771 100 76 139 4 1171798736 63898400 14910256
05:30:57 5453 8192000000 10019621200 10408947712 1064857792 11.503 98 57 114 0 1147294032 70814464 11621776
05:32:03 1256 8192000000 10025402672 10409574400 1070255280 11.555 100 86 161 5 1142887312 56829264 15802768
05:33:05 1824 8192000000 9995944016 10376241152 1039905072 11.264 98 76 145 3 1111149104 56706560 14537472
05:34:09 1008 8192000000 9975592784 10343395328 1017323136 11.046 99 94 180 5 1096312160 60905312 18083712
05:35:10 3060 8192000000 10001657136 10379259904 1037949776 11.245 100 77 138 4 1127727408 75813072 13964560
05:36:14 5080 8192000000 9964050400 10342785024 1015251744 11.026 98 70 132 1 1087986176 58091744 14642688
05:37:20 1141 8192000000 10003335792 10376908800 1048723872 11.348 100 91 175 2 1125157664 57863616 18570176
05:38:29 7796 8192000000 10029462576 10409119744 1068197216 11.535 100 83 152 0 1143149504 60020400 14931888
05:39:44 7018 8192000000 10064031392 10452729856 1087918096 11.723 100 80 152 1 1185711424 81940176 15853152
05:41:00 4957 8192000000 10066527904 10457055232 1096519280 11.805 100 64 118 2 1190104672 82085584 11499808
05:42:14 9330 8192000000 10121082368 10508406784 1098134528 11.820 99 76 141 2 1228104976 115288288 14682160
05:43:29 1873 8192000000 10174955760 10564595712 1121744976 12.043 100 74 140 1 1294856672 157330720 15780976
05:44:37 2497 8192000000 10212162960 10609438720 1132862240 12.148 100 75 147 0 1327868000 179038656 15967104
05:45:43 3587 8192000000 10247601632 10639040512 1140895888 12.224 100 81 153 0 1357893904 199530032 17467984
05:46:51 1837 8192000000 10284663600 10689949696 1146701984 12.279 100 89 174 1 1401596960 234724416 20170560
05:47:56 858 8192000000 10330357376 10724593664 1161688848 12.419 100 96 190 1 1439703504 256605600 21409056
05:49:00 1163 8192000000 10358756720 10767851520 1173777344 12.532 100 95 184 1 1480066080 284920640 21368096
05:50:03 3069 8192000000 10388856048 10800128000 1181158864 12.601 100 83 160 3 1497418736 297954656 18305216
05:51:09 1523 8192000000 10391671984 10829246464 1175739888 12.550 100 95 186 0 1505782752 307678304 22364560
05:52:14 1044 8192000000 10408331984 10826268672 1188872672 12.673 100 93 173 0 1516152592 307520352 19759568
05:53:20 3646 8192000000 10441802992 10885488640 1189380880 12.678 100 82 162 0 1553453824 345100544 18972400
05:54:27 1261 8192000000 10462590800 10888527872 1192015456 12.702 100 89 176 0 1569200480 356723280 20461744
05:55:31 964 8192000000 10490800624 10928418816 1193951248 12.720 100 95 184 0 1599930080 385279712 20699120
05:56:34 2676 8192000000 10501183168 10922344448 1186477296 12.651 100 80 155 1 1612690336 407662272 18550768
05:57:42 954 8192000000 10567030752 10986442752 1189292240 12.677 100 96 185 1 1674037360 462113488 22631632
05:58:47 1516 8192000000 10576339440 10996568064 1208797344 12.858 100 96 185 0 1680418192 448993392 22627456
05:59:50 1652 8192000000 10574393488 11017789440 1201365120 12.789 100 68 136 0 1681553296 463442656 16745520
06:01:01 3917 8192000000 10560358800 11028107264 1182764384 12.616 100 56 110 1 1662470384 464887840 14818160
06:02:32 2699 8192000000 10588158224 11015827456 1172917136 12.524 100 75 143 1 1697473056 506313440 18242480
06:03:41 7708 8192000000 10589774640 11037650944 1172166992 12.517 100 63 123 1 1697080032 508529008 16384032
06:04:52 7354 8192000000 10514078864 11003408384 1145883008 12.271 99 49 98 0 1628817056 470112928 12821120
06:06:03 9343 8192000000 10516144368 10983497728 1151992416 12.328 100 34 68 0 1613833232 452473584 9367232
06:07:25 18891 8192000000 10429491504 10946600960 1133358384 12.153 98 37 72 0 1548862880 406563808 8940688
06:08:53 32951 8192000000 10311738432 10826752000 1161696896 12.419 100 25 50 0 1463188192 295051184 6440112
06:10:28 20915 8192000000 10230025296 10738233344 1139798464 12.214 99 19 37 0 1398215456 254149728 4267264
06:11:50 18462 8192000000 10163371488 10659962880 1123551680 12.061 98 32 61 1 1241919200 111445456 6922064
06:13:10 31135 8192000000 10164423040 10606321664 1138553888 12.202 99 26 52 0 1257198704 112916304 5728512
06:14:43 45789 8192000000 10084922528 10538627072 1093887984 11.780 95 12 22 0 1135141776 39016576 2237216
06:16:30 21824 8192000000 10148435152 10580996096 1151055632 12.319 100 41 72 1 1248984928 89373808 8555488
06:18:01 91691 8192000000 10067731712 10549215232 1077696784 11.626 94 12 24 0 1168003808 87717664 2589360
06:20:33 67876 8192000000 10098241152 10528034816 1109841360 11.931 97 20 39 1 1142309456 28903728 3564368
06:22:48 79717 8192000000 10086917680 10550984704 1134341776 12.162 99 37 70 0 1209614752 67332368 7940608
06:25:09 66948 8192000000 10068449888 10546360320 1122383200 12.050 98 12 20 1 1164456080 39539296 2533584
06:27:22 81000 8192000000 10102420784 10560569344 1148102592 12.292 100 63 119 0 1213053184 52258848 12691744
06:30:17 51896 8192000000 10116999504 10555506688 1136464240 12.182 99 45 77 0 1214528176 68383744 9680192
06:32:25 3157 8192000000 10109502128 10541449216 1151913216 12.327 100 54 108 0 1204444384 41367824 11163344
06:33:33 41884 8192000000 10158786800 10597691392 1137905360 12.196 98 39 76 1 1226067472 79276576 8885536
06:35:45 27406 8192000000 10217947136 10672295936 1151965504 12.328 100 56 102 0 1318477248 155430544 11081200
06:37:24 85130 8192000000 10180142352 10637070336 1145450736 12.267 99 23 45 0 1286364384 135841088 5072560
06:39:53 53255 8192000000 10200535952 10655911936 1124121472 12.066 97 14 28 0 1272805472 145497920 3186080
06:41:47 53514 8192000000 10128126000 10647175168 1127217808 12.095 98 24 49 1 1277759904 144017072 6525024
06:43:41 53936 8192000000 10138778048 10597814272 1124133696 12.066 98 30 60 0 1217232512 86274320 6824496
06:45:35 93480 8192000000 10087624032 10611449856 1122083712 12.047 98 19 37 0 1212834704 86881360 3869632
06:48:09 44440 8192000000 10103993568 10542338048 1095355760 11.794 96 43 84 0 1156941744 52945712 8640272
06:49:57 15030 8192000000 10064883168 10499448832 1105130624 11.886 99 37 69 1 1153626640 41334272 7161744
06:51:13 90644 8192000000 10089603392 10518175744 1101569520 11.853 98 32 62 0 1150954224 42917168 6467536
06:53:44 10030 8192000000 10106620880 10537984000 1114657120 11.976 99 53 106 0 1189197440 64218400 10321920
06:55:00 154520 8192000000 10083009360 10595733504 1142073008 12.235 100 22 42 0 1241678528 93992688 5612832
06:58:42 47679 8192000000 10104688832 10539229184 1137149200 12.189 100 48 95 0 1211533072 64402544 9981328
07:00:30 43078 8192000000 10047446256 10539192320 1127594832 12.099 100 16 32 0 1168637088 37403344 3638912
07:02:26 57308 8192000000 10087321488 10556829696 1118794320 12.016 98 19 38 1 1166575376 44462032 3319024
07:05:30 61429 8192000000 10120035760 10588958720 1113894576 11.969 97 18 36 0 1218331936 100426944 4010416
07:07:35 39259 8192000000 10109887040 10646380544 1117905744 12.007 99 15 30 0 1243311520 121914720 3491056
07:09:34 31640 8192000000 10103231952 10582327296 1123999984 12.065 99 24 45 0 1229398992 100613584 4785424
07:11:12 40057 8192000000 10109220304 10554269696 1119343664 12.021 98 23 44 1 1175833344 52101600 4388080
07:12:54 38955 8192000000 10124711136 10591477760 1143006224 12.244 99 35 68 1 1244432304 94159264 7266816
07:14:54 56501 8192000000 10128329552 10608345088 1079595440 11.644 94 26 52 0 1211135840 125562064 5978336
07:16:55 37192 8192000000 10119239696 10594344960 1132911984 12.149 100 20 40 0 1219379472 82266816 4200672
07:18:52 62473 8192000000 10097607456 10540498944 1127591808 12.099 99 42 80 0 1181898656 46280320 8026528
07:20:56 5837 8192000000 10129290224 10557489152 1135896896 12.177 100 58 116 1 1226136512 79001008 11238608
07:22:04 68882 8192000000 10103333200 10571186176 1134648736 12.165 100 35 70 1 1203194144 61589840 6955568
07:24:53 60002 8192000000 10051347808 10515582976 1109623952 11.929 97 16 32 0 1135468960 22828112 3016896
07:27:00 62208 8192000000 10074429792 10522591232 1106882752 11.903 97 18 35 0 1144420688 34288960 3248976
07:29:03 60071 8192000000 10072021392 10556628992 1104855776 11.884 97 16 32 0 1181988832 73432272 3700784
07:31:04 108035 8192000000 10044618944 10527199232 1090110336 11.744 96 9 18 0 1144148576 52330688 1707552
07:33:53 30364 8192000000 10096056112 10542587904 1126785632 12.091 99 30 60 0 1197988608 64951200 6251776
07:35:23 47824 8192000000 10072632144 10505764864 1090520720 11.748 96 33 65 0 1142645872 45637088 6488064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-12-2022 07:38:36 to 19-12-2022 07:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2022 07:38:53 to 19-12-2022 07:38:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          223011
MON$OLDEST_ACTIVE               223012
MON$OLDEST_SNAPSHOT             223012
MON$NEXT_TRANSACTION            223012
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                       2087648
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.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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'

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