0s autopkgtest [22:04:45]: starting date and time: 2025-10-30 22:04:45+0000 0s autopkgtest [22:04:45]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [22:04:45]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.e3e8kvm3/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:netpbm-free --apt-upgrade python-docx-template --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=netpbm-free/2:11.12.00-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-3.secgroup --name adt-resolute-ppc64el-python-docx-template-20251030-220445-juju-7f2275-prod-proposed-migration-environment-15-5d89ee99-2090-4126-a08f-b69e4b280847 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-ppc64el-python-docx-template-20251030-220445-juju-7f2275-prod-proposed-migration-environment-15-5d89ee99-2090-4126-a08f-b69e4b280847 from image adt/ubuntu-resolute-ppc64el-server-20251030.img (UUID 27d7268f-18b6-4bc3-8ab4-2e1cc221265f)... 49s autopkgtest [22:05:34]: testbed dpkg architecture: ppc64el 49s autopkgtest [22:05:34]: testbed apt version: 3.1.11 50s autopkgtest [22:05:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 50s autopkgtest [22:05:35]: testbed release detected to be: None 50s autopkgtest [22:05:35]: updating testbed package index (apt update) 51s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 51s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 51s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 51s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 51s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [206 kB] 51s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5856 B] 51s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [3669 kB] 52s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [70.5 kB] 52s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [269 kB] 52s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted ppc64el Packages [940 B] 52s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [2494 kB] 52s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [38.5 kB] 53s Fetched 6843 kB in 2s (3086 kB/s) 54s Reading package lists... 54s Failed to check for VM: Permission denied 55s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 55s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 55s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 55s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 56s Reading package lists... 56s Reading package lists... 56s Building dependency tree... 56s Reading state information... 56s Calculating upgrade... 56s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s autopkgtest [22:05:41]: upgrading testbed (apt dist-upgrade and autopurge) 56s Reading package lists... 56s Building dependency tree... 56s Reading state information... 56s Calculating upgrade... 57s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s Solving dependencies... 57s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 60s autopkgtest [22:05:45]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:02:41 UTC 2025 60s autopkgtest [22:05:45]: @@@@@@@@@@@@@@@@@@@@ apt-source python-docx-template 62s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (dsc) [2360 B] 62s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (tar) [1586 kB] 62s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (diff) [2904 B] 62s gpgv: Signature made Tue Sep 2 12:36:18 2025 UTC 62s gpgv: using RSA key 772292F6F7AC85FAE041D41EE5F43F9C2734F287 62s gpgv: issuer "merkys@debian.org" 62s gpgv: Can't check signature: No public key 62s dpkg-source: warning: cannot verify inline signature for ./python-docx-template_0.20.1+dfsg-1.dsc: no acceptable signature found 63s autopkgtest [22:05:48]: testing package python-docx-template version 0.20.1+dfsg-1 63s autopkgtest [22:05:48]: build not needed 63s autopkgtest [22:05:48]: test command1: preparing testbed 64s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Solving dependencies... 64s The following NEW packages will be installed: 64s libdeflate0 libjbig0 libjpeg-turbo8 libjpeg8 liblerc4 libnetpbm11t64 64s libqrencode4 libsharpyuv0 libtiff6 libwebp7 libxslt1.1 netpbm python3-docx 64s python3-docxcompose python3-docxtpl python3-lxml qrencode 64s 0 upgraded, 17 newly installed, 0 to remove and 0 not upgraded. 64s Need to get 6919 kB of archives. 64s After this operation, 44.9 MB of additional disk space will be used. 64s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libdeflate0 ppc64el 1.23-2 [63.3 kB] 64s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjpeg-turbo8 ppc64el 2.1.5-4ubuntu2 [215 kB] 64s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjpeg8 ppc64el 8c-2ubuntu11 [2148 B] 64s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el liblerc4 ppc64el 4.0.0+ds-5ubuntu1 [298 kB] 65s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el libnetpbm11t64 ppc64el 2:11.12.00-1 [143 kB] 65s Get:6 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libqrencode4 ppc64el 4.1.1-2 [45.2 kB] 65s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libsharpyuv0 ppc64el 1.5.0-0.1 [22.3 kB] 65s Get:8 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjbig0 ppc64el 2.1-6.1ubuntu2 [35.9 kB] 65s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el libwebp7 ppc64el 1.5.0-0.1 [315 kB] 65s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el libtiff6 ppc64el 4.7.0-3ubuntu3 [307 kB] 65s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el libxslt1.1 ppc64el 1.1.43-0.3 [190 kB] 65s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el netpbm ppc64el 2:11.12.00-1 [2603 kB] 65s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-lxml ppc64el 6.0.1-1build1 [2449 kB] 65s Get:14 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-docx all 1.2.0+dfsg-1 [163 kB] 65s Get:15 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-docxcompose all 1.4.0-4 [20.7 kB] 65s Get:16 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-docxtpl all 0.20.1+dfsg-1 [19.2 kB] 65s Get:17 http://ftpmaster.internal/ubuntu resolute/universe ppc64el qrencode ppc64el 4.1.1-2 [27.6 kB] 66s Fetched 6919 kB in 1s (5154 kB/s) 66s Selecting previously unselected package libdeflate0:ppc64el. 66s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 76559 files and directories currently installed.) 66s Preparing to unpack .../00-libdeflate0_1.23-2_ppc64el.deb ... 66s Unpacking libdeflate0:ppc64el (1.23-2) ... 66s Selecting previously unselected package libjpeg-turbo8:ppc64el. 66s Preparing to unpack .../01-libjpeg-turbo8_2.1.5-4ubuntu2_ppc64el.deb ... 66s Unpacking libjpeg-turbo8:ppc64el (2.1.5-4ubuntu2) ... 66s Selecting previously unselected package libjpeg8:ppc64el. 66s Preparing to unpack .../02-libjpeg8_8c-2ubuntu11_ppc64el.deb ... 66s Unpacking libjpeg8:ppc64el (8c-2ubuntu11) ... 66s Selecting previously unselected package liblerc4:ppc64el. 66s Preparing to unpack .../03-liblerc4_4.0.0+ds-5ubuntu1_ppc64el.deb ... 66s Unpacking liblerc4:ppc64el (4.0.0+ds-5ubuntu1) ... 66s Selecting previously unselected package libnetpbm11t64:ppc64el. 66s Preparing to unpack .../04-libnetpbm11t64_2%3a11.12.00-1_ppc64el.deb ... 66s Unpacking libnetpbm11t64:ppc64el (2:11.12.00-1) ... 66s Selecting previously unselected package libqrencode4:ppc64el. 66s Preparing to unpack .../05-libqrencode4_4.1.1-2_ppc64el.deb ... 66s Unpacking libqrencode4:ppc64el (4.1.1-2) ... 66s Selecting previously unselected package libsharpyuv0:ppc64el. 66s Preparing to unpack .../06-libsharpyuv0_1.5.0-0.1_ppc64el.deb ... 66s Unpacking libsharpyuv0:ppc64el (1.5.0-0.1) ... 66s Selecting previously unselected package libjbig0:ppc64el. 66s Preparing to unpack .../07-libjbig0_2.1-6.1ubuntu2_ppc64el.deb ... 66s Unpacking libjbig0:ppc64el (2.1-6.1ubuntu2) ... 66s Selecting previously unselected package libwebp7:ppc64el. 66s Preparing to unpack .../08-libwebp7_1.5.0-0.1_ppc64el.deb ... 66s Unpacking libwebp7:ppc64el (1.5.0-0.1) ... 66s Selecting previously unselected package libtiff6:ppc64el. 66s Preparing to unpack .../09-libtiff6_4.7.0-3ubuntu3_ppc64el.deb ... 66s Unpacking libtiff6:ppc64el (4.7.0-3ubuntu3) ... 66s Selecting previously unselected package libxslt1.1:ppc64el. 66s Preparing to unpack .../10-libxslt1.1_1.1.43-0.3_ppc64el.deb ... 66s Unpacking libxslt1.1:ppc64el (1.1.43-0.3) ... 66s Selecting previously unselected package netpbm. 66s Preparing to unpack .../11-netpbm_2%3a11.12.00-1_ppc64el.deb ... 66s Unpacking netpbm (2:11.12.00-1) ... 66s Selecting previously unselected package python3-lxml:ppc64el. 66s Preparing to unpack .../12-python3-lxml_6.0.1-1build1_ppc64el.deb ... 66s Unpacking python3-lxml:ppc64el (6.0.1-1build1) ... 66s Selecting previously unselected package python3-docx. 66s Preparing to unpack .../13-python3-docx_1.2.0+dfsg-1_all.deb ... 66s Unpacking python3-docx (1.2.0+dfsg-1) ... 66s Selecting previously unselected package python3-docxcompose. 66s Preparing to unpack .../14-python3-docxcompose_1.4.0-4_all.deb ... 66s Unpacking python3-docxcompose (1.4.0-4) ... 66s Selecting previously unselected package python3-docxtpl. 66s Preparing to unpack .../15-python3-docxtpl_0.20.1+dfsg-1_all.deb ... 66s Unpacking python3-docxtpl (0.20.1+dfsg-1) ... 66s Selecting previously unselected package qrencode. 66s Preparing to unpack .../16-qrencode_4.1.1-2_ppc64el.deb ... 66s Unpacking qrencode (4.1.1-2) ... 66s Setting up libsharpyuv0:ppc64el (1.5.0-0.1) ... 66s Setting up liblerc4:ppc64el (4.0.0+ds-5ubuntu1) ... 66s Setting up libqrencode4:ppc64el (4.1.1-2) ... 66s Setting up qrencode (4.1.1-2) ... 66s Setting up libdeflate0:ppc64el (1.23-2) ... 66s Setting up libjbig0:ppc64el (2.1-6.1ubuntu2) ... 66s Setting up libnetpbm11t64:ppc64el (2:11.12.00-1) ... 66s Setting up libjpeg-turbo8:ppc64el (2.1.5-4ubuntu2) ... 66s Setting up libwebp7:ppc64el (1.5.0-0.1) ... 67s Setting up libxslt1.1:ppc64el (1.1.43-0.3) ... 67s Setting up libjpeg8:ppc64el (8c-2ubuntu11) ... 67s Setting up python3-lxml:ppc64el (6.0.1-1build1) ... 67s Setting up libtiff6:ppc64el (4.7.0-3ubuntu3) ... 67s Setting up python3-docx (1.2.0+dfsg-1) ... 67s Setting up python3-docxcompose (1.4.0-4) ... 67s Setting up python3-docxtpl (0.20.1+dfsg-1) ... 67s Setting up netpbm (2:11.12.00-1) ... 67s Processing triggers for man-db (2.13.1-1) ... 68s Processing triggers for libc-bin (2.42-0ubuntu3) ... 69s autopkgtest [22:05:54]: test command1: make -f debian/rules override_dh_auto_test 69s autopkgtest [22:05:54]: test command1: [----------------------- 69s qrencode --output debian/test.png TEST 69s pngtopnm debian/test.png | pnmtojpeg > debian/test.jpg 69s cp debian/test.png tests/templates/dummy_pic_for_header.png 69s cp debian/test.png tests/templates/python_logo.png 69s cp debian/test.png tests/templates/django.png 69s cp debian/test.png tests/templates/python.png 69s cp debian/test.png tests/templates/zope.png 69s cp debian/test.png tests/templates/bottle.png 69s cp debian/test.png tests/templates/tornado.png 69s cp debian/test.png tests/templates/pyramid.png 69s cp debian/test.jpg tests/templates/python_jpeg.jpg 69s cd tests && PYTHONPATH=$(find ../.pybuild -name build 2>/dev/null | xargs echo | tr ' ' :) python3 runtests.py 72s Testing get_undeclared_template_variables method... 72s ================================================== 72s === Test 1: Before render === 72s Variables found: {'skills', 'is_student', 'projects', 'degree_field', 'generation_date', 'email', 'name', 'has_degree', 'page_number', 'author', 'age', 'company_name'} 72s PASS: Found all expected variables before render 72s 72s === Test 2: After incomplete render === 72s Missing variables: {'projects', 'generation_date', 'has_degree', 'page_number', 'degree_field'} 72s PASS: Found missing variables after incomplete render 72s 72s === Test 3: After complete render === 72s Undeclared variables: set() 72s PASS: No undeclared variables after complete render 72s 72s === Test 4: With custom Jinja environment === 72s Variables found with custom env: {'skills', 'is_student', 'projects', 'degree_field', 'generation_date', 'email', 'name', 'has_degree', 'page_number', 'author', 'age', 'company_name'} 72s PASS: Custom Jinja environment works correctly 72s 72s ================================================== 72s SUMMARY: 72s Test 1 (Before render): PASS 72s Test 2 (After incomplete render): PASS 72s Test 3 (After complete render): PASS 72s Test 4 (Custom Jinja env): PASS 72s ALL TESTS PASSED! 72s 72s Note: This test demonstrates that get_undeclared_template_variables 72s now correctly analyzes the original template, not the rendered document. 75s Executing "python3 -m docxtpl templates/module_execute_tpl.docx templates/module_execute.json output/module_execute.docx -o -q" ... 75s --> File output/module_execute.docx has been generated. 77s ================================================================================ 77s Generating template error for testing (so it is safe to ignore) : 77s ................................................................................ 77s Encountered unknown tag 'endif'. 77s Context: 77s I am the second paragraph. 77s I am the third paragraph. 77s I am the fourth paragraph. 77s I am the fifth paragraph. {% if test_variable %}I am part of it, too.{% endif %} {% endif %} 77s I am the sixth paragraph. 77s I am the seventh paragraph. 77s I am the eighth paragraph. 77s ................................................................................ 77s End of TemplateError Test 77s ================================================================================ 78s cellbg.py ... 78s comments.py ... 78s custom_jinja_filters.py ... 78s doc_properties.py ... 78s dynamic_table.py ... 78s embedded.py ... 78s escape.py ... 78s escape_auto.py ... 78s footnotes.py ... 78s get_undeclared_variables.py ... 78s header_footer.py ... 78s header_footer_entities.py ... 78s header_footer_image.py ... 78s header_footer_image_file_obj.py ... 78s header_footer_inline_image.py ... 78s header_footer_utf8.py ... 78s horizontal_merge.py ... 78s inline_image.py ... 78s less_cells_after_loop.py ... 78s merge_docx.py ... 78s merge_paragraph.py ... 78s module_execute.py ... 78s multi_rendering.py ... 78s nested_for.py ... 78s order.py ... 78s preserve_spaces.py ... 78s replace_picture.py ... 78s richtext.py ... 78s richtext_and_if.py ... 78s richtext_eastAsia.py ... 78s richtextparagraph.py ... 78s subdoc.py ... 78s template_error.py ... 78s vertical_merge.py ... 78s vertical_merge_nested.py ... 78s word2016.py ... 78s Done. 78s autopkgtest [22:06:03]: test command1: -----------------------] 79s command1 PASS 79s autopkgtest [22:06:04]: test command1: - - - - - - - - - - results - - - - - - - - - - 79s autopkgtest [22:06:04]: @@@@@@@@@@@@@@@@@@@@ summary 79s command1 PASS