I get this error when trying to provision a drupal:

sh-3.1$ php "/var/hostmaster/webroot/profiles/hostmaster/modules/drush/drush.php"             -r /var/hostmaster/webroot             -l hostmaster.koumbit.net hosting actions -v
Action starts processing
Executed: /var/hostmaster/webroot/profiles/hostmaster/modules/drush/drush.php provision install test21.hostmaster.koumbit.net --root=/var/hostmaster/webroot --uri=hostmaster.koumbit.net -b  --site_id=161 --site_db_host=localhost
The command could not be executed succesfully (return: Warning: Table 'mysql.system' doesn't exist query: SELECT * FROM system WHERE type = 'theme' in /var/hostmaster/DRUPAL-5-7/includes/database.mysql.inc on line 172
[serialized errors]
, data: , code: 6)

I think that we should rely on the return value of the command rather than its output.

Comments

anarcat’s picture

anarcat CreditAttribution: anarcat commented

What should be made clear here is that the big problem here is that the provisionning command doesn't return a properly serialized value. That mysql warning should never appear out there, it breaks the hosting's framework expectations (which is: serialized output).

I guess the IPC should be rationalized around here...

adrian’s picture

adrian CreditAttribution: adrian commented

i changed things around a bit.

now it returns 1 on success. meaning that it needs to complete successfully to trigger removing from the queue.

anarcat’s picture

anarcat CreditAttribution: anarcat commented
Status: Active » Fixed

I don't get this error anymore so I assume this was fixed somehow.

anarcat’s picture

anarcat CreditAttribution: anarcat commented
Status: Fixed » Active

Actually, no I still see this error.

Steps to reproduce:

* create test.hostmaster.koumbit.net, run the queue
* create test2.hostmaster.koumbit.net, run the queue
* create test3.hostmaster.koumbit.net, run the queue, this error shows up:


Action starts processing
Executed: /var/hostmaster/webroot/profiles/hostmaster/modules/drush/drush.php provision install test3.hostmaster.koumbit.net --root=/var/hostma
ster/webroot --uri=hostmaster.koumbit.net -b  --site_id=124 --site_db_host=localhost
The command could not be executed succesfully (return: <em>
Warning: Table &amp;#039;mysql.system&amp;#039; doesn&amp;#039;t exist
query: SELECT * FROM system WHERE type = &amp;#039;theme&amp;#039; in /var/hostmaster/DRUPAL-5-7/includes/database.mysql.inc on line 172
...
s:9:&quot;timestamp&quot;;i:1211661148;}i:11;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:93:&quot;Changed group ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/tmp&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:12;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:127:&quot;Could not change permissions &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/tmp&lt;/em&gt;&lt;/code&gt; to &lt;em&gt;1528&lt;/em&gt; (2750 != 2770)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:13;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:77:&quot;Created &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/images&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:14;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:90:&quot;Changed ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/images&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:15;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:96:&quot;Changed group ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/images&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:16;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:130:&quot;Could not change permissions &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/images&lt;/em&gt;&lt;/code&gt; to &lt;em&gt;1528&lt;/em&gt; (2750 != 2770)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:17;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:79:&quot;Created &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/pictures&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:18;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:92:&quot;Changed ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/pictures&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:19;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:98:&quot;Changed group ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/pictures&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:20;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:132:&quot;Could not change permissions &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/files/pictures&lt;/em&gt;&lt;/code&gt; to &lt;em&gt;1528&lt;/em&gt; (2750 != 2770)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:21;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:71:&quot;Created &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/themes&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:22;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:84:&quot;Changed ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/themes&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:23;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:108:&quot;Could not change group ownership &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/themes&lt;/em&gt;&lt;/code&gt; (500 != 33)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:24;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:123:&quot;Could not change permissions &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/themes&lt;/em&gt;&lt;/code&gt; to &lt;em&gt;1512&lt;/em&gt; (750 != 2750)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:25;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:72:&quot;Created &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/modules&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:26;a:3:{s:4:&quot;type&quot;;s:7:&quot;message&quot;;s:7:&quot;message&quot;;s:85:&quot;Changed ownership of &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/modules&lt;/em&gt;&lt;/code&gt;&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:27;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:109:&quot;Could not change group ownership &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/modules&lt;/em&gt;&lt;/code&gt; (500 != 33)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:28;a:3:{s:4:&quot;type&quot;;s:5:&quot;error&quot;;s:7:&quot;message&quot;;s:124:&quot;Could not change permissions &lt;code&gt;&lt;em&gt;sites/test3.hostmaster.koumbit.net/modules&lt;/em&gt;&lt;/code&gt; to &lt;em&gt;1512&lt;/em&gt; (750 != 2750)&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}i:29;a:3:{s:4:&quot;type&quot;;s:7:&quot;install&quot;;s:7:&quot;message&quot;;s:24:&quot;Installing Drupal schema&quot;;s:9:&quot;timestamp&quot;;i:1211661148;}}s:8:&quot;messages&quot;;a:1:{s:5:&quot;error&quot;;a:1:{i:0;s:189:&quot;user warning: Can&amp;#039;t drop database &amp;#039;site_124&amp;#039;; database doesn&amp;#039;t exist
query: DROP DATABASE site_124 in /var/hostmaster/DRUPAL-5-7/includes/database.mysql.inc on line 172.&quot;;}}}</em>, data: <em></em>, code: <em>1</em>)

Running the provision hook manually works.

anarcat’s picture

anarcat CreditAttribution: anarcat commented

Note that the error doesn't happen the second time the queue is run. This is a very weird bug.

anarcat’s picture

anarcat CreditAttribution: anarcat commented

This could be related to the work done on the status field:

http://drupal.org/node/259382#comment-855943

adrian’s picture

adrian CreditAttribution: adrian commented
Version: » 5.x-0.1-alpha1

I still have no idea how this works. Never had it happen to me.

Nor had it happen on hm2.bryght.com.

We're going to need additional testers on this issue.

anarcat’s picture

anarcat CreditAttribution: anarcat commented
Status: Active » Fixed

So if this doesn't happen anymore, the bug is fixed. I'll open another issue for the more general IPC problem.

anarcat’s picture

anarcat CreditAttribution: anarcat commented

There: http://drupal.org/node/269914

Anonymous’s picture

Anonymous (not verified) CreditAttribution: Anonymous commented
Status: Fixed » Closed (fixed)

Automatically closed -- issue fixed for two weeks with no activity.

  • Commit 19a4450 on debian, dev-dns, dev-envobject, dev-koumbit, dev-log_directory, dev-migrate_aliases, dev-multiserver-install, dev-newhooks, dev-nginx, dev-platform_management, dev-ports, dev-purgebackup, dev-restore, dev-services, dev-simplerinstaller, dev-site_rename, dev-ssl, dev_716166_apache_conf, dev_dns, dev_server_verify, prod-koumbit, ssl, dev-ssl-ip-allocation-refactor, dev-1205458-move_sites_out_of_platforms, 7.x-3.x, dev-subdir-multiserver, 6.x-2.x-backports, dev-helmo-3.x authored by anarcat: 
    fix implementation problems with delete hook. see #259383. This could...