Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2021 07:37
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 16708772 kB
MemAvailable 31469856 kB
Buffers 0 kB
Cached 14960656 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-09 07:37:13
DTS_END                         2021-12-09 07:37:13.4690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36218
TRN_ID                          232345
ATT_ID                          632
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: 8879.78 25412 1065574
customer order: creation 170.99 439 20519
customer order: refuse 14.67 311 1760
order to supplier: creation 44.90 1385 5388
order to supplier: removal 3.55 102076 426
invoice (draft): creation 45.46 3572 5455
invoice (draft): removal 51.73 913 6208
invoice accept: apply 40.55 19160 4866
invoice accept: cancel 45.99 842 5519
customer reserve: creation 3695.29 192 443435
customer reserve: removal 4475.87 64 537104
realization accept: apply 82.75 185 9930
realization accept: cancel 82.33 118 9880
payment from customer: creation 57.99 105 6959
payment from customer: removal 5.38 79 646
payment to supplier: creation 47.92 120 5750
payment to supplier: removal 3.66 74 439
service: total inventory turnovers 3.77 3441 452
service: total monetary turnovers 6.21 529 745
service: aggregate perf. data 0.74 22108 89
service: refresh index statistics 0.03 352519 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 07:37:47 to 09-12-2021 07:37:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6630
TEST_TIME 2 6379
TEST_TIME 3 6786
TEST_TIME 4 6557
TEST_TIME 5 6126
TEST_TIME 6 6056
TEST_TIME 7 6091
TEST_TIME 8 6265
TEST_TIME 9 6266
TEST_TIME 10 6310
TEST_TIME 11 6670
TEST_TIME 12 6933
TEST_TIME 13 7340
TEST_TIME 14 7554
TEST_TIME 15 7769
TEST_TIME 16 8345
TEST_TIME 17 8725
TEST_TIME 18 9161
TEST_TIME 19 9197
TEST_TIME 20 9055
TEST_TIME 21 9395
TEST_TIME 22 9283
TEST_TIME 23 9769
TEST_TIME 24 10068
TEST_TIME 25 10267
TEST_TIME 26 11334
TEST_TIME 27 11500
TEST_TIME 28 11126
TEST_TIME 29 10900
TEST_TIME 30 10694
TEST_TIME 31 10684
TEST_TIME 32 10515
TEST_TIME 33 10307
TEST_TIME 34 10402
TEST_TIME 35 10178
TEST_TIME 36 9947
TEST_TIME 37 10137
TEST_TIME 38 10042
TEST_TIME 39 9947
TEST_TIME 40 9912
TEST_TIME 41 10078
TEST_TIME 42 9911
TEST_TIME 43 9796
TEST_TIME 44 9728
TEST_TIME 45 9671
TEST_TIME 46 9558
TEST_TIME 47 9456
TEST_TIME 48 9329
TEST_TIME 49 9412
TEST_TIME 50 9419
TEST_TIME 51 9402
TEST_TIME 52 9380
TEST_TIME 53 9404
TEST_TIME 54 9324
TEST_TIME 55 9390
TEST_TIME 56 9529
TEST_TIME 57 9487
TEST_TIME 58 9397
TEST_TIME 59 9520
TEST_TIME 60 9681
TEST_TIME 61 9608
TEST_TIME 62 9503
TEST_TIME 63 9422
TEST_TIME 64 9489
TEST_TIME 65 9380
TEST_TIME 66 9300
TEST_TIME 67 9276
TEST_TIME 68 9251
TEST_TIME 69 9244
TEST_TIME 70 9348
TEST_TIME 71 9297
TEST_TIME 72 9291
TEST_TIME 73 9300
TEST_TIME 74 9273
TEST_TIME 75 9273
TEST_TIME 76 9253
TEST_TIME 77 9376
TEST_TIME 78 9361
TEST_TIME 79 9401
TEST_TIME 80 9287
TEST_TIME 81 9213
TEST_TIME 82 9166
TEST_TIME 83 9185
TEST_TIME 84 9121
TEST_TIME 85 9086
TEST_TIME 86 9040
TEST_TIME 87 8972
TEST_TIME 88 8950
TEST_TIME 89 8979
TEST_TIME 90 8970
TEST_TIME 91 8986
TEST_TIME 92 8977
TEST_TIME 93 8962
TEST_TIME 94 9012
TEST_TIME 95 9042
TEST_TIME 96 9051
TEST_TIME 97 9040
TEST_TIME 98 9067
TEST_TIME 99 9126
TEST_TIME 100 9085
TEST_TIME 101 9054
TEST_TIME 102 9053
TEST_TIME 103 9001
TEST_TIME 104 8983
TEST_TIME 105 8968
TEST_TIME 106 8922
TEST_TIME 107 8892
TEST_TIME 108 8869
TEST_TIME 109 8847
TEST_TIME 110 8852
TEST_TIME 111 8827
TEST_TIME 112 8808
TEST_TIME 113 8891
TEST_TIME 114 8889
TEST_TIME 115 8871
TEST_TIME 116 8925
TEST_TIME 117 8915
TEST_TIME 118 8911
TEST_TIME 119 8954
TEST_TIME 120 8886

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2021 07:37:51 to 09-12-2021 07:37:54.

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 1477399 1477399 0 0.00 0 21501 7 0 0
doc_list_biud 1477391 1477391 0 0.00 0 73239 30 0 0
sp_add_invoice_to_stock 4956 4866 90 1.81 55 171438 19161 0 90
sp_cancel_adding_invoice 5519 5519 0 0.00 5 82479 843 0 0
sp_cancel_client_order 1760 1760 0 0.00 5 10646 312 0 0
sp_cancel_customer_reserve 537104 537104 0 0.00 1 66047 64 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 2 8061 80 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 2 5241 75 0 0
sp_cancel_supplier_invoice 6215 6208 7 0.11 2 73251 914 0 7
sp_cancel_supplier_order 426 426 0 0.00 10 995890 102077 0 0
sp_cancel_write_off 9880 9880 0 0.00 1 11266 118 0 0
sp_client_order 20519 20519 0 0.00 4 34078 440 0 0
sp_customer_reserve 443435 443435 0 0.00 0 87053 193 0 0
sp_fill_shopping_cart 31353 31353 0 0.00 1 18134 46 0 0
sp_fill_shopping_cart_clo_res 443421 443421 0 0.00 0 17417 16 0 0
sp_get_clo_for_invoice 4852 4852 0 0.00 0 26796 336 0 0
sp_kill_cost_storno 16484 16484 0 0.00 0 7671 58 0 0
sp_kill_qstorno_ret_qs2qd 1090722 1090722 0 0.00 0 73238 35 0 0
sp_kill_qty_storno 575679 575679 0 0.00 0 73239 75 0 0
sp_lock_selected_doc 20608 20608 0 0.00 0 11017 5 0 0
sp_make_cost_storno 27491 27491 0 0.00 0 21480 57 0 0
sp_multiply_rows_for_pdistr 27491 27491 0 0.00 0 9490 32 0 0
sp_multiply_rows_for_qdistr 20519 20519 0 0.00 0 34066 256 0 0
sp_pay_from_customer 6959 6959 0 0.00 4 13817 105 0 0
sp_pay_to_supplier 5750 5750 0 0.00 5 13601 121 0 0
sp_payment_common 12709 12709 0 0.00 1 7900 18 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 10632 282 0 0
sp_qd_handle_on_invoice_upd_sts 10371 10371 0 0.00 0 18028 330 0 0
sp_qd_handle_on_reserve_upd_sts 9930 9930 0 0.00 0 13166 60 0 0
sp_reserve_write_off 9930 9930 0 0.00 6 17945 185 0 0
sp_supplier_invoice 5455 5455 0 0.00 6 94965 3572 0 0
sp_supplier_order 5388 5388 0 0.00 5 40072 1386 0 0
srv_aggregate_perf_data 89 89 0 0.00 2663 476577 22109 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1165 140178 48042 0 0
srv_find_qd_qs_mism 1019651 1019651 0 0.00 0 10738 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 58 129728 3441 0 0
srv_make_money_saldo 745 745 0 0.00 1 16562 530 0 0
srv_recalc_idx_stat 4 4 0 0.00 149393 546147 352519 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4866 4866 0 0.00 0 14714 161 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 7350 92 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 8796 18 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 656 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 7301 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 5417 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1509 20 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 36599 1551 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 1458 6 0 0
t$perf-norm:sp_client_order 20519 20519 0 0.00 0 14443 17 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 747 4 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 7176 11 0 0
t$perf-norm:sp_pay_to_supplier 5750 5750 0 0.00 0 10112 14 0 0
t$perf-norm:sp_reserve_write_off 9930 9930 0 0.00 0 8383 12 0 0
t$perf-norm:sp_supplier_invoice 5455 5455 0 0.00 0 6123 17 0 0
t$perf-norm:sp_supplier_order 5388 5388 0 0.00 0 11427 16 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 117 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 280 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 713 10 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1166 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9884 9704 180 1.82 0 4338 15 0 180
v_all_customers 37248 37248 0 0.00 0 30 1 0 0
v_all_suppliers 29956 29956 0 0.00 0 21 1 0 0
v_all_wares 41036 41036 0 0.00 0 114 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1836 11 0 0
v_cancel_client_order 3520 3520 0 0.00 0 2946 6 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 4166 7 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2512 8 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 1465 6 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 2530 19 0 14
v_cancel_supplier_order 852 852 0 0.00 0 6618 20 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2956 20 0 0
v_max_id_clo_ord 5386 5386 0 0.00 0 290 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 3065 15 0 0
v_max_id_ord_sup 5449 5449 0 0.00 0 4871 6 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 1293 3 0 0
v_max_non_paid_realizn 3685 3685 0 0.00 0 621 2 0 0
v_min_id_clo_ord 5386 5386 0 0.00 0 1336 5 0 0
v_min_id_clo_res 986 986 0 0.00 0 4477 11 0 0
v_min_id_ord_sup 5449 5449 0 0.00 0 4931 9 0 0
v_min_non_paid_invoice 5750 5750 0 0.00 0 7893 12 0 0
v_min_non_paid_realizn 6959 6959 0 0.00 0 4100 13 0 0
v_our_firm 10336 10336 0 0.00 0 14 1 0 0
v_reserve_write_off 19858 19858 0 0.00 0 2941 6 0 0
x_lock_depdocs_on_canc_invoice 11293 11293 0 0.00 0 24782 119 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 22033 383 0 0
x_make_qstorno_1000_1200 5388 5388 0 0.00 2 40065 1309 0 0
x_make_qstorno_1200_2000 5455 5455 0 0.00 3 94950 3392 0 0
x_make_qstorno_2100_3300 443435 443435 0 0.00 0 87052 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 07:37:55 to 09-12-2021 07:37:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:16 1135 8192000000 8954547568 9017331712 11298832 0.137 3 2 4 0 11648960 102192 247936
05:08:19 14358 8192000000 9724670752 10023149568 799008288 8.886 100 84 168 0 826611712 7768864 19834560
05:09:41 30169 8192000000 9769037408 10080260096 837125536 9.271 100 88 176 0 869603632 11520688 20957408
05:11:19 15300 8192000000 9805429376 10125295616 871120224 9.611 100 83 166 0 911471792 21599280 18752288
05:12:39 10279 8192000000 9858384000 10192928768 906149792 9.959 100 76 151 0 964343744 41787008 16406944
05:14:02 12225 8192000000 9928217952 10274603008 955211040 10.442 100 78 156 3 1030055488 58826672 16017776
05:15:17 7019 8192000000 9971936544 10330898432 983413088 10.717 100 78 156 1 1086407120 87117504 15876528
05:16:26 2845 8192000000 9971422560 10333548544 1007992592 10.956 100 71 143 1 1077252672 54317776 14942304
05:17:33 2216 8192000000 10034336144 10398990336 1041874976 11.283 100 85 171 1 1126986080 67816656 17294448
05:18:36 1756 8192000000 10019613312 10402230272 1054522512 11.404 100 74 149 1 1127922944 57904576 15495856
05:19:40 7963 8192000000 9979426672 10376830976 1058268464 11.440 100 24 47 0 1095021696 31487808 5265424
05:20:48 3445 8192000000 9999558576 10393784320 1067221536 11.526 100 43 84 0 1104237760 27862320 9153904
05:21:53 33611 8192000000 10007263072 10398097408 1079327136 11.641 100 76 153 1 1110148464 13629184 17192144
05:23:28 8646 8192000000 9992748400 10398195712 1085993120 11.705 100 24 48 0 1114982080 23940064 5048896
05:24:39 10633 8192000000 10009414992 10407342080 1096160720 11.801 100 19 37 0 1111549024 11374608 4013696
05:25:51 5715 8192000000 10026581696 10433400832 1100077824 11.838 100 45 87 3 1133828960 23458704 10292432
05:27:03 41041 8192000000 10021276000 10437623808 1078013232 11.629 98 22 40 0 1112618544 30334608 4270704
05:28:47 7559 8192000000 9978512736 10373111808 1036915872 11.235 96 34 65 1 1066681344 23127920 6637552
05:29:56 4017 8192000000 9947459648 10335592448 1016329072 11.037 97 44 83 2 1035308352 9676592 9302688
05:31:03 23878 8192000000 9919023232 10294956032 959783120 10.487 93 27 53 0 982094352 14628208 7683024
05:32:29 12054 8192000000 9932378576 10323329024 992308320 10.804 98 19 38 0 1024448144 28509232 3630592
05:33:42 6516 8192000000 9954263872 10344738816 1038299712 11.248 100 34 68 1 1071204688 24730608 8174368
05:34:49 10263 8192000000 9969202960 10378424320 1054827248 11.407 100 29 58 0 1087950768 26404336 6719184
05:36:00 70958 8192000000 9983759776 10385604608 1066636912 11.520 100 31 63 1 1089092736 16308576 6147248
05:38:15 9867 8192000000 10049991920 10444124160 1093215952 11.773 100 51 100 2 1156643792 53475392 9952448
05:39:33 13343 8192000000 10067114800 10463293440 1111733632 11.949 100 50 97 3 1180522880 59211072 9578176
05:40:51 34106 8192000000 10108464464 10508206080 1130022880 12.122 100 55 109 1 1222525376 80704896 11797600
05:42:43 32944 8192000000 10167437632 10585980928 1147049792 12.282 100 38 74 3 1266509312 111064944 8394576
05:44:23 12410 8192000000 10208293120 10614693888 1158147632 12.386 100 53 106 2 1319120400 149332448 11640320
05:45:39 12912 8192000000 10211499392 10630500352 1159782128 12.401 100 45 90 2 1314652096 145052736 9817232
05:46:58 22915 8192000000 10255353728 10672930816 1171733984 12.513 100 46 92 0 1358375520 176767040 9874496
05:48:22 4294 8192000000 10281236704 10686558208 1176924320 12.562 100 75 150 1 1380516912 186531296 17061296
05:49:30 2569 8192000000 10331022304 10730844160 1168723904 12.485 99 86 171 1 1421196464 233373696 19098864
05:50:38 2077 8192000000 10379588672 10783391744 1178625008 12.577 100 86 170 1 1477395680 279844576 18926096
05:51:42 1164 8192000000 10377367808 10785406976 1171277776 12.509 100 83 165 0 1478258000 287436992 19543232
05:52:48 3042 8192000000 10412429216 10814885888 1171295392 12.509 100 85 171 1 1505224784 314968544 18960848
05:53:57 2979 8192000000 10379123424 10781396992 1170644336 12.503 100 92 184 2 1472880272 280872800 21363136
05:55:03 3506 8192000000 10357261024 10757279744 1144664160 12.259 98 78 154 0 1441620944 279249104 17707680
05:56:10 27344 8192000000 10264220240 10693890048 1103899920 11.875 98 30 60 0 1352821520 241090896 7830704
05:57:54 14272 8192000000 10337087968 10759254016 1114649552 11.976 99 30 60 0 1429787056 307215392 7922112
05:59:22 8911 8192000000 10323887744 10750554112 1117384368 12.002 100 35 70 2 1417339840 291033184 8922288
06:00:47 17875 8192000000 10268072560 10695815168 1103339920 11.869 99 24 47 0 1359011440 249589424 6082096
06:02:06 42848 8192000000 10208446784 10700632064 1096484240 11.804 99 17 34 0 1329972240 229202128 4285872
06:03:54 140177 8192000000 10034689760 10459529216 1066838480 11.522 96 17 34 2 1176680544 105699904 4142160
06:07:15 57563 8192000000 10126181792 10548867072 1106381472 11.898 99 34 68 1 1203966656 90141056 7444128
06:09:45 72984 8192000000 10114629968 10555039744 1122794912 12.053 100 18 36 0 1214343600 87650560 3898128
06:12:00 88648 8192000000 10180066960 10623410176 1133797888 12.157 99 27 54 0 1221564784 81642224 6124672
06:14:58 70983 8192000000 10130683200 10584702976 1133084496 12.150 100 31 62 0 1260564624 121067904 6412224
06:17:39 53168 8192000000 10086646016 10584891392 1139345200 12.209 100 22 44 0 1203829200 59422608 5061392
06:19:45 65408 8192000000 10114449888 10539020288 1131451152 12.135 99 19 36 0 1176228160 41070992 3706016
06:21:51 86778 8192000000 10050250784 10488410112 1136708400 12.185 100 20 40 0 1169309168 28071984 4528784
06:24:18 59600 8192000000 10085910928 10546790400 1135129632 12.170 99 5 10 0 1190623168 54504080 989456
06:26:18 63481 8192000000 10118040576 10621657088 1148911376 12.299 100 10 20 0 1238813568 87778768 2123424
06:28:27 71501 8192000000 10113341936 10541969408 1125057088 12.075 98 30 60 0 1187142656 55121584 6963984
06:30:55 52515 8192000000 10136224048 10582491136 1143515680 12.249 99 23 46 0 1202377952 53780624 5081648
06:32:48 53230 8192000000 10099576544 10614202368 1157276592 12.378 100 15 30 0 1250625968 89770928 3578448
06:34:44 24140 8192000000 10112346496 10552135680 1143920192 12.252 99 37 74 0 1184606400 33635328 7050880
06:36:13 38635 8192000000 10127921888 10602319872 1134653488 12.165 98 24 49 2 1220652944 80874064 5125392
06:37:56 58503 8192000000 10087506880 10517430272 1113876480 11.969 97 26 52 1 1148131936 28744176 5511280
06:39:57 76461 8192000000 10120737792 10585473024 1139369936 12.210 99 22 43 0 1220694160 77079280 4244944
06:42:14 28593 8192000000 10070449616 10598232064 1137992976 12.197 100 3 6 0 1187163056 48906592 263488
06:43:44 80345 8192000000 10088235200 10537525248 1122545744 12.051 98 20 40 0 1182452672 55380288 4526640
06:46:11 49302 8192000000 10118286352 10565922816 1111034320 11.942 98 21 42 0 1188058128 72468304 4555504
06:48:19 50029 8192000000 10061731696 10525962240 1107537552 11.909 98 23 46 0 1161060096 48512384 5010160
06:50:15 42719 8192000000 10075078480 10518568960 1103858784 11.874 98 24 48 1 1154371488 45282960 5229744
06:51:59 43119 8192000000 10095375808 10532556800 1132500880 12.145 100 43 84 0 1178406272 37176608 8728784
06:53:43 128881 8192000000 10073594992 10562818048 1063915072 11.494 94 16 32 0 1131065216 63962816 3187328
06:56:53 90678 8192000000 10043136160 10470412288 1071671792 11.568 96 12 24 0 1105939280 31100032 3167456
07:00:34 85329 8192000000 10035344624 10514833408 1095128096 11.791 99 7 14 1 1131712560 35216944 1367520
07:03:00 48616 8192000000 10050782624 10499723264 1095593424 11.796 98 21 42 1 1134134400 33871440 4669536
07:04:51 81345 8192000000 10051038432 10525790208 1090764704 11.750 98 12 24 0 1142345712 49007504 2573504
07:07:15 36477 8192000000 10052970896 10485096448 1108804672 11.921 99 31 62 0 1134447408 19071776 6570960
07:09:03 54067 8192000000 10071606928 10503770112 1109016080 11.923 98 23 46 0 1142351344 28608048 4727216
07:10:57 48221 8192000000 10116275968 10591285248 1128508144 12.107 100 29 58 0 1222951072 88482832 5960096
07:12:46 24344 8192000000 10083393488 10511597568 1098964288 11.828 97 44 87 3 1146747904 38575472 9208144
07:14:12 58377 8192000000 10076074032 10532798464 1106150160 11.896 98 13 26 2 1175805904 66527024 3128720
07:16:12 90370 8192000000 10070073008 10495918080 1111927600 11.951 98 24 46 0 1156814480 40285696 4601184
07:18:43 18903 8192000000 10120622672 10542706688 1118132704 12.009 99 54 108 3 1188808512 60088544 10587264
07:20:04 95377 8192000000 10039270016 10478018560 1104419344 11.880 98 26 52 0 1185573776 75314544 5839888
07:22:55 49533 8192000000 10071261488 10522058752 1113060976 11.961 98 16 32 0 1145956240 29321488 3573776
07:24:49 41706 8192000000 10068117504 10555711488 1119484512 12.022 99 11 21 1 1164082784 42203872 2394400
07:27:02 76508 8192000000 10104776080 10568556544 1113011216 11.961 98 19 38 0 1184040608 66789920 4239472
07:29:19 85663 8192000000 10078714128 10548551680 1106839872 11.902 97 27 54 3 1203197040 89942192 6414976
07:31:45 58275 8192000000 10095707824 10538504192 1112444160 11.956 98 13 25 1 1170663136 55321088 2897888
07:33:53 38267 8192000000 10097877344 10536714240 1126940016 12.093 99 31 60 1 1169613600 36491424 6182160
07:35:32 70318 8192000000 10062598320 10554081280 1123992768 12.065 99 15 30 1 1181561728 54714336 2854624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 07:38:01 to 09-12-2021 07:38:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2021 07:38:16 to 09-12-2021 07:38:18.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230709
MON$OLDEST_ACTIVE               232462
MON$OLDEST_SNAPSHOT             232462
MON$NEXT_TRANSACTION            232462
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2029408
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:102: 
:103: mscs-test-161.budzdorov.ru    Thu Dec  9 07:37:16 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

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