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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15491924 kB
MemAvailable 30380644 kB
Buffers 0 kB
Cached 16206272 kB
SwapCached 2384 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-16 22:38:35
DTS_END                         2023-01-16 22:38:35.6270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50348
TRN_ID                          236540
ATT_ID                          648
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: 9345.67 24035 1121481
customer order: creation 167.38 379 20086
customer order: refuse 14.90 377 1788
order to supplier: creation 44.64 1276 5357
order to supplier: removal 3.54 103515 425
invoice (draft): creation 45.93 2967 5512
invoice (draft): removal 54.23 798 6508
invoice accept: apply 42.28 17942 5074
invoice accept: cancel 48.91 744 5869
customer reserve: creation 3878.51 178 465421
customer reserve: removal 4750.21 60 570025
realization accept: apply 81.83 197 9820
realization accept: cancel 85.51 114 10261
payment from customer: creation 59.67 99 7160
payment from customer: removal 5.63 61 676
payment to supplier: creation 47.96 136 5755
payment to supplier: removal 3.12 58 374
service: total inventory turnovers 4.77 1103 572
service: total monetary turnovers 5.85 30 702
service: aggregate perf. data 0.77 21179 92
service: refresh index statistics 0.03 329495 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-01-2023 22:39:36 to 16-01-2023 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2752
TEST_TIME 2 3398
TEST_TIME 3 3798
TEST_TIME 4 4134
TEST_TIME 5 4500
TEST_TIME 6 4443
TEST_TIME 7 4752
TEST_TIME 8 4825
TEST_TIME 9 4835
TEST_TIME 10 4841
TEST_TIME 11 5364
TEST_TIME 12 5446
TEST_TIME 13 5774
TEST_TIME 14 6076
TEST_TIME 15 6883
TEST_TIME 16 7487
TEST_TIME 17 7289
TEST_TIME 18 7711
TEST_TIME 19 7725
TEST_TIME 20 8269
TEST_TIME 21 8809
TEST_TIME 22 8706
TEST_TIME 23 8845
TEST_TIME 24 9339
TEST_TIME 25 9466
TEST_TIME 26 11203
TEST_TIME 27 10955
TEST_TIME 28 10576
TEST_TIME 29 10340
TEST_TIME 30 10301
TEST_TIME 31 10177
TEST_TIME 32 10177
TEST_TIME 33 10107
TEST_TIME 34 10099
TEST_TIME 35 10099
TEST_TIME 36 9975
TEST_TIME 37 9955
TEST_TIME 38 9808
TEST_TIME 39 9766
TEST_TIME 40 9836
TEST_TIME 41 9729
TEST_TIME 42 9608
TEST_TIME 43 9570
TEST_TIME 44 9390
TEST_TIME 45 9267
TEST_TIME 46 9466
TEST_TIME 47 9396
TEST_TIME 48 9416
TEST_TIME 49 9392
TEST_TIME 50 9431
TEST_TIME 51 9444
TEST_TIME 52 9542
TEST_TIME 53 9561
TEST_TIME 54 9527
TEST_TIME 55 9528
TEST_TIME 56 9491
TEST_TIME 57 9636
TEST_TIME 58 9677
TEST_TIME 59 9663
TEST_TIME 60 9532
TEST_TIME 61 9539
TEST_TIME 62 9475
TEST_TIME 63 9426
TEST_TIME 64 9412
TEST_TIME 65 9370
TEST_TIME 66 9335
TEST_TIME 67 9289
TEST_TIME 68 9325
TEST_TIME 69 9303
TEST_TIME 70 9325
TEST_TIME 71 9417
TEST_TIME 72 9466
TEST_TIME 73 9460
TEST_TIME 74 9444
TEST_TIME 75 9493
TEST_TIME 76 9435
TEST_TIME 77 9508
TEST_TIME 78 9484
TEST_TIME 79 9535
TEST_TIME 80 9438
TEST_TIME 81 9393
TEST_TIME 82 9363
TEST_TIME 83 9341
TEST_TIME 84 9346
TEST_TIME 85 9362
TEST_TIME 86 9303
TEST_TIME 87 9308
TEST_TIME 88 9303
TEST_TIME 89 9244
TEST_TIME 90 9233
TEST_TIME 91 9336
TEST_TIME 92 9344
TEST_TIME 93 9347
TEST_TIME 94 9374
TEST_TIME 95 9373
TEST_TIME 96 9374
TEST_TIME 97 9374
TEST_TIME 98 9399
TEST_TIME 99 9425
TEST_TIME 100 9448
TEST_TIME 101 9478
TEST_TIME 102 9509
TEST_TIME 103 9514
TEST_TIME 104 9540
TEST_TIME 105 9471
TEST_TIME 106 9456
TEST_TIME 107 9407
TEST_TIME 108 9397
TEST_TIME 109 9389
TEST_TIME 110 9345
TEST_TIME 111 9332
TEST_TIME 112 9303
TEST_TIME 113 9259
TEST_TIME 114 9250
TEST_TIME 115 9317
TEST_TIME 116 9316
TEST_TIME 117 9289
TEST_TIME 118 9270
TEST_TIME 119 9372
TEST_TIME 120 9352

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2023 22:39:40 to 16-01-2023 22:39:44.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1552559 1552559 0 0.00 0 37287 6 0 0
doc_list_biud 1552559 1552559 0 0.00 0 34340 28 0 0
sp_add_invoice_to_stock 5206 5074 132 2.53 43 203712 17943 0 132
sp_cancel_adding_invoice 5869 5869 0 0.00 4 79039 744 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 10887 378 0 0
sp_cancel_customer_reserve 570025 570025 0 0.00 0 31979 61 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 5744 62 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 4575 59 0 0
sp_cancel_supplier_invoice 6519 6508 11 0.16 1 25600 798 0 11
sp_cancel_supplier_order 425 425 0 0.00 6 1259028 103516 0 0
sp_cancel_write_off 10261 10261 0 0.00 1 12911 114 0 0
sp_client_order 20086 20086 0 0.00 3 36110 379 0 0
sp_customer_reserve 465421 465421 0 0.00 0 42750 179 0 0
sp_fill_shopping_cart 30955 30955 0 0.00 1 10953 36 0 0
sp_fill_shopping_cart_clo_res 465408 465408 0 0.00 0 13168 15 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 33096 362 0 0
sp_kill_cost_storno 17180 17180 0 0.00 0 37262 57 0 0
sp_kill_qstorno_ret_qs2qd 1157244 1157244 0 0.00 0 31935 33 0 0
sp_kill_qty_storno 609757 609757 0 0.00 0 34337 71 0 0
sp_lock_selected_doc 20617 20617 0 0.00 0 7330 7 0 0
sp_make_cost_storno 27796 27796 0 0.00 0 24472 56 0 0
sp_multiply_rows_for_pdistr 27796 27796 0 0.00 0 19092 28 0 0
sp_multiply_rows_for_qdistr 20086 20086 0 0.00 0 15009 170 0 0
sp_pay_from_customer 7160 7160 0 0.00 4 12670 99 0 0
sp_pay_to_supplier 5755 5755 0 0.00 5 16723 136 0 0
sp_payment_common 12915 12915 0 0.00 1 7419 23 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 10871 340 0 0
sp_qd_handle_on_invoice_upd_sts 10930 10930 0 0.00 0 34333 311 0 0
sp_qd_handle_on_reserve_upd_sts 9820 9820 0 0.00 0 18679 57 0 0
sp_reserve_write_off 9820 9820 0 0.00 6 24496 197 0 0
sp_supplier_invoice 5512 5512 0 0.00 5 76234 2968 0 0
sp_supplier_order 5357 5357 0 0.00 5 39161 1277 0 0
srv_aggregate_perf_data 92 92 0 0.00 2804 316342 21180 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1871 128884 45925 0 0
srv_find_qd_qs_mism 1074176 1074176 0 0.00 0 21224 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 35 43424 1103 0 0
srv_make_money_saldo 702 702 0 0.00 1 4448 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 196720 481975 329496 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 0 15784 161 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 7718 163 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 11458 21 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1988 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3117 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 791 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 6357 38 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 25666 1622 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 2285 11 0 0
t$perf-norm:sp_client_order 20086 20086 0 0.00 0 12827 17 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 16572 23 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 4631 10 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 6379 10 0 0
t$perf-norm:sp_reserve_write_off 9820 9820 0 0.00 0 9585 15 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 12684 21 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 12471 17 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 187 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 89 2 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 5050 36 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 15484 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10386 10122 264 2.54 0 17246 28 0 264
v_all_customers 37158 37158 0 0.00 0 33 0 0 0
v_all_suppliers 30120 30120 0 0.00 0 20 1 0 0
v_all_wares 40172 40172 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1587 13 0 0
v_cancel_client_order 3576 3576 0 0.00 0 4139 9 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 5728 7 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 2730 5 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 4528 8 0 0
v_cancel_supplier_invoice 806 784 22 2.72 0 5808 25 0 22
v_cancel_supplier_order 850 850 0 0.00 0 2671 8 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2765 15 0 0
v_max_id_clo_ord 5357 5357 0 0.00 0 1752 2 0 0
v_max_id_clo_res 928 928 0 0.00 0 7126 28 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 4292 3 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 2436 5 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 2967 4 0 0
v_min_id_clo_ord 5357 5357 0 0.00 0 9152 8 0 0
v_min_id_clo_res 928 928 0 0.00 0 4220 19 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 5931 5 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 5790 17 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 5062 16 0 0
v_our_firm 10012 10012 0 0.00 0 16 1 0 0
v_reserve_write_off 19640 19640 0 0.00 0 5137 9 0 0
x_lock_depdocs_on_canc_invoice 11985 11985 0 0.00 0 11763 116 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 12048 371 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 2 39149 1205 0 0
x_make_qstorno_1200_2000 5512 5512 0 0.00 2 76212 2840 0 0
x_make_qstorno_2100_3300 465421 465421 0 0.00 0 42749 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2023 22:39:45 to 16-01-2023 22:39:49.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:39 2 8192000000 8800005040 8811544576 6401568 0.078 2 1 2 0 6501200 56848 42784
20:09:40 14141 8192000000 9897570144 10207490048 988865248 10.770 100 87 174 0 1016827344 5295248 22666848
20:10:59 24236 8192000000 9944369904 10262982656 1024930240 11.120 100 90 180 0 1056197184 8055440 23211504
20:12:29 16200 8192000000 9977389296 10305908736 1058212240 11.439 100 88 176 0 1089537472 9266336 22058896
20:13:51 10022 8192000000 10028670880 10372947968 1114861296 11.978 100 80 160 0 1143973504 10133648 18978560
20:15:06 7574 8192000000 10084038480 10441187328 1171731312 12.513 100 79 156 1 1201942208 12696752 17514144
20:16:17 3815 8192000000 10127584864 10495393792 1211112096 12.879 100 76 151 0 1241062112 13482512 16467504
20:17:23 1658 8192000000 10158645904 10530406400 1239877568 13.145 100 88 177 1 1274610512 14948560 19784384
20:18:26 4254 8192000000 10173274128 10551746560 1265648496 13.382 100 77 154 0 1295616432 13387584 16580352
20:19:32 985 8192000000 10212020288 10600226816 1296738400 13.666 100 83 165 0 1327565936 12798624 18028912
20:20:34 911 8192000000 10217760672 10610810880 1302980992 13.722 100 78 156 0 1331996880 11273952 17741936
20:21:35 9541 8192000000 10206690096 10610434048 1308880976 13.776 100 30 60 0 1321494848 5585472 7028400
20:22:46 6846 8192000000 10195355376 10614222848 1315629680 13.837 100 26 53 0 1328085664 6636880 5819104
20:23:54 10536 8192000000 10203163680 10624761856 1318036224 13.859 100 30 58 0 1332034304 7566960 6431120
20:25:05 6793 8192000000 10215008528 10633486336 1326285360 13.934 100 19 39 0 1336391216 5627232 4478624
20:26:12 6715 8192000000 10221185696 10643259392 1332744528 13.992 100 15 30 0 1341772032 5234304 3793200
20:27:27 69828 8192000000 10201440928 10620825600 1300198976 13.697 98 30 60 1 1311893520 4665216 7029328
20:29:40 3212 8192000000 10162081808 10564059136 1249382992 13.233 97 31 60 1 1262562080 6354608 6824480
20:30:47 7989 8192000000 10162324224 10570145792 1251084144 13.248 98 25 49 0 1262070736 5277104 5709488
20:31:56 19313 8192000000 10134060928 10535309312 1204755360 12.820 95 26 53 0 1216679152 5269552 6654240
20:33:15 36657 8192000000 10146121088 10547572736 1206219072 12.834 95 28 56 0 1217998000 5515520 6263408
20:34:52 6936 8192000000 10165156624 10576556032 1260547184 13.335 98 31 59 0 1273848128 6256704 7044240
20:36:00 10434 8192000000 10194503600 10606514176 1301303024 13.707 100 21 41 0 1311169472 5017168 4849280
20:37:12 36944 8192000000 10195676208 10619252736 1314444976 13.826 100 25 51 0 1327057040 5435648 7176416
20:39:27 16739 8192000000 10257330720 10670264320 1340499376 14.062 100 50 100 2 1369558848 18336624 10722848
20:40:49 23093 8192000000 10269551664 10693525504 1363406992 14.268 100 31 61 1 1387161392 16707888 7046512
20:42:15 33330 8192000000 10301279088 10725359616 1384226592 14.454 100 45 88 0 1414686160 19542416 10917152
20:44:11 10873 8192000000 10326634432 10750312448 1402313808 14.616 100 53 105 1 1438397568 23468688 12615072
20:45:23 9329 8192000000 10339026560 10764374016 1414677344 14.725 100 61 122 1 1457133408 28037376 14418688
20:46:41 8385 8192000000 10360581344 10790952960 1426740336 14.832 100 50 100 1 1471296416 32350768 12205312
20:47:55 11183 8192000000 10373486816 10800320512 1436650784 14.920 100 55 109 2 1489938224 39310912 13976528
20:49:12 5956 8192000000 10385972992 10812391424 1442656496 14.973 100 51 101 1 1497673344 41955728 13061120
20:50:28 1870 8192000000 10397323472 10818961408 1441348496 14.962 100 73 144 0 1504590528 45416000 17826032
20:51:34 6579 8192000000 10395777008 10821967872 1434506304 14.901 100 68 133 0 1501290208 49687392 17096512
20:52:46 4645 8192000000 10407438256 10824712192 1433427104 14.892 100 76 150 1 1505988896 54600240 17961552
20:53:56 2641 8192000000 10406010976 10832199680 1441211888 14.960 100 69 138 1 1509110224 49452064 18446272
20:55:02 14129 8192000000 10381530144 10818551808 1429129984 14.854 100 40 80 0 1488305024 48539280 10635760
20:56:23 14830 8192000000 10346360576 10782777344 1378462000 14.403 97 34 67 0 1438629232 50526784 9640448
20:57:45 10842 8192000000 10364215824 10783121408 1375124656 14.373 99 58 115 0 1451448768 60819344 15504768
20:58:58 17078 8192000000 10330334128 10758717440 1338157008 14.041 97 37 72 0 1410489520 62298304 10034208
21:00:23 11155 8192000000 10323036672 10749820928 1369744768 14.325 99 31 62 0 1427129808 49163984 8221056
21:01:37 34953 8192000000 10325288224 10765611008 1365122128 14.283 100 31 63 1 1440043136 66058960 8862048
21:03:15 31888 8192000000 10332181344 10766049280 1348698256 14.136 99 24 48 0 1420402224 64205984 7497984
21:05:12 25845 8192000000 10277787648 10711003136 1347087008 14.121 99 47 96 0 1367585456 10674272 9824176
21:06:54 62585 8192000000 10260090000 10694361088 1331946208 13.985 98 36 71 0 1350824432 10536544 8341680
21:08:58 11642 8192000000 10250215568 10689433600 1337891840 14.038 98 63 127 2 1360936672 8054768 14990064
21:10:12 41423 8192000000 10265188848 10717302784 1353826976 14.182 99 24 48 1 1378886416 19584912 5474528
21:12:26 14860 8192000000 10271859888 10733334528 1373116272 14.355 100 18 35 2 1390518624 13546576 3855776
21:13:45 32130 8192000000 10272542592 10734108672 1375028736 14.372 100 9 18 0 1386621200 9714592 1877872
21:15:25 34497 8192000000 10269285616 10715607040 1332770864 13.992 97 20 40 0 1346395296 8869536 4754896
21:17:10 78369 8192000000 10279892704 10730033152 1353651840 14.180 99 23 46 0 1373359840 13024768 6683232
21:19:29 43212 8192000000 10287554688 10739646464 1384784000 14.459 100 21 42 0 1404238208 14469136 4985072
21:21:18 50325 8192000000 10288531200 10748076032 1390355280 14.509 100 11 22 0 1406492496 13533888 2603328
21:23:28 70230 8192000000 10275037840 10753794048 1381812240 14.433 99 28 56 0 1400380432 12157344 6410848
21:25:57 30685 8192000000 10302078736 10752712704 1385024704 14.461 99 47 94 1 1406109472 10139552 10945216
21:27:43 66871 8192000000 10302416816 10750451712 1370637808 14.333 98 32 64 0 1394074624 15929184 7507632
21:29:50 59852 8192000000 10300142144 10754220032 1379634848 14.413 99 31 62 0 1398570544 11746832 7188864
21:31:54 70324 8192000000 10307652880 10756689920 1388717952 14.494 99 29 55 0 1405418960 10722432 5978576
21:34:13 21748 8192000000 10318069840 10765733888 1399729808 14.593 100 36 71 0 1420160144 12508912 7921424
21:35:40 91495 8192000000 10316957328 10788282368 1393800336 14.540 99 10 21 0 1421282384 25430016 2052032
21:38:44 94299 8192000000 10300522800 10754662400 1359924048 14.237 97 26 50 0 1377050304 11723200 5403056
21:41:26 40924 8192000000 10282162608 10740629504 1378239088 14.401 99 18 32 1 1389767280 7831568 3696624
21:43:09 51907 8192000000 10279106096 10746339328 1380932272 14.425 99 9 18 0 1391585472 8664208 1988992
21:45:01 95402 8192000000 10306064512 10762063872 1357719616 14.217 97 20 38 0 1377092944 14708608 4664720
21:47:37 57388 8192000000 10284333920 10731024384 1340499488 14.062 97 25 50 0 1364282208 17707488 6075232
21:49:41 104149 8192000000 10259385312 10701037568 1266174048 13.387 92 19 35 0 1288348576 17772048 4402480
21:52:26 58987 8192000000 10250781168 10696269824 1305821072 13.748 96 28 57 0 1321945696 10095232 6029392
21:54:32 10276 8192000000 10260721360 10688593920 1322728096 13.901 98 55 111 2 1342277856 6997552 12552208
21:55:47 78424 8192000000 10270403152 10706575360 1316289104 13.843 97 19 36 0 1329910304 9321552 4299648
21:58:06 80735 8192000000 10260357376 10696749056 1330955184 13.976 98 27 53 1 1345085008 7520064 6609760
22:00:38 89169 8192000000 10262655184 10708574208 1347333104 14.123 99 27 54 0 1364563248 10473520 6756624
22:03:27 80578 8192000000 10266087152 10724814848 1367811216 14.307 100 9 19 0 1381870192 11900864 2158112
22:05:48 72295 8192000000 10271937568 10730561536 1359799472 14.236 99 15 30 0 1378599776 15202128 3598176
22:08:12 55094 8192000000 10266768080 10721804288 1318904032 13.867 96 15 30 0 1332882960 11124544 2854384
22:10:08 58358 8192000000 10283344480 10738700288 1362020480 14.255 98 12 24 0 1379276560 14906016 2350064
22:12:13 29894 8192000000 10298032688 10752966656 1391592224 14.520 100 22 44 1 1405475744 8497040 5386480
22:13:43 28054 8192000000 10310679424 10752126976 1382646592 14.440 99 38 76 2 1400154448 9220672 8287184
22:15:12 14963 8192000000 10309732784 10749059072 1379794032 14.415 99 30 60 1 1395996256 9576000 6626224
22:16:31 44896 8192000000 10292614736 10731773952 1335894848 14.020 96 23 46 0 1354317216 13552608 4869760
22:18:19 111834 8192000000 10302717296 10758930432 1378065536 14.399 99 11 22 0 1394100096 13720496 2314064
22:21:16 128884 8192000000 10305497600 10757300224 1395620256 14.556 100 16 32 0 1417262656 18139472 3502928
22:25:12 52677 8192000000 10311877968 10761916416 1403572304 14.627 100 34 69 1 1421036144 9171936 8291904
22:27:30 52644 8192000000 10281473296 10746462208 1376627520 14.386 99 8 14 0 1386894672 8685824 1581328
22:29:31 55578 8192000000 10273329216 10733703168 1341021824 14.067 96 17 34 0 1357995248 12880720 4092704
22:31:27 72360 8192000000 10278343600 10743214080 1358242240 14.222 98 13 26 0 1375078672 13894944 2941488
22:33:51 93588 8192000000 10277991344 10740346880 1347607136 14.126 97 12 24 0 1362891152 12620848 2663168
22:36:25 104177 8192000000 10278213840 10741579776 1336256944 14.024 97 16 32 1 1352043536 12177552 3609040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-01-2023 22:39:51 to 16-01-2023 22:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-01-2023 22:40:07 to 16-01-2023 22:40:09.

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          235758
MON$OLDEST_ACTIVE               236650
MON$OLDEST_SNAPSHOT             236650
MON$NEXT_TRANSACTION            236650
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                       2003392
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:308: 
:309: mscs-test-161.budzdorov.ru    Mon Jan 16 22:38:37 2023
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:311: 
:312: 
:313: mscs-test-161.budzdorov.ru    Mon Jan 16 22:38:41 2023
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:315: 
--- end of diff output ---

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

16.01.23 22:40:10. 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-16 22:40:12 No severe PSQL-related problems occured
16.01.23 22:40:14. End of report.