Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2023 01:40
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 15356972 kB
MemAvailable 30443140 kB
Buffers 0 kB
Cached 16382972 kB
SwapCached 1644 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-01-24 01:38:37
DTS_END                         2023-01-24 01:38:37.9950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47810
TRN_ID                          240789
ATT_ID                          662
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: 9207.25 21315 1104870
customer order: creation 177.69 349 21323
customer order: refuse 14.94 338 1793
order to supplier: creation 46.19 1223 5543
order to supplier: removal 3.38 104919 405
invoice (draft): creation 46.83 3241 5620
invoice (draft): removal 50.73 786 6088
invoice accept: apply 41.75 17532 5010
invoice accept: cancel 46.46 803 5575
customer reserve: creation 3876.48 173 465178
customer reserve: removal 4598.52 61 551822
realization accept: apply 84.48 179 10137
realization accept: cancel 87.75 102 10530
payment from customer: creation 60.24 86 7229
payment from customer: removal 5.68 72 682
payment to supplier: creation 50.27 119 6032
payment to supplier: removal 3.38 47 405
service: total inventory turnovers 5.02 920 602
service: total monetary turnovers 6.65 27 798
service: aggregate perf. data 0.78 25271 94
service: refresh index statistics 0.03 270061 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-01-2023 01:40:41 to 24-01-2023 01:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1619
TEST_TIME 2 3108
TEST_TIME 3 4635
TEST_TIME 4 4832
TEST_TIME 5 4763
TEST_TIME 6 4894
TEST_TIME 7 4854
TEST_TIME 8 4927
TEST_TIME 9 5016
TEST_TIME 10 5457
TEST_TIME 11 5445
TEST_TIME 12 5813
TEST_TIME 13 6149
TEST_TIME 14 6402
TEST_TIME 15 6497
TEST_TIME 16 6660
TEST_TIME 17 7213
TEST_TIME 18 7720
TEST_TIME 19 7866
TEST_TIME 20 8507
TEST_TIME 21 8692
TEST_TIME 22 9029
TEST_TIME 23 9369
TEST_TIME 24 9285
TEST_TIME 25 9116
TEST_TIME 26 8978
TEST_TIME 27 9970
TEST_TIME 28 10269
TEST_TIME 29 10884
TEST_TIME 30 11208
TEST_TIME 31 11002
TEST_TIME 32 10716
TEST_TIME 33 10498
TEST_TIME 34 10641
TEST_TIME 35 10462
TEST_TIME 36 10346
TEST_TIME 37 10230
TEST_TIME 38 10237
TEST_TIME 39 10127
TEST_TIME 40 10006
TEST_TIME 41 9971
TEST_TIME 42 9896
TEST_TIME 43 9968
TEST_TIME 44 9989
TEST_TIME 45 9958
TEST_TIME 46 9789
TEST_TIME 47 9720
TEST_TIME 48 9602
TEST_TIME 49 9582
TEST_TIME 50 9557
TEST_TIME 51 9415
TEST_TIME 52 9421
TEST_TIME 53 9405
TEST_TIME 54 9378
TEST_TIME 55 9545
TEST_TIME 56 9556
TEST_TIME 57 9543
TEST_TIME 58 9567
TEST_TIME 59 9578
TEST_TIME 60 9569
TEST_TIME 61 9618
TEST_TIME 62 9637
TEST_TIME 63 9733
TEST_TIME 64 9694
TEST_TIME 65 9717
TEST_TIME 66 9693
TEST_TIME 67 9716
TEST_TIME 68 9638
TEST_TIME 69 9565
TEST_TIME 70 9577
TEST_TIME 71 9534
TEST_TIME 72 9466
TEST_TIME 73 9485
TEST_TIME 74 9425
TEST_TIME 75 9375
TEST_TIME 76 9331
TEST_TIME 77 9289
TEST_TIME 78 9354
TEST_TIME 79 9386
TEST_TIME 80 9368
TEST_TIME 81 9371
TEST_TIME 82 9500
TEST_TIME 83 9424
TEST_TIME 84 9485
TEST_TIME 85 9445
TEST_TIME 86 9494
TEST_TIME 87 9562
TEST_TIME 88 9582
TEST_TIME 89 9645
TEST_TIME 90 9589
TEST_TIME 91 9526
TEST_TIME 92 9501
TEST_TIME 93 9508
TEST_TIME 94 9512
TEST_TIME 95 9449
TEST_TIME 96 9414
TEST_TIME 97 9388
TEST_TIME 98 9368
TEST_TIME 99 9370
TEST_TIME 100 9336
TEST_TIME 101 9338
TEST_TIME 102 9340
TEST_TIME 103 9315
TEST_TIME 104 9309
TEST_TIME 105 9307
TEST_TIME 106 9403
TEST_TIME 107 9376
TEST_TIME 108 9413
TEST_TIME 109 9370
TEST_TIME 110 9416
TEST_TIME 111 9449
TEST_TIME 112 9435
TEST_TIME 113 9432
TEST_TIME 114 9373
TEST_TIME 115 9371
TEST_TIME 116 9327
TEST_TIME 117 9317
TEST_TIME 118 9288
TEST_TIME 119 9260
TEST_TIME 120 9208

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-01-2023 01:40:45 to 24-01-2023 01:40:48.

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 1535979 1535979 0 0.00 0 33366 6 0 0
doc_list_biud 1535979 1535979 0 0.00 0 56081 28 0 0
sp_add_invoice_to_stock 5110 5010 100 1.95 40 154111 17532 0 100
sp_cancel_adding_invoice 5575 5575 0 0.00 5 71166 804 0 0
sp_cancel_client_order 1793 1793 0 0.00 4 20010 338 0 0
sp_cancel_customer_reserve 551822 551822 0 0.00 0 56085 62 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 3714 73 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 956 47 0 0
sp_cancel_supplier_invoice 6094 6088 6 0.09 2 15955 786 0 6
sp_cancel_supplier_order 405 405 0 0.00 7 1042228 104920 0 0
sp_cancel_write_off 10530 10530 0 0.00 1 12561 103 0 0
sp_client_order 21323 21323 0 0.00 3 66019 349 0 0
sp_customer_reserve 465178 465178 0 0.00 0 78450 174 0 0
sp_fill_shopping_cart 32476 32476 0 0.00 1 26758 37 0 0
sp_fill_shopping_cart_clo_res 465178 465178 0 0.00 0 15896 15 0 0
sp_get_clo_for_invoice 5009 5009 0 0.00 0 48777 305 0 0
sp_kill_cost_storno 17192 17192 0 0.00 0 12559 51 0 0
sp_kill_qstorno_ret_qs2qd 1120667 1120667 0 0.00 0 56031 33 0 0
sp_kill_qty_storno 591359 591359 0 0.00 0 56080 71 0 0
sp_lock_selected_doc 20933 20933 0 0.00 0 5932 5 0 0
sp_make_cost_storno 28407 28407 0 0.00 0 33351 56 0 0
sp_multiply_rows_for_pdistr 28407 28407 0 0.00 0 7493 22 0 0
sp_multiply_rows_for_qdistr 21323 21323 0 0.00 0 65733 164 0 0
sp_pay_from_customer 7229 7229 0 0.00 4 11612 86 0 0
sp_pay_to_supplier 6032 6032 0 0.00 4 17596 120 0 0
sp_payment_common 13261 13261 0 0.00 1 10651 23 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 20004 314 0 0
sp_qd_handle_on_invoice_upd_sts 10584 10584 0 0.00 0 19612 301 0 0
sp_qd_handle_on_reserve_upd_sts 10137 10137 0 0.00 0 23161 68 0 0
sp_reserve_write_off 10137 10137 0 0.00 7 23171 180 0 0
sp_supplier_invoice 5620 5620 0 0.00 5 71599 3242 0 0
sp_supplier_order 5543 5543 0 0.00 5 25879 1223 0 0
srv_aggregate_perf_data 94 94 0 0.00 2853 674841 25272 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1682 124517 41792 0 0
srv_find_qd_qs_mism 1056500 1056500 0 0.00 0 12378 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 42 28761 921 0 0
srv_make_money_saldo 798 798 0 0.00 1 2050 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 135981 404293 270062 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5010 5010 0 0.00 0 8801 138 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4306 108 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 2864 14 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 5315 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1635 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 285 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 1909 15 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 26802 1544 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 28565 33 0 0
t$perf-norm:sp_client_order 21323 21323 0 0.00 0 6190 15 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 40790 49 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 30837 10 0 0
t$perf-norm:sp_pay_to_supplier 6032 6032 0 0.00 0 5295 9 0 0
t$perf-norm:sp_reserve_write_off 10137 10137 0 0.00 0 3175 6 0 0
t$perf-norm:sp_supplier_invoice 5620 5620 0 0.00 0 13872 14 0 0
t$perf-norm:sp_supplier_order 5543 5543 0 0.00 0 9279 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 438 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 124 3 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 529 16 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 2065 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10218 10018 200 1.95 0 3987 19 0 200
v_all_customers 39266 39266 0 0.00 0 25 0 0 0
v_all_suppliers 31452 31452 0 0.00 0 19 1 0 0
v_all_wares 42646 42646 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 149 6 0 0
v_cancel_client_order 3586 3586 0 0.00 0 3986 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3688 11 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 948 4 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 940 5 0 0
v_cancel_supplier_invoice 744 732 12 1.61 0 939 18 0 12
v_cancel_supplier_order 810 810 0 0.00 0 2374 11 0 0
v_cancel_write_off 2026 2026 0 0.00 0 1548 13 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 109 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2300 14 0 0
v_max_id_ord_sup 5612 5612 0 0.00 0 7831 9 0 0
v_max_non_paid_invoice 1459 1459 0 0.00 0 580 2 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 866 3 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 2569 7 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 1170 5 0 0
v_min_id_ord_sup 5612 5612 0 0.00 0 6677 9 0 0
v_min_non_paid_invoice 6032 6032 0 0.00 0 10647 19 0 0
v_min_non_paid_realizn 7229 7229 0 0.00 0 9026 16 0 0
v_our_firm 10604 10604 0 0.00 0 16 1 0 0
v_reserve_write_off 20274 20274 0 0.00 0 4343 6 0 0
x_lock_depdocs_on_canc_invoice 11297 11297 0 0.00 0 15320 126 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 12851 371 0 0
x_make_qstorno_1000_1200 5543 5543 0 0.00 1 25868 1160 0 0
x_make_qstorno_1200_2000 5620 5620 0 0.00 2 71528 3099 0 0
x_make_qstorno_2100_3300 465178 465178 0 0.00 0 78448 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-01-2023 01:40:49 to 24-01-2023 01:40:53.

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
23:08:41 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:42 29923 8192000000 9894901056 10203103232 976487152 10.650 100 94 188 0 1007598224 6114784 24996288
23:11:18 20108 8192000000 9937618640 10256596992 1018901376 11.061 100 88 176 0 1049796384 8246192 22648816
23:12:42 9865 8192000000 9973879440 10305335296 1054274640 11.402 100 83 166 0 1085505120 9731488 21498992
23:14:06 6896 8192000000 10026690096 10372411392 1115181600 11.981 100 78 157 0 1145146704 11170336 18794768
23:15:16 3349 8192000000 10069515680 10420240384 1151254416 12.321 100 82 162 0 1183738224 13712304 18771504
23:16:22 2385 8192000000 10108661184 10471809024 1198535648 12.763 100 92 181 1 1232429104 13365408 20528048
23:17:30 2094 8192000000 10156383152 10529787904 1242329008 13.168 100 86 172 0 1275869200 14334576 19205616
23:18:34 1540 8192000000 10196666592 10576486400 1279699504 13.510 100 89 178 0 1312013408 12709184 19604720
23:19:38 1240 8192000000 10222753024 10605412352 1305924416 13.749 100 89 178 0 1340571648 14956528 19690704
23:20:40 27986 8192000000 10194961664 10605764608 1308496224 13.772 100 33 65 0 1321945792 6164240 7285328
23:22:10 6981 8192000000 10212988368 10630893568 1325199200 13.924 100 21 42 0 1339863776 9929120 4735456
23:23:17 14888 8192000000 10212851088 10636009472 1333318016 13.997 100 15 30 0 1342630192 5706912 3605264
23:24:32 19714 8192000000 10227034736 10648731648 1340310928 14.060 100 24 46 1 1350565424 5169424 5085072
23:26:09 5569 8192000000 10236767840 10655408128 1341945136 14.075 100 32 64 1 1356937568 7477504 7514928
23:27:15 6727 8192000000 10214653728 10632663040 1312383552 13.808 98 24 48 0 1324077936 6438160 5256224
23:28:23 8678 8192000000 10225038624 10645123072 1332215024 13.987 100 22 43 0 1343597280 6184208 5198048
23:29:33 67361 8192000000 10172221856 10584440832 1215793568 12.923 92 35 69 0 1229895824 6257136 7845120
23:31:43 6588 8192000000 10145359280 10546335744 1223625664 12.995 97 17 34 0 1232677488 5062464 3989360
23:32:53 8185 8192000000 10145778048 10540220416 1227671824 13.033 98 27 54 1 1239874160 5962144 6240192
23:34:02 68755 8192000000 10143245200 10553745408 1226643232 13.023 97 29 59 1 1238768224 5319776 6805216
23:36:12 4838 8192000000 10183200176 10587791360 1284572880 13.555 100 17 35 0 1294008624 5654192 3781552
23:37:17 5582 8192000000 10197627232 10611122176 1305411072 13.744 100 34 66 0 1318235968 5251888 7573008
23:38:26 10351 8192000000 10211123648 10631516160 1323563024 13.909 100 11 22 0 1330410896 4462192 2385680
23:39:41 26638 8192000000 10241735920 10655617024 1339984752 14.057 100 54 108 1 1362200656 10404208 11811696
23:41:15 5164 8192000000 10263463744 10676252672 1349336976 14.142 100 55 110 1 1377376992 15310032 12729984
23:42:25 14408 8192000000 10293342352 10708590592 1369097744 14.319 100 51 101 0 1401366096 21393808 10874544
23:43:43 1712 8192000000 10310140480 10724560896 1385187312 14.463 100 77 151 1 1423992528 20059968 18745248
23:44:47 15206 8192000000 10311967936 10739187712 1395917952 14.559 100 39 79 1 1428679536 23082656 9678928
23:46:05 32991 8192000000 10329888144 10762211328 1410481024 14.688 100 42 84 0 1447044688 25236992 11326672
23:47:54 3088 8192000000 10354977216 10777149440 1422203328 14.792 100 58 114 0 1462916032 27000576 13712128
23:48:59 6331 8192000000 10364108784 10785173504 1424869888 14.816 100 65 129 0 1475854064 35039888 15944288
23:50:18 3928 8192000000 10360255856 10785402880 1408626896 14.672 99 46 90 1 1458457200 38259904 11570400
23:51:33 9592 8192000000 10345327328 10766004224 1409020800 14.675 100 48 94 0 1453579952 31800592 12758560
23:52:45 4847 8192000000 10356839776 10776944640 1415869392 14.736 100 70 138 1 1466414512 32914016 17631104
23:53:56 7177 8192000000 10347583392 10754363392 1376335936 14.384 98 77 149 2 1435488080 40687792 18464352
23:55:07 1679 8192000000 10346222784 10759819264 1381884448 14.433 100 74 148 0 1450638416 49968784 18785184
23:56:14 1763 8192000000 10364982624 10775687168 1387630000 14.485 100 82 162 1 1468506864 60370736 20506128
23:57:20 7696 8192000000 10329683280 10743504896 1346392512 14.115 98 60 119 0 1418105648 56666960 15046176
23:58:34 3298 8192000000 10326211584 10737926144 1370570496 14.332 100 77 151 0 1432793312 43504400 18718416
23:59:39 11989 8192000000 10310688144 10729517056 1352174064 14.167 99 35 65 0 1400471936 39217504 9080368
00:00:54 7604 8192000000 10334967728 10769899520 1386772848 14.477 100 38 76 2 1446482624 49224736 10485040
00:02:07 22460 8192000000 10331496384 10769391616 1380861152 14.424 99 26 52 0 1428631440 40728528 7041760
00:03:31 9882 8192000000 10332456048 10761854976 1377443360 14.394 100 36 72 0 1436005344 48461040 10100944
00:04:43 13578 8192000000 10355941440 10787995648 1389486688 14.501 100 32 65 0 1456711424 57700960 9523776
00:06:10 35848 8192000000 10298433056 10736148480 1348946256 14.138 99 14 28 1 1387513328 34240320 4326752
00:07:48 53271 8192000000 10264798208 10713300992 1321312128 13.889 98 7 14 0 1348283760 25124544 1847088
00:09:56 100655 8192000000 10301407568 10729521152 1369688992 14.324 100 37 73 1 1387740912 10403600 7648320
00:13:02 92312 8192000000 10272341456 10714968064 1337744992 14.037 97 17 34 0 1352292096 10803648 3743456
00:15:35 16485 8192000000 10302373040 10733088768 1379430192 14.411 100 41 82 1 1397751264 8880464 9440608
00:16:54 42949 8192000000 10291871632 10734313472 1382628672 14.440 100 42 85 0 1400788336 8699040 9460624
00:18:55 53369 8192000000 10293818800 10750500864 1392482528 14.528 100 34 67 1 1411445168 10797024 8165616
00:20:49 43701 8192000000 10304999648 10758848512 1396908096 14.567 100 18 36 0 1416252192 14887696 4456400
00:22:34 54217 8192000000 10312414736 10771021824 1399728400 14.593 100 14 28 0 1416868800 14143808 2996592
00:24:42 47539 8192000000 10287343840 10750951424 1382466864 14.439 99 11 22 0 1398608576 13187520 2954192
00:26:33 15090 8192000000 10297821472 10763804672 1398661280 14.583 100 12 24 0 1411647408 10562112 2424016
00:27:49 76614 8192000000 10288184864 10755358720 1359847888 14.236 97 11 22 1 1370827760 8188624 2791248
00:30:06 41370 8192000000 10281420976 10747359232 1369871824 14.326 98 16 32 0 1392056704 18108112 4076768
00:31:55 72104 8192000000 10308264144 10772131840 1401180960 14.606 100 17 34 0 1419172320 14294832 3696528
00:34:09 70912 8192000000 10279665120 10741927936 1339183184 14.050 96 4 8 0 1356756704 16668640 904880
00:36:33 47537 8192000000 10299905888 10764533760 1382677296 14.440 99 25 50 0 1402295984 14138944 5479744
00:38:31 37587 8192000000 10288632112 10734252032 1337831024 14.038 96 45 91 1 1358362288 10683072 9848192
00:40:09 88528 8192000000 10273473088 10723549184 1285839776 13.566 93 15 30 0 1299033824 10183696 3010352
00:43:37 47152 8192000000 10281281792 10717609984 1339378448 14.052 98 30 56 1 1354247744 8670192 6199104
00:45:27 60858 8192000000 10273662224 10709917696 1310317264 13.789 96 24 48 0 1324586400 9136912 5132224
00:47:40 58486 8192000000 10274346400 10726895616 1342501200 14.080 98 17 34 0 1357232128 10944656 3786272
00:49:49 24492 8192000000 10262435056 10726645760 1362873488 14.263 100 10 20 0 1382218384 17246752 2098144
00:51:18 47414 8192000000 10235054560 10694504448 1300679472 13.701 96 6 12 0 1318720864 16838880 1202512
00:53:05 64204 8192000000 10266264496 10714767360 1346771920 14.118 99 21 42 0 1364969264 13506848 4690496
00:55:10 44968 8192000000 10265891968 10723213312 1341520368 14.071 98 21 41 0 1362149376 15479616 5149392
00:57:09 50809 8192000000 10267624192 10714374144 1341592368 14.072 98 25 50 0 1363012112 15115072 6304672
00:59:01 16357 8192000000 10283260192 10725896192 1356828400 14.209 99 41 82 0 1379698720 13668480 9201840
01:00:19 60660 8192000000 10263438144 10724110336 1347800832 14.128 99 15 30 0 1368791984 17495392 3495760
01:02:24 50404 8192000000 10278573264 10721218560 1331976992 13.985 97 16 30 0 1347097168 11479648 3640528
01:04:39 55040 8192000000 10269577008 10728603648 1357767280 14.217 99 20 40 0 1374824240 12451728 4605232
01:06:35 75651 8192000000 10281897232 10751500288 1361544480 14.251 99 12 24 0 1379302912 15271968 2486464
01:08:51 124516 8192000000 10303036000 10761183232 1394405616 14.545 100 33 66 2 1413026784 10998256 7622912
01:11:56 47986 8192000000 10298023472 10765516800 1403957424 14.630 100 13 26 1 1423193664 16088784 3147456
01:13:48 79535 8192000000 10298824176 10762526720 1389062864 14.498 99 9 18 0 1400966816 9771936 2132016
01:16:40 81031 8192000000 10301370752 10769874944 1385891568 14.469 99 11 20 1 1400056768 11719376 2445824
01:19:04 61109 8192000000 10298197232 10767572992 1402272016 14.615 100 14 28 0 1414597488 9001136 3324336
01:21:07 36767 8192000000 10299433088 10751520768 1382827728 14.442 99 45 90 0 1401571712 8432464 10311520
01:22:48 80089 8192000000 10297267344 10765717504 1376640400 14.387 98 19 38 0 1397687888 16637536 4409952
01:25:19 31962 8192000000 10311817648 10750357504 1357140592 14.212 97 14 27 0 1367566688 7520272 2905824
01:26:52 60282 8192000000 10261478688 10700976128 1311629872 13.801 95 19 38 0 1325799728 9687568 4482288
01:28:53 63433 8192000000 10261513408 10730598400 1310891968 13.794 96 18 36 0 1330095296 15320960 3882368
01:30:58 91612 8192000000 10287905152 10730217472 1328796816 13.956 97 30 60 0 1345218160 9656560 6764784
01:33:31 52812 8192000000 10243686784 10694656000 1303749184 13.729 96 20 38 0 1318090688 10102176 4239328
01:35:32 28115 8192000000 10242949872 10688262144 1328674560 13.955 98 25 50 0 1344227872 10110752 5442560
01:37:08 61353 8192000000 10248513552 10700574720 1330643248 13.973 98 18 36 0 1346907696 12029856 4234592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-01-2023 01:40:55 to 24-01-2023 01:41:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 24-01-2023 01:41:11 to 24-01-2023 01:41:13.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          238622
MON$OLDEST_ACTIVE               242038
MON$OLDEST_SNAPSHOT             242038
MON$NEXT_TRANSACTION            242038
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-03 16:41:55.3470
MON$PAGES                       2030432
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:381: 
:382: mscs-test-161.budzdorov.ru    Tue Jan 24 01:38:56 2023
:383:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:384: 
--- end of diff output ---

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

24.01.23 01:41:15. There are 3 logs in /var/tmp/logs-oltp30 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
2023-01-24 01:41:17 No severe PSQL-related problems occured
24.01.23 01:41:19. End of report.