Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2023 19: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 15276196 kB
MemAvailable 30389628 kB
Buffers 0 kB
Cached 16420008 kB
SwapCached 3576 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-02-04 19:38:38
DTS_END                         2023-02-04 19:38:38.7440
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/57070
TRN_ID                          247789
ATT_ID                          682
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: 9412.42 17919 1129491
customer order: creation 177.96 348 21355
customer order: refuse 15.52 359 1862
order to supplier: creation 46.98 1276 5638
order to supplier: removal 3.51 97809 421
invoice (draft): creation 46.54 3350 5585
invoice (draft): removal 54.39 733 6527
invoice accept: apply 43.20 18114 5184
invoice accept: cancel 48.94 856 5873
customer reserve: creation 3898.67 182 467840
customer reserve: removal 4765.04 57 571805
realization accept: apply 86.16 181 10339
realization accept: cancel 91.98 114 11037
payment from customer: creation 60.38 109 7246
payment from customer: removal 5.73 83 687
payment to supplier: creation 50.76 135 6091
payment to supplier: removal 3.61 64 433
service: total inventory turnovers 5.10 994 612
service: total monetary turnovers 7.08 63 850
service: aggregate perf. data 0.85 18087 102
service: refresh index statistics 0.03 215475 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-02-2023 19:39:56 to 04-02-2023 19:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3198
TEST_TIME 2 4109
TEST_TIME 3 4452
TEST_TIME 4 4387
TEST_TIME 5 4816
TEST_TIME 6 4784
TEST_TIME 7 5149
TEST_TIME 8 5592
TEST_TIME 9 5424
TEST_TIME 10 6113
TEST_TIME 11 6062
TEST_TIME 12 6251
TEST_TIME 13 6501
TEST_TIME 14 6574
TEST_TIME 15 6877
TEST_TIME 16 6923
TEST_TIME 17 7650
TEST_TIME 18 7708
TEST_TIME 19 7978
TEST_TIME 20 8058
TEST_TIME 21 8555
TEST_TIME 22 8747
TEST_TIME 23 9156
TEST_TIME 24 9525
TEST_TIME 25 9799
TEST_TIME 26 9983
TEST_TIME 27 10215
TEST_TIME 28 10492
TEST_TIME 29 10834
TEST_TIME 30 10656
TEST_TIME 31 10538
TEST_TIME 32 10301
TEST_TIME 33 10103
TEST_TIME 34 9989
TEST_TIME 35 10038
TEST_TIME 36 9964
TEST_TIME 37 9907
TEST_TIME 38 9802
TEST_TIME 39 9727
TEST_TIME 40 9754
TEST_TIME 41 9833
TEST_TIME 42 9717
TEST_TIME 43 9661
TEST_TIME 44 9652
TEST_TIME 45 9491
TEST_TIME 46 9519
TEST_TIME 47 9550
TEST_TIME 48 9685
TEST_TIME 49 9666
TEST_TIME 50 9722
TEST_TIME 51 9736
TEST_TIME 52 9835
TEST_TIME 53 9795
TEST_TIME 54 9864
TEST_TIME 55 9922
TEST_TIME 56 10004
TEST_TIME 57 10030
TEST_TIME 58 10030
TEST_TIME 59 9974
TEST_TIME 60 9918
TEST_TIME 61 9796
TEST_TIME 62 9775
TEST_TIME 63 9756
TEST_TIME 64 9688
TEST_TIME 65 9690
TEST_TIME 66 9654
TEST_TIME 67 9637
TEST_TIME 68 9587
TEST_TIME 69 9567
TEST_TIME 70 9464
TEST_TIME 71 9453
TEST_TIME 72 9413
TEST_TIME 73 9423
TEST_TIME 74 9412
TEST_TIME 75 9484
TEST_TIME 76 9579
TEST_TIME 77 9559
TEST_TIME 78 9553
TEST_TIME 79 9573
TEST_TIME 80 9626
TEST_TIME 81 9691
TEST_TIME 82 9675
TEST_TIME 83 9680
TEST_TIME 84 9735
TEST_TIME 85 9647
TEST_TIME 86 9589
TEST_TIME 87 9558
TEST_TIME 88 9556
TEST_TIME 89 9530
TEST_TIME 90 9479
TEST_TIME 91 9424
TEST_TIME 92 9429
TEST_TIME 93 9459
TEST_TIME 94 9460
TEST_TIME 95 9475
TEST_TIME 96 9462
TEST_TIME 97 9482
TEST_TIME 98 9474
TEST_TIME 99 9512
TEST_TIME 100 9530
TEST_TIME 101 9504
TEST_TIME 102 9602
TEST_TIME 103 9625
TEST_TIME 104 9637
TEST_TIME 105 9625
TEST_TIME 106 9609
TEST_TIME 107 9583
TEST_TIME 108 9539
TEST_TIME 109 9539
TEST_TIME 110 9540
TEST_TIME 111 9523
TEST_TIME 112 9530
TEST_TIME 113 9486
TEST_TIME 114 9451
TEST_TIME 115 9442
TEST_TIME 116 9386
TEST_TIME 117 9426
TEST_TIME 118 9409
TEST_TIME 119 9439
TEST_TIME 120 9414

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-02-2023 19:39:59 to 04-02-2023 19:40:03.

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 1564442 1564442 0 0.00 0 16134 6 0 0
doc_list_biud 1564442 1564442 0 0.00 0 49321 27 0 0
sp_add_invoice_to_stock 5326 5184 142 2.66 23 157294 18114 0 142
sp_cancel_adding_invoice 5873 5873 0 0.00 3 79502 857 0 0
sp_cancel_client_order 1862 1862 0 0.00 4 10767 360 0 0
sp_cancel_customer_reserve 571805 571805 0 0.00 0 20550 57 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 7467 83 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 2 2136 65 0 0
sp_cancel_supplier_invoice 6538 6527 11 0.16 2 18393 733 0 11
sp_cancel_supplier_order 421 421 0 0.00 9 876378 97809 0 0
sp_cancel_write_off 11037 11037 0 0.00 1 14434 115 0 0
sp_client_order 21355 21355 0 0.00 3 16791 348 0 0
sp_customer_reserve 467840 467840 0 0.00 0 110735 183 0 0
sp_fill_shopping_cart 32547 32547 0 0.00 1 26993 33 0 0
sp_fill_shopping_cart_clo_res 467836 467836 0 0.00 0 33699 14 0 0
sp_get_clo_for_invoice 5175 5175 0 0.00 0 33231 322 0 0
sp_kill_cost_storno 18030 18030 0 0.00 0 7977 54 0 0
sp_kill_qstorno_ret_qs2qd 1161595 1161595 0 0.00 0 20511 31 0 0
sp_kill_qty_storno 613036 613036 0 0.00 0 49320 67 0 0
sp_lock_selected_doc 21644 21644 0 0.00 0 10880 6 0 0
sp_make_cost_storno 28848 28848 0 0.00 0 13848 52 0 0
sp_multiply_rows_for_pdistr 28848 28848 0 0.00 0 5639 26 0 0
sp_multiply_rows_for_qdistr 21355 21355 0 0.00 0 13954 161 0 0
sp_pay_from_customer 7246 7246 0 0.00 4 10962 109 0 0
sp_pay_to_supplier 6091 6091 0 0.00 4 11797 135 0 0
sp_payment_common 13337 13337 0 0.00 1 5212 22 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 9818 294 0 0
sp_qd_handle_on_invoice_upd_sts 11045 11045 0 0.00 0 49320 328 0 0
sp_qd_handle_on_reserve_upd_sts 10339 10339 0 0.00 0 25770 55 0 0
sp_reserve_write_off 10339 10339 0 0.00 6 25804 182 0 0
sp_supplier_invoice 5585 5585 0 0.00 5 81473 3350 0 0
sp_supplier_order 5638 5638 0 0.00 4 31289 1277 0 0
srv_aggregate_perf_data 102 102 0 0.00 1909 244380 18087 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1351 124885 42845 0 0
srv_find_qd_qs_mism 1081527 1081527 0 0.00 0 14210 5 0 0
srv_make_invnt_saldo 612 612 0 0.00 27 56127 995 0 0
srv_make_money_saldo 850 850 0 0.00 0 15628 64 0 0
srv_recalc_idx_stat 4 4 0 0.00 136243 320462 215476 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 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 5184 5184 0 0.00 0 21471 176 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 4528 126 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 6796 16 0 0
t$perf-norm:sp_cancel_customer_reserve 768 768 0 0.00 0 2829 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2128 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 4592 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 6734 34 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 18933 1611 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 7597 16 0 0
t$perf-norm:sp_client_order 21355 21355 0 0.00 0 8619 16 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 1550 5 0 0
t$perf-norm:sp_pay_from_customer 7246 7246 0 0.00 0 3367 6 0 0
t$perf-norm:sp_pay_to_supplier 6091 6091 0 0.00 0 4939 10 0 0
t$perf-norm:sp_reserve_write_off 10339 10339 0 0.00 0 15280 12 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 5442 16 0 0
t$perf-norm:sp_supplier_order 5638 5638 0 0.00 0 14453 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 237 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 107 3 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 1848 17 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 2346 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 105 28 0 0
v_add_invoice_to_stock 10628 10344 284 2.67 0 11783 24 0 284
v_all_customers 39144 39144 0 0.00 0 22 1 0 0
v_all_suppliers 31090 31090 0 0.00 0 56 1 0 0
v_all_wares 42710 42710 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 6981 17 0 0
v_cancel_client_order 3724 3724 0 0.00 0 10049 19 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1692 6 0 0
v_cancel_customer_reserve 1536 1536 0 0.00 0 2532 6 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2067 10 0 0
v_cancel_supplier_invoice 912 890 22 2.41 0 1645 16 0 22
v_cancel_supplier_order 842 842 0 0.00 0 9989 20 0 0
v_cancel_write_off 2112 2112 0 0.00 0 2136 18 0 0
v_max_id_clo_ord 5638 5638 0 0.00 0 2578 2 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 3080 16 0 0
v_max_id_ord_sup 5554 5554 0 0.00 0 2882 5 0 0
v_max_non_paid_invoice 1738 1738 0 0.00 0 118 2 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 4875 6 0 0
v_min_id_clo_ord 5638 5638 0 0.00 0 1612 4 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 9685 18 0 0
v_min_id_ord_sup 5554 5554 0 0.00 0 7120 6 0 0
v_min_non_paid_invoice 6091 6091 0 0.00 0 4992 17 0 0
v_min_non_paid_realizn 7246 7246 0 0.00 0 3630 13 0 0
v_our_firm 10430 10430 0 0.00 0 16 1 0 0
v_reserve_write_off 20678 20678 0 0.00 0 10931 11 0 0
x_lock_depdocs_on_canc_invoice 11955 11955 0 0.00 0 21216 125 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 8297 344 0 0
x_make_qstorno_1000_1200 5638 5638 0 0.00 1 31281 1208 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 2 80965 3207 0 0
x_make_qstorno_2100_3300 467840 467840 0 0.00 0 110734 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2023 19:40:04 to 04-02-2023 19:40:08.

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
17:08:42 17 8192000000 8805013056 8818233344 10567776 0.128 3 2 3 0 10710080 67360 74944
17:09:43 21769 8192000000 9893723632 10206846976 977773408 10.663 100 75 150 0 1003077632 5052576 20251648
17:11:14 19592 8192000000 9952907984 10275864576 1039212448 11.257 100 82 164 0 1068252560 8038240 21001872
17:12:38 9166 8192000000 9980252304 10312491008 1067562912 11.529 100 81 162 0 1096984160 9319696 20101552
17:13:52 10029 8192000000 10030524400 10375323648 1121094352 12.037 100 84 168 0 1151980256 10263840 20622064
17:15:10 7022 8192000000 10085708768 10446036992 1173315952 12.528 100 79 158 1 1203543712 11737760 18490000
17:16:20 2554 8192000000 10126066208 10493300736 1214479776 12.911 100 82 162 0 1246306688 13860288 17966624
17:17:25 1444 8192000000 10158975856 10533769216 1246227008 13.204 100 87 167 0 1278556512 13778896 18550608
17:18:34 1857 8192000000 10197394208 10578108416 1278918768 13.503 100 85 169 0 1311097120 13381360 18796992
17:19:38 822 8192000000 10207040448 10596065280 1298200112 13.679 100 73 144 1 1326011184 12213280 15597792
17:20:39 6187 8192000000 10196312000 10605015040 1311727728 13.802 100 43 87 3 1327913504 5879472 10306304
17:21:51 14025 8192000000 10199039408 10619359232 1317284112 13.852 100 12 24 0 1325174160 5235248 2654800
17:23:06 8523 8192000000 10215559040 10638823424 1336188192 14.023 100 18 36 0 1345961600 6124816 3648592
17:24:20 29883 8192000000 10236950336 10660347904 1343631520 14.090 100 41 81 0 1362796544 9237664 9927360
17:25:56 6625 8192000000 10238729328 10664820736 1348405616 14.133 100 13 26 0 1357043744 5218208 3419920
17:27:05 8043 8192000000 10237655264 10656571392 1320792704 13.884 98 23 47 0 1331735584 5889296 5053584
17:28:13 45513 8192000000 10209151936 10626969600 1281202528 13.524 96 22 44 1 1292673488 5332128 6138832
17:29:59 4360 8192000000 10180158416 10586910720 1263653392 13.363 97 30 60 0 1277121920 6797888 6670640
17:31:04 9507 8192000000 10148674896 10551144448 1238728240 13.135 98 18 36 0 1248640416 5886496 4025680
17:32:14 11092 8192000000 10147352064 10546839552 1219468880 12.957 97 29 58 1 1232149072 5217184 7463008
17:33:26 40322 8192000000 10156848640 10571505664 1265182288 13.378 99 25 48 1 1276229280 4984272 6062720
17:35:09 7286 8192000000 10185368800 10600517632 1295993088 13.659 100 17 34 0 1305367920 4967152 4407680
17:36:16 8477 8192000000 10196646256 10614726656 1307640704 13.765 100 17 34 0 1316026320 5148016 3237600
17:37:25 7912 8192000000 10203715264 10625970176 1316719088 13.847 100 16 32 0 1325672640 5275744 3677808
17:38:33 85603 8192000000 10238907040 10658463744 1337155408 14.032 100 25 50 0 1348467376 5961792 5350176
17:41:03 10686 8192000000 10273391376 10688163840 1361278384 14.249 100 45 90 0 1382760336 11595216 9886736
17:42:19 10680 8192000000 10287048272 10707206144 1377994640 14.399 100 50 96 1 1403596752 14672464 10929648
17:43:32 16773 8192000000 10292056688 10716504064 1378564496 14.404 100 51 102 0 1409207712 18423408 12219808
17:44:57 14090 8192000000 10296533536 10719227904 1373036368 14.354 99 46 92 1 1402125888 17679232 11410288
17:46:14 11072 8192000000 10299638144 10723131392 1376218448 14.383 99 49 96 1 1406500208 19058256 11223504
17:47:36 16060 8192000000 10309434928 10736918528 1380374096 14.420 99 36 70 0 1409631168 20543392 8713680
17:49:05 1398 8192000000 10342915488 10754695168 1398304400 14.580 99 81 159 0 1441074064 25268240 17501424
17:50:12 4504 8192000000 10340627760 10755239936 1385158960 14.463 98 65 129 0 1429165824 29148560 14858304
17:51:23 3371 8192000000 10352307824 10756513792 1399761360 14.593 100 80 160 0 1452275328 31983216 20530752
17:52:30 3130 8192000000 10332290576 10736902144 1385683312 14.467 100 74 145 2 1437668560 34390480 17594768
17:53:39 4106 8192000000 10323891264 10736758784 1379471536 14.412 100 47 92 0 1426398992 34631136 12296320
17:54:52 6068 8192000000 10316028656 10716602368 1353859216 14.182 99 76 148 3 1419929312 47335152 18734944
17:56:04 1349 8192000000 10335651280 10741481472 1379789792 14.415 100 79 155 1 1436935360 38159488 18986080
17:57:09 6884 8192000000 10325119664 10740613120 1380038640 14.417 100 60 120 0 1436619136 40652288 15928208
17:58:27 4063 8192000000 10323507408 10729361408 1359499696 14.233 99 74 147 2 1421424032 43065056 18859280
17:59:36 3740 8192000000 10334321280 10743885824 1374913472 14.371 100 68 135 1 1429913504 37945056 17054976
18:00:45 6621 8192000000 10320189520 10740260864 1360914144 14.246 99 50 96 1 1417831152 43976688 12940320
18:01:54 22505 8192000000 10312610304 10735034368 1365287424 14.285 100 41 81 0 1414831552 38213616 11330512
18:03:23 21979 8192000000 10299484816 10733969408 1358892896 14.227 99 20 40 0 1397817440 33080704 5843840
18:05:08 41880 8192000000 10297326672 10727837696 1359434880 14.232 99 31 61 1 1394954896 27158000 8362016
18:07:06 70274 8192000000 10273911712 10726510592 1329696688 13.964 97 7 14 0 1355396560 23676928 2022944
18:09:19 57185 8192000000 10299098656 10733887488 1383470112 14.448 100 42 85 0 1401019856 7508560 10041184
18:11:16 46920 8192000000 10298041696 10740719616 1375594720 14.377 99 27 54 0 1389336224 7561920 6179584
18:13:05 34815 8192000000 10319334608 10765299712 1399281408 14.589 100 23 46 0 1417431072 13148912 5000752
18:14:41 41208 8192000000 10303774464 10755940352 1397270032 14.571 100 22 44 0 1411126864 8417968 5438864
18:16:24 31276 8192000000 10320115712 10765271040 1394782144 14.549 99 21 42 0 1412103968 12241968 5079856
18:17:57 36958 8192000000 10326789216 10779672576 1410853552 14.692 100 55 107 2 1439482064 15317728 13310784
18:19:37 87784 8192000000 10318037792 10778472448 1398571872 14.582 99 14 28 0 1416436464 14370272 3494320
18:22:06 62996 8192000000 10334776032 10789445632 1408205152 14.668 99 20 40 0 1431965440 18789200 4971088
18:25:15 55610 8192000000 10313864640 10778955776 1379978256 14.416 98 21 42 0 1404083520 18810896 5294368
18:27:14 42012 8192000000 10312830624 10766950400 1365331616 14.285 97 24 48 0 1381039680 9950512 5757552
18:29:04 51754 8192000000 10319341392 10774085632 1403579056 14.627 100 17 35 0 1416577232 9005152 3993024
18:30:57 46659 8192000000 10314066944 10763952128 1354790320 14.191 96 11 22 0 1363984992 7226176 1968496
18:32:46 35489 8192000000 10300510848 10758660096 1383300432 14.446 100 9 18 0 1397142176 11512352 2329392
18:34:36 105245 8192000000 10301421120 10772004864 1373267968 14.356 99 9 18 0 1392580320 17374960 1937392
18:37:35 17018 8192000000 10297284768 10734977024 1361863456 14.254 99 35 70 0 1378035616 8611600 7560560
18:38:53 73228 8192000000 10267217808 10711134208 1346866240 14.119 99 37 74 0 1363341888 7907088 8568560
18:41:13 66932 8192000000 10233508768 10673389568 1290644560 13.610 95 17 34 0 1301760688 7730768 3385360
18:43:21 53117 8192000000 10238635728 10692599808 1330403728 13.971 98 16 33 0 1343023552 8942608 3677216
18:45:14 35957 8192000000 10267963184 10718056448 1364248560 14.275 100 28 54 0 1377392000 7495072 5648368
18:46:51 54259 8192000000 10252207072 10712133632 1350373968 14.151 99 12 24 0 1362908432 9770896 2763568
18:48:52 51835 8192000000 10257990880 10730799104 1364157824 14.275 100 11 22 0 1375362224 9079296 2125104
18:50:44 72751 8192000000 10273615760 10735046656 1337240144 14.033 97 18 36 0 1357231840 15486624 4505072
18:52:57 35197 8192000000 10267338128 10714398720 1332989984 13.994 98 19 38 1 1355172912 17567952 4614976
18:54:52 78312 8192000000 10287966960 10738413568 1333955184 14.003 97 18 36 0 1346038640 8147856 3935600
18:57:12 33063 8192000000 10287626352 10728714240 1347927936 14.129 98 35 70 1 1367533488 11493744 8111808
18:58:46 62537 8192000000 10300537088 10755960832 1368518256 14.314 99 15 30 0 1383504864 12056368 2930240
19:01:12 47228 8192000000 10301405600 10754379776 1377510832 14.394 99 30 60 0 1394290080 9965200 6814048
19:03:08 124885 8192000000 10278485904 10732355584 1361331808 14.249 98 33 64 1 1377548624 8192048 8024768
19:06:13 40356 8192000000 10280046960 10751246336 1386293872 14.473 100 30 60 0 1403516816 10609152 6613792
19:08:03 48937 8192000000 10260371984 10717880320 1358822512 14.227 98 8 16 0 1366595072 6162448 1610112
19:10:17 72895 8192000000 10298630064 10766307328 1388887568 14.496 100 11 22 0 1403499024 12412656 2198800
19:12:31 59695 8192000000 10289819328 10756698112 1376494448 14.385 98 16 32 0 1388572432 8303520 3774464
19:14:30 30271 8192000000 10302542624 10743607296 1367307216 14.303 98 35 70 0 1382468160 7593808 7567136
19:16:08 38758 8192000000 10301246896 10747027456 1339463104 14.053 96 33 66 0 1360596016 13710192 7422720
19:17:49 52540 8192000000 10289667296 10744262656 1332689200 13.991 96 17 34 0 1350844768 14420512 3735056
19:20:23 69249 8192000000 10292242640 10753241088 1351968832 14.165 98 5 10 0 1369640032 16701136 970064
19:22:32 75922 8192000000 10281045968 10732302336 1306262528 13.752 95 12 24 0 1321422672 12548528 2611616
19:24:49 23610 8192000000 10280361616 10719363072 1347761760 14.127 99 41 82 3 1365524016 7742064 10020192
19:26:16 98311 8192000000 10277141584 10731610112 1317490208 13.854 96 20 40 0 1332984688 10762384 4732096
19:29:14 26280 8192000000 10279678720 10723897344 1360559536 14.242 100 28 52 1 1382241024 15559904 6121584
19:30:42 83936 8192000000 10259085392 10727305216 1332857648 13.993 98 6 12 0 1350495424 16598960 1038816
19:33:06 76594 8192000000 10260984048 10714062848 1338061056 14.040 98 15 30 1 1350617696 8702144 3854496
19:35:27 68006 8192000000 10270235744 10739453952 1341307904 14.069 98 9 18 0 1356935904 13557280 2070720
19:37:39 53292 8192000000 10267454192 10725560320 1343459856 14.089 98 18 36 0 1360999696 12468112 5071728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-02-2023 19:40:10 to 04-02-2023 19:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 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 04-02-2023 19:40:26 to 04-02-2023 19:40:28.

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          248103
MON$OLDEST_ACTIVE               248104
MON$OLDEST_SNAPSHOT             248104
MON$NEXT_TRANSACTION            248104
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                       2027168
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
--- end of diff output ---

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

04.02.23 19:40:29. 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-02-04 19:40:32 No severe PSQL-related problems occured
04.02.23 19:40:33. End of report.