builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1468607172.61 results: success (0) buildid: 20160715073046 builduid: 0a68d9db484c438cb1c39515302dd5cd revision: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.613682) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.614529) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.615087) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.637097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.637565) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 11:26:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.6M=0.001s 2016-07-15 11:26:12 (19.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.732644) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.733044) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018446 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.782805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:12.783227) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 11:26:13,079 truncating revision to first 12 chars 2016-07-15 11:26:13,079 Setting DEBUG logging. 2016-07-15 11:26:13,079 attempt 1/10 2016-07-15 11:26:13,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/0d82d5d030af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 11:26:13,379 unpacking tar archive at: mozilla-inbound-0d82d5d030af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.629460 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:13.427483) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:13.427854) ========= script_repo_revision: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:13.428325) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:13.428634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 11:26:13.438237) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-15 11:26:13.438543) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:13 INFO - MultiFileLogger online at 20160715 11:26:13 in /builds/slave/test 11:26:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 11:26:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:13 INFO - {'append_to_log': False, 11:26:13 INFO - 'base_work_dir': '/builds/slave/test', 11:26:13 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 11:26:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:13 INFO - 'branch': 'Mozilla-Inbound', 11:26:13 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:13 INFO - 'config_files': ('talos/linux_config.py',), 11:26:13 INFO - 'default_actions': ('clobber', 11:26:13 INFO - 'read-buildbot-config', 11:26:13 INFO - 'download-and-extract', 11:26:13 INFO - 'populate-webroot', 11:26:13 INFO - 'create-virtualenv', 11:26:13 INFO - 'install', 11:26:13 INFO - 'run-tests'), 11:26:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:13 INFO - 'download_minidump_stackwalk': True, 11:26:13 INFO - 'download_symbols': 'ondemand', 11:26:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:26:13 INFO - 'tooltool.py': '/tools/tooltool.py', 11:26:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:26:13 INFO - '/tools/misc-python/virtualenv.py')}, 11:26:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:13 INFO - 'installer_path': 'installer.exe', 11:26:13 INFO - 'log_level': 'info', 11:26:13 INFO - 'log_name': 'talos', 11:26:13 INFO - 'log_to_console': True, 11:26:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:13 INFO - 'opt_config_files': (), 11:26:13 INFO - 'pip_index': False, 11:26:13 INFO - 'sps_profile': False, 11:26:13 INFO - 'sps_profile_interval': 0, 11:26:13 INFO - 'suite': 'dromaeojs', 11:26:13 INFO - 'system_bits': '32', 11:26:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:13 INFO - 'title': 'talos-linux64-ix-054', 11:26:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:13 INFO - 'use_talos_json': True, 11:26:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:13 INFO - 'work_dir': 'build'} 11:26:13 INFO - ##### 11:26:13 INFO - ##### Running clobber step. 11:26:13 INFO - ##### 11:26:13 INFO - Running pre-action listener: _resource_record_pre_action 11:26:13 INFO - Running main action method: clobber 11:26:13 INFO - rmtree: /builds/slave/test/build 11:26:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:14 INFO - Running post-action listener: _resource_record_post_action 11:26:14 INFO - ##### 11:26:14 INFO - ##### Finished clobber step (success) 11:26:14 INFO - ##### 11:26:14 INFO - ##### 11:26:14 INFO - ##### Running read-buildbot-config step. 11:26:14 INFO - ##### 11:26:14 INFO - Running pre-action listener: _resource_record_pre_action 11:26:14 INFO - Running main action method: read_buildbot_config 11:26:14 INFO - Using buildbot properties: 11:26:14 INFO - { 11:26:14 INFO - "project": "", 11:26:14 INFO - "product": "firefox", 11:26:14 INFO - "script_repo_revision": "production", 11:26:14 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 11:26:14 INFO - "repository": "", 11:26:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 11:26:14 INFO - "buildid": "20160715073046", 11:26:14 INFO - "pgo_build": "True", 11:26:14 INFO - "basedir": "/builds/slave/test", 11:26:14 INFO - "buildnumber": 1342, 11:26:14 INFO - "slavename": "talos-linux64-ix-054", 11:26:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:26:14 INFO - "platform": "ubuntu64_hw", 11:26:14 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 11:26:14 INFO - "branch": "mozilla-inbound", 11:26:14 INFO - "repo_path": "integration/mozilla-inbound", 11:26:14 INFO - "revision": "0d82d5d030afa2e8f48dd68e86eb75efd0947a5c", 11:26:14 INFO - "stage_platform": "linux64-pgo", 11:26:14 INFO - "builduid": "0a68d9db484c438cb1c39515302dd5cd", 11:26:14 INFO - "slavebuilddir": "test" 11:26:14 INFO - } 11:26:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:26:14 INFO - Running post-action listener: _resource_record_post_action 11:26:14 INFO - ##### 11:26:14 INFO - ##### Finished read-buildbot-config step (success) 11:26:14 INFO - ##### 11:26:14 INFO - ##### 11:26:14 INFO - ##### Running download-and-extract step. 11:26:14 INFO - ##### 11:26:14 INFO - Running pre-action listener: _resource_record_pre_action 11:26:14 INFO - Running main action method: download_and_extract 11:26:14 INFO - mkdir: /builds/slave/test/build/tests 11:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:14 INFO - https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:26:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:26:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:26:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:26:14 INFO - Downloaded 1361 bytes. 11:26:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:26:14 INFO - Using the following test package requirements: 11:26:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:26:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:26:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'jsshell-linux-x86_64.zip'], 11:26:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:26:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:26:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:26:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:26:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:26:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:14 INFO - https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:26:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:26:16 INFO - Downloaded 24327174 bytes. 11:26:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:26:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:26:17 INFO - Return code: 0 11:26:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:17 INFO - https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:26:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:26:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:26:18 INFO - Downloaded 24327174 bytes. 11:26:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:26:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:26:19 INFO - Return code: 0 11:26:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:19 INFO - https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:26:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:26:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:26:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:26:20 INFO - Downloaded 14403317 bytes. 11:26:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:26:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:26:21 INFO - Return code: 0 11:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:21 INFO - https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:26:22 INFO - Downloaded 60821060 bytes. 11:26:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:26:22 INFO - mkdir: /builds/slave/test/properties 11:26:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:22 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:22 INFO - Contents: 11:26:22 INFO - build_url:https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:26:23 INFO - Running post-action listener: _resource_record_post_action 11:26:23 INFO - Running post-action listener: set_extra_try_arguments 11:26:23 INFO - ##### 11:26:23 INFO - ##### Finished download-and-extract step (success) 11:26:23 INFO - ##### 11:26:23 INFO - ##### 11:26:23 INFO - ##### Running populate-webroot step. 11:26:23 INFO - ##### 11:26:23 INFO - Running pre-action listener: _resource_record_pre_action 11:26:23 INFO - Running main action method: populate_webroot 11:26:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:26:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:26:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:26:23 INFO - '--tppagecycles', 11:26:23 INFO - '7'], 11:26:23 INFO - 'tests': ['tsvgm']}}, 11:26:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:26:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:26:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['damp', 'tps']}, 11:26:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'tests': ['damp', 'tps']}, 11:26:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['dromaeo_dom']}, 11:26:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['basic_compositor_video']}, 11:26:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:26:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['a11yr', 11:26:23 INFO - 'ts_paint', 11:26:23 INFO - 'tpaint', 11:26:23 INFO - 'sessionrestore', 11:26:23 INFO - 'sessionrestore_no_auto_restore', 11:26:23 INFO - 'tabpaint']}, 11:26:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['tsvgx', 11:26:23 INFO - 'tsvgr_opacity', 11:26:23 INFO - 'tart', 11:26:23 INFO - 'tscrollx', 11:26:23 INFO - 'cart']}, 11:26:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:23 INFO - 'tsvgr_opacity', 11:26:23 INFO - 'tart', 11:26:23 INFO - 'tscrollx', 11:26:23 INFO - 'cart']}, 11:26:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'talos_options': ['--disable-e10s'], 11:26:23 INFO - 'tests': ['tp5o']}, 11:26:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'tests': ['tp5o']}, 11:26:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'talos_options': ['--disable-e10s', 11:26:23 INFO - '--xperf_path', 11:26:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:23 INFO - 'tests': ['tp5n']}, 11:26:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:23 INFO - 'talos_options': ['--xperf_path', 11:26:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:23 INFO - 'tests': ['tp5n']}}, 11:26:23 INFO - 'talos.zip': {'path': '', 11:26:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:26:23 INFO - Running post-action listener: _resource_record_post_action 11:26:23 INFO - ##### 11:26:23 INFO - ##### Finished populate-webroot step (success) 11:26:23 INFO - ##### 11:26:23 INFO - ##### 11:26:23 INFO - ##### Running create-virtualenv step. 11:26:23 INFO - ##### 11:26:23 INFO - Running pre-action listener: _resource_record_pre_action 11:26:23 INFO - Running main action method: create_virtualenv 11:26:23 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:26:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:26:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:23 INFO - Using real prefix '/usr' 11:26:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:23 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:25 INFO - Installing pip.................done. 11:26:25 INFO - Return code: 0 11:26:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:26:25 INFO - Using env: {'DISPLAY': ':0', 11:26:25 INFO - 'HOME': '/home/cltbld', 11:26:25 INFO - 'LANG': 'en_US.UTF-8', 11:26:25 INFO - 'LANGUAGE': 'en_US:en', 11:26:25 INFO - 'LOGNAME': 'cltbld', 11:26:25 INFO - 'MAIL': '/var/mail/cltbld', 11:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:25 INFO - 'MOZ_NO_REMOTE': '1', 11:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:25 INFO - 'NO_EM_RESTART': '1', 11:26:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:25 INFO - 'PWD': '/builds/slave/test', 11:26:25 INFO - 'SHELL': '/bin/bash', 11:26:25 INFO - 'SHLVL': '1', 11:26:25 INFO - 'TERM': 'linux', 11:26:25 INFO - 'TMOUT': '86400', 11:26:25 INFO - 'USER': 'cltbld', 11:26:25 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:25 INFO - '_': '/tools/buildbot/bin/python'} 11:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:26:25 INFO - Cleaning up... 11:26:26 INFO - Return code: 0 11:26:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:26:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:26:26 INFO - Using env: {'DISPLAY': ':0', 11:26:26 INFO - 'HOME': '/home/cltbld', 11:26:26 INFO - 'LANG': 'en_US.UTF-8', 11:26:26 INFO - 'LANGUAGE': 'en_US:en', 11:26:26 INFO - 'LOGNAME': 'cltbld', 11:26:26 INFO - 'MAIL': '/var/mail/cltbld', 11:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:26 INFO - 'MOZ_NO_REMOTE': '1', 11:26:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:26 INFO - 'NO_EM_RESTART': '1', 11:26:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:26 INFO - 'PWD': '/builds/slave/test', 11:26:26 INFO - 'SHELL': '/bin/bash', 11:26:26 INFO - 'SHLVL': '1', 11:26:26 INFO - 'TERM': 'linux', 11:26:26 INFO - 'TMOUT': '86400', 11:26:26 INFO - 'USER': 'cltbld', 11:26:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:26 INFO - '_': '/tools/buildbot/bin/python'} 11:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:26 INFO - Downloading/unpacking psutil>=3.1.1 11:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:28 INFO - Installing collected packages: psutil 11:26:28 INFO - Running setup.py install for psutil 11:26:28 INFO - building 'psutil._psutil_linux' extension 11:26:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:26:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:26:28 INFO - building 'psutil._psutil_posix' extension 11:26:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:26:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:26:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:28 INFO - Successfully installed psutil 11:26:28 INFO - Cleaning up... 11:26:28 INFO - Return code: 0 11:26:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:26:28 INFO - Using env: {'DISPLAY': ':0', 11:26:28 INFO - 'HOME': '/home/cltbld', 11:26:28 INFO - 'LANG': 'en_US.UTF-8', 11:26:28 INFO - 'LANGUAGE': 'en_US:en', 11:26:28 INFO - 'LOGNAME': 'cltbld', 11:26:28 INFO - 'MAIL': '/var/mail/cltbld', 11:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:28 INFO - 'MOZ_NO_REMOTE': '1', 11:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:28 INFO - 'NO_EM_RESTART': '1', 11:26:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:28 INFO - 'PWD': '/builds/slave/test', 11:26:28 INFO - 'SHELL': '/bin/bash', 11:26:28 INFO - 'SHLVL': '1', 11:26:28 INFO - 'TERM': 'linux', 11:26:28 INFO - 'TMOUT': '86400', 11:26:28 INFO - 'USER': 'cltbld', 11:26:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:28 INFO - '_': '/tools/buildbot/bin/python'} 11:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:31 INFO - Installing collected packages: mozsystemmonitor 11:26:31 INFO - Running setup.py install for mozsystemmonitor 11:26:31 INFO - Successfully installed mozsystemmonitor 11:26:31 INFO - Cleaning up... 11:26:31 INFO - Return code: 0 11:26:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:26:31 INFO - Using env: {'DISPLAY': ':0', 11:26:31 INFO - 'HOME': '/home/cltbld', 11:26:31 INFO - 'LANG': 'en_US.UTF-8', 11:26:31 INFO - 'LANGUAGE': 'en_US:en', 11:26:31 INFO - 'LOGNAME': 'cltbld', 11:26:31 INFO - 'MAIL': '/var/mail/cltbld', 11:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:31 INFO - 'MOZ_NO_REMOTE': '1', 11:26:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:31 INFO - 'NO_EM_RESTART': '1', 11:26:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:31 INFO - 'PWD': '/builds/slave/test', 11:26:31 INFO - 'SHELL': '/bin/bash', 11:26:31 INFO - 'SHLVL': '1', 11:26:31 INFO - 'TERM': 'linux', 11:26:31 INFO - 'TMOUT': '86400', 11:26:31 INFO - 'USER': 'cltbld', 11:26:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:31 INFO - '_': '/tools/buildbot/bin/python'} 11:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:31 INFO - Downloading/unpacking jsonschema==2.5.1 11:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:33 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:33 INFO - warning: no files found matching '*.txt' 11:26:33 INFO - no previously-included directories found matching 'build' 11:26:33 INFO - no previously-included directories found matching 'dist' 11:26:33 INFO - no previously-included directories found matching '.git*' 11:26:33 INFO - Installing collected packages: jsonschema, functools32 11:26:33 INFO - Running setup.py install for functools32 11:26:33 INFO - warning: no files found matching '*.txt' 11:26:33 INFO - no previously-included directories found matching 'build' 11:26:33 INFO - no previously-included directories found matching 'dist' 11:26:33 INFO - no previously-included directories found matching '.git*' 11:26:33 INFO - Successfully installed jsonschema functools32 11:26:33 INFO - Cleaning up... 11:26:33 INFO - Return code: 0 11:26:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:26:33 INFO - Using env: {'DISPLAY': ':0', 11:26:33 INFO - 'HOME': '/home/cltbld', 11:26:33 INFO - 'LANG': 'en_US.UTF-8', 11:26:33 INFO - 'LANGUAGE': 'en_US:en', 11:26:33 INFO - 'LOGNAME': 'cltbld', 11:26:33 INFO - 'MAIL': '/var/mail/cltbld', 11:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:33 INFO - 'MOZ_NO_REMOTE': '1', 11:26:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:33 INFO - 'NO_EM_RESTART': '1', 11:26:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:33 INFO - 'PWD': '/builds/slave/test', 11:26:33 INFO - 'SHELL': '/bin/bash', 11:26:33 INFO - 'SHLVL': '1', 11:26:33 INFO - 'TERM': 'linux', 11:26:33 INFO - 'TMOUT': '86400', 11:26:33 INFO - 'USER': 'cltbld', 11:26:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:33 INFO - '_': '/tools/buildbot/bin/python'} 11:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:33 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:35 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:36 INFO - Downloading docopt-0.6.1.tar.gz 11:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:36 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:36 INFO - Running setup.py install for blobuploader 11:26:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:36 INFO - Running setup.py install for requests 11:26:37 INFO - Running setup.py install for docopt 11:26:37 INFO - Successfully installed blobuploader requests docopt 11:26:37 INFO - Cleaning up... 11:26:37 INFO - Return code: 0 11:26:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:37 INFO - Using env: {'DISPLAY': ':0', 11:26:37 INFO - 'HOME': '/home/cltbld', 11:26:37 INFO - 'LANG': 'en_US.UTF-8', 11:26:37 INFO - 'LANGUAGE': 'en_US:en', 11:26:37 INFO - 'LOGNAME': 'cltbld', 11:26:37 INFO - 'MAIL': '/var/mail/cltbld', 11:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:37 INFO - 'MOZ_NO_REMOTE': '1', 11:26:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:37 INFO - 'NO_EM_RESTART': '1', 11:26:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:37 INFO - 'PWD': '/builds/slave/test', 11:26:37 INFO - 'SHELL': '/bin/bash', 11:26:37 INFO - 'SHLVL': '1', 11:26:37 INFO - 'TERM': 'linux', 11:26:37 INFO - 'TMOUT': '86400', 11:26:37 INFO - 'USER': 'cltbld', 11:26:37 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:37 INFO - '_': '/tools/buildbot/bin/python'} 11:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:37 INFO - Running setup.py (path:/tmp/pip-HyH7Wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:37 INFO - Running setup.py (path:/tmp/pip-zS5_SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:37 INFO - Running setup.py (path:/tmp/pip-rEg_iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:37 INFO - Running setup.py (path:/tmp/pip-suAzsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:37 INFO - Running setup.py (path:/tmp/pip-kXLU6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:37 INFO - Running setup.py (path:/tmp/pip-B3R_sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:37 INFO - Running setup.py (path:/tmp/pip-JgSBaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:37 INFO - Running setup.py (path:/tmp/pip-ZZJQNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:38 INFO - Running setup.py (path:/tmp/pip-eV4Sqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:38 INFO - Running setup.py (path:/tmp/pip-Ra9b8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:38 INFO - Running setup.py (path:/tmp/pip-vziWNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:38 INFO - Running setup.py (path:/tmp/pip-xRsRiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:38 INFO - Running setup.py (path:/tmp/pip-H2Q8wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:38 INFO - Running setup.py (path:/tmp/pip-Uep3We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:38 INFO - Running setup.py (path:/tmp/pip-gwzS3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:38 INFO - Running setup.py (path:/tmp/pip-43jYB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:38 INFO - Running setup.py (path:/tmp/pip-veCivb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:38 INFO - Running setup.py install for manifestparser 11:26:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:38 INFO - Running setup.py install for mozcrash 11:26:38 INFO - Running setup.py install for mozdebug 11:26:38 INFO - Running setup.py install for mozdevice 11:26:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for mozfile 11:26:39 INFO - Running setup.py install for mozhttpd 11:26:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for mozinfo 11:26:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for mozInstall 11:26:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for mozleak 11:26:39 INFO - Running setup.py install for mozlog 11:26:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for moznetwork 11:26:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Running setup.py install for mozprocess 11:26:39 INFO - Running setup.py install for mozprofile 11:26:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:40 INFO - Running setup.py install for mozrunner 11:26:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:40 INFO - Running setup.py install for mozscreenshot 11:26:40 INFO - Running setup.py install for moztest 11:26:40 INFO - Running setup.py install for mozversion 11:26:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:40 INFO - Cleaning up... 11:26:40 INFO - Return code: 0 11:26:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:40 INFO - Using env: {'DISPLAY': ':0', 11:26:40 INFO - 'HOME': '/home/cltbld', 11:26:40 INFO - 'LANG': 'en_US.UTF-8', 11:26:40 INFO - 'LANGUAGE': 'en_US:en', 11:26:40 INFO - 'LOGNAME': 'cltbld', 11:26:40 INFO - 'MAIL': '/var/mail/cltbld', 11:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:40 INFO - 'MOZ_NO_REMOTE': '1', 11:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:40 INFO - 'NO_EM_RESTART': '1', 11:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:40 INFO - 'PWD': '/builds/slave/test', 11:26:40 INFO - 'SHELL': '/bin/bash', 11:26:40 INFO - 'SHLVL': '1', 11:26:40 INFO - 'TERM': 'linux', 11:26:40 INFO - 'TMOUT': '86400', 11:26:40 INFO - 'USER': 'cltbld', 11:26:40 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:40 INFO - '_': '/tools/buildbot/bin/python'} 11:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:40 INFO - Running setup.py (path:/tmp/pip-htJEDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:40 INFO - Running setup.py (path:/tmp/pip-trXdTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:40 INFO - Running setup.py (path:/tmp/pip-Dt8fC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:40 INFO - Running setup.py (path:/tmp/pip-bKHYM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:40 INFO - Running setup.py (path:/tmp/pip-S5ZEPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:41 INFO - Running setup.py (path:/tmp/pip-zTxO4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:41 INFO - Running setup.py (path:/tmp/pip-fmqPBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:41 INFO - Running setup.py (path:/tmp/pip-qUGPaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:41 INFO - Running setup.py (path:/tmp/pip-AN1xJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:41 INFO - Running setup.py (path:/tmp/pip-HDa9QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:41 INFO - Running setup.py (path:/tmp/pip-AVme0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:41 INFO - Running setup.py (path:/tmp/pip-rzqfjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:41 INFO - Running setup.py (path:/tmp/pip-1kl9MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:41 INFO - Running setup.py (path:/tmp/pip-zrgMi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:41 INFO - Running setup.py (path:/tmp/pip-smyWdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:41 INFO - Running setup.py (path:/tmp/pip-HV5jm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:41 INFO - Running setup.py (path:/tmp/pip-v6dahb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:43 INFO - Downloading blessings-1.6.tar.gz 11:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:43 INFO - Installing collected packages: blessings 11:26:43 INFO - Running setup.py install for blessings 11:26:43 INFO - Successfully installed blessings 11:26:43 INFO - Cleaning up... 11:26:44 INFO - Return code: 0 11:26:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:44 INFO - Reading from file tmpfile_stdout 11:26:44 INFO - Current package versions: 11:26:44 INFO - argparse == 1.2.1 11:26:44 INFO - blessings == 1.6 11:26:44 INFO - blobuploader == 1.2.4 11:26:44 INFO - docopt == 0.6.1 11:26:44 INFO - functools32 == 3.2.3-2 11:26:44 INFO - jsonschema == 2.5.1 11:26:44 INFO - manifestparser == 1.1 11:26:44 INFO - mozInstall == 1.12 11:26:44 INFO - mozcrash == 0.17 11:26:44 INFO - mozdebug == 0.1 11:26:44 INFO - mozdevice == 0.48 11:26:44 INFO - mozfile == 1.2 11:26:44 INFO - mozhttpd == 0.7 11:26:44 INFO - mozinfo == 0.9 11:26:44 INFO - mozleak == 0.1 11:26:44 INFO - mozlog == 3.2 11:26:44 INFO - moznetwork == 0.27 11:26:44 INFO - mozprocess == 0.23 11:26:44 INFO - mozprofile == 0.28 11:26:44 INFO - mozrunner == 6.12 11:26:44 INFO - mozscreenshot == 0.1 11:26:44 INFO - mozsystemmonitor == 0.3 11:26:44 INFO - moztest == 0.7 11:26:44 INFO - mozversion == 1.4 11:26:44 INFO - psutil == 3.1.1 11:26:44 INFO - requests == 1.2.3 11:26:44 INFO - wsgiref == 0.1.2 11:26:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:44 INFO - Using env: {'DISPLAY': ':0', 11:26:44 INFO - 'HOME': '/home/cltbld', 11:26:44 INFO - 'LANG': 'en_US.UTF-8', 11:26:44 INFO - 'LANGUAGE': 'en_US:en', 11:26:44 INFO - 'LOGNAME': 'cltbld', 11:26:44 INFO - 'MAIL': '/var/mail/cltbld', 11:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:44 INFO - 'MOZ_NO_REMOTE': '1', 11:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:44 INFO - 'NO_EM_RESTART': '1', 11:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:44 INFO - 'PWD': '/builds/slave/test', 11:26:44 INFO - 'SHELL': '/bin/bash', 11:26:44 INFO - 'SHLVL': '1', 11:26:44 INFO - 'TERM': 'linux', 11:26:44 INFO - 'TMOUT': '86400', 11:26:44 INFO - 'USER': 'cltbld', 11:26:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:44 INFO - '_': '/tools/buildbot/bin/python'} 11:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:44 INFO - Cleaning up... 11:26:44 INFO - Return code: 0 11:26:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ff180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:26:44 INFO - Using env: {'DISPLAY': ':0', 11:26:44 INFO - 'HOME': '/home/cltbld', 11:26:44 INFO - 'LANG': 'en_US.UTF-8', 11:26:44 INFO - 'LANGUAGE': 'en_US:en', 11:26:44 INFO - 'LOGNAME': 'cltbld', 11:26:44 INFO - 'MAIL': '/var/mail/cltbld', 11:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:44 INFO - 'MOZ_NO_REMOTE': '1', 11:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:44 INFO - 'NO_EM_RESTART': '1', 11:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:44 INFO - 'PWD': '/builds/slave/test', 11:26:44 INFO - 'SHELL': '/bin/bash', 11:26:44 INFO - 'SHLVL': '1', 11:26:44 INFO - 'TERM': 'linux', 11:26:44 INFO - 'TMOUT': '86400', 11:26:44 INFO - 'USER': 'cltbld', 11:26:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:44 INFO - '_': '/tools/buildbot/bin/python'} 11:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:26:44 INFO - Cleaning up... 11:26:44 INFO - Return code: 0 11:26:44 INFO - Running post-action listener: _resource_record_post_action 11:26:44 INFO - Running post-action listener: _start_resource_monitoring 11:26:44 INFO - Starting resource monitoring. 11:26:44 INFO - ##### 11:26:44 INFO - ##### Finished create-virtualenv step (success) 11:26:44 INFO - ##### 11:26:44 INFO - ##### 11:26:44 INFO - ##### Running install step. 11:26:44 INFO - ##### 11:26:44 INFO - Running pre-action listener: _resource_record_pre_action 11:26:44 INFO - Running main action method: install 11:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:44 INFO - Reading from file tmpfile_stdout 11:26:44 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:45 INFO - Reading from file tmpfile_stdout 11:26:45 INFO - Output received: 11:26:45 INFO - Usage: mozinstall [options] installer 11:26:45 INFO - Options: 11:26:45 INFO - -h, --help show this help message and exit 11:26:45 INFO - -d DEST, --destination=DEST 11:26:45 INFO - Directory to install application into. [default: 11:26:45 INFO - "/builds/slave/test"] 11:26:45 INFO - --app=APP Application being installed. [default: firefox] 11:26:45 INFO - mkdir: /builds/slave/test/build/application 11:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:27:02 INFO - Reading from file tmpfile_stdout 11:27:02 INFO - Output received: 11:27:02 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:02 INFO - Running post-action listener: _resource_record_post_action 11:27:02 INFO - ##### 11:27:02 INFO - ##### Finished install step (success) 11:27:02 INFO - ##### 11:27:02 INFO - ##### 11:27:02 INFO - ##### Running run-tests step. 11:27:02 INFO - ##### 11:27:02 INFO - Running pre-action listener: _resource_record_pre_action 11:27:02 INFO - Running main action method: run_tests 11:27:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:02 INFO - Python 2.7.3 11:27:02 INFO - Return code: 0 11:27:02 INFO - grabbing minidump binary from tooltool 11:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fd3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fe2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fe740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:27:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:27:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:27:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:02 INFO - Return code: 0 11:27:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:27:02 INFO - Using env: {'DISPLAY': ':0', 11:27:02 INFO - 'HOME': '/home/cltbld', 11:27:02 INFO - 'LANG': 'en_US.UTF-8', 11:27:02 INFO - 'LANGUAGE': 'en_US:en', 11:27:02 INFO - 'LOGNAME': 'cltbld', 11:27:02 INFO - 'MAIL': '/var/mail/cltbld', 11:27:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:02 INFO - 'MOZ_NO_REMOTE': '1', 11:27:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:02 INFO - 'NO_EM_RESTART': '1', 11:27:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:02 INFO - 'PWD': '/builds/slave/test', 11:27:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:02 INFO - 'SHELL': '/bin/bash', 11:27:02 INFO - 'SHLVL': '1', 11:27:02 INFO - 'TERM': 'linux', 11:27:02 INFO - 'TMOUT': '86400', 11:27:02 INFO - 'USER': 'cltbld', 11:27:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881', 11:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:02 INFO - '_': '/tools/buildbot/bin/python'} 11:27:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:27:02 INFO - mozversion application_buildid: 20160715073046 11:27:02 INFO - mozversion application_changeset: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c 11:27:02 INFO - mozversion application_display_name: Nightly 11:27:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:02 INFO - mozversion application_name: Firefox 11:27:02 INFO - mozversion application_remotingname: firefox 11:27:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:27:02 INFO - mozversion application_vendor: Mozilla 11:27:02 INFO - mozversion application_version: 50.0a1 11:27:02 INFO - mozversion platform_buildid: 20160715073046 11:27:02 INFO - mozversion platform_changeset: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c 11:27:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:27:02 INFO - mozversion platform_version: 50.0a1 11:27:02 INFO - using testdate: 1468607222 11:27:02 INFO - actual date: 1468607222 11:27:02 INFO - starting webserver on 'localhost:36838' 11:27:02 INFO - SUITE-START | Running 2 tests 11:27:02 INFO - TEST-START | dromaeo_css 11:27:02 INFO - operating with platform_type : linux_ 11:27:02 INFO - Initialising browser for dromaeo_css test... 11:27:02 INFO - TEST-INFO | started process 29925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMFhoFg/profile http://localhost:36838/getInfo.html) 11:27:06 INFO - PROCESS | 29925 | __metrics Screen width/height:1600/1200 11:27:06 INFO - PROCESS | 29925 | colorDepth:24 11:27:06 INFO - PROCESS | 29925 | Browser inner width/height: 1024/697 11:27:06 INFO - PROCESS | 29925 | __metrics 11:27:07 INFO - PROCESS | 29925 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:27:07 INFO - PROCESS | 29925 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:27:08 INFO - TEST-INFO | 29925: exit 0 11:27:08 INFO - Browser initialized. 11:27:08 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:08 INFO - TEST-INFO | started process 30010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMFhoFg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:20 INFO - PROCESS | 30010 | RSS: Main: 157405184 11:27:20 INFO - PROCESS | 30010 | 11:28:11 INFO - PROCESS | 30010 | 1468607291485 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:30:19 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36838/tests/dromaeo/cssquery-ext.html) 11:30:21 INFO - PROCESS | 30010 | RSS: Main: 291373056 11:30:21 INFO - PROCESS | 30010 | 11:33:23 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-ext.html (next: http://localhost:36838/tests/dromaeo/cssquery-jquery.html) 11:33:24 INFO - PROCESS | 30010 | RSS: Main: 241512448 11:33:24 INFO - PROCESS | 30010 | 11:36:26 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36838/tests/dromaeo/cssquery-mootools.html) 11:36:26 INFO - PROCESS | 30010 | RSS: Main: 247418880 11:36:26 INFO - PROCESS | 30010 | 11:39:28 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36838/tests/dromaeo/cssquery-prototype.html) 11:39:29 INFO - PROCESS | 30010 | RSS: Main: 237699072 11:39:29 INFO - PROCESS | 30010 | 11:42:32 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36838/tests/dromaeo/cssquery-yui.html) 11:42:32 INFO - PROCESS | 30010 | RSS: Main: 219672576 11:42:32 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | Cycle 1(1): loaded http://localhost:36838/tests/dromaeo/cssquery-yui.html (next: http://localhost:36838/tests/dromaeo/cssquery-dojo.html) 11:45:35 INFO - PROCESS | 30010 | RSS: Main: 279343104 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | __start_tp_report 11:45:35 INFO - PROCESS | 30010 | _x_x_mozilla_page_load 11:45:35 INFO - PROCESS | 30010 | _x_x_mozilla_page_load_details 11:45:35 INFO - PROCESS | 30010 | |i|pagename|runs| 11:45:35 INFO - PROCESS | 30010 | |0;dojo.html;3039.2098054505827;3050.9542356864663;3071.708187722167;3080.260737423018;3092.38152369526;5288.92066619001;5353.277307563478;5371.785128594852;5411.729413529322;5434.3207099112615;5344.251804747329;5396.83809485716;5408;5415.593830462712;5465.344158700951;4841.975790121044;4886.63350248731;4893.04585605807;4931.580815630676;5084.2373643953515;5032;5038.59691224703;5048.671836330626;5050.3434553508;5052.166392545226;4795.664303498739;4830.275458681191;4839.7338146401935;4967.627427942901;4987.900241995158;4885.5358740919555;4886.169351210267;4887.584555312803;4888.804447822083;4894.583960363373;4830.975845120787;4835.468098509161;4842.757862106881;4854.393200849894;4861.052094841507;5256.553192978601;5323.680579165224;5363.973180134074;5394.946050539523;5409.6213265071465;5674.290713660793;5674.773009079632;5710.229119068956;5723.51350135239;5725.856853578653;5800;5813.88372232553;5851.093080572518;5865.794697185621;5869.559783016291;5240.528352448298;5288.233206185082;5315.01672190646;5335.67985920846;5399.649022813504;5844.9415505845245;5858.794942177004;5874.9118763218585;5903.527717782567;5910.586258961831;5632.239647647597;5637.210790489335;5640.351359593699;5641.153826925912;5643.689597072201;5610.4670056344585;5613.887722245614;5646.181303710939;5650.85872853182;5661.377248502661;5667.688277144714;5671.234383358194;5688.800891968761;5727.971360143256;5731.140328950607;5621.690807005654;5659.6321239119325;5670.432956704297;5676.517496012802;5679.2333035040565;4318.762468064224;4329.242382583036;4346.413234213341;4353.608175264241;4357.564243575617;3866.7679939203736;3884.475595794588;3894;3922.607739226112;3928.2929072766606;5260.894782104337;5400.540954018874;5403.594730395236;5409.864753381197;5418.566514678895;5694.715264236772;5708.257926469532;5748.051571490741;5751.827445176651;5787.913181302284;5453.372862120827;5457.017736807413;5461.290032295776;5465.152901300305;5466.726663666801;4706.388169538007;4751.619870410359;4858.076965376567;4861.80552777892;4913.778879950411;5876.941230587725;5919.792807251725;5930.169776231331;5944.256967879015;5979.222701048836;5706.400827913009;5709.115087161496;5713.05734553794;5715.342735585295;5729.8567535811935;5734.5125664319;5872.794452194153;5891.057430811221;5899.4985426239255;5909.52723782105;5634.6337488063145;5635.042042852643;5638.464345887134;5654.85862853432;5704.828855134353;5712.857178570569;5745.856353591026;5750.453706897838;5761.135829625589;5776.364599893925;5467.753951072291;5603.691796951207;5607.41122182165;5674.375818659862;5691.658500489983;5628.268325117709;5655.7172141393585;5658.377578466448;5665.433456654301;5668.03643380618;5606.775728970795;5619.887602247851;5625.46558076982;5635.915461268002;5639.379668236573;5457.235986961908;5495.203195536705;5622.437756224344;5684.431556844448;5692.516136128475;5389.299391079135;5445.264889239902;5472.945270547243;5479.068558345011;5480.780768769205 11:45:35 INFO - PROCESS | 30010 | |1;ext.html;586.9031609784386;615.5937081526192;640.9166808314918;644.4554351572925;648.2188962300429;30433.847830760842;43291.567084329145;44474;44492.11015779682;44543.886402839904;44629.7768511157;45172;45209.321860172124;45268.54731452685;45337;8080.070791858952;8791.252743516809;8959.552022398886;10395.792084158313;10679;10644.62743803967;10655.520501577408;10655.946720266387;10759.193060520452;10767.300125491818;10826;10863.18526110545;10868;10869.239153259276;10876.293040952352;10744;10746.73133171677;10771.24601277911;10783.946080269588;10811.459427028618;10675.679729608124;10726.839097413545;10730.302530335503;10731.731706707316;10734.677959661223;41811.7909410454;42113;42173;42275.7886210567;42284.15431691349;3517.7009954153673;3541.5395998520025;3585.3904836177912;3611.5124458198334;3660.4509323601405;32482.18794530132;32672.836635816908;32923.6707632923;33437.83281083604;34411.827940860385;4028.7582745035393;4085.7140000199997;4157.605027522381;4203;4214.515330736974;372299;373243;373323;375269;375533;56496.43503564994;56549.43450565442;62736.68631656813;62963;62975.37024629787;64664.67667661631;64686;64761.35238647553;65035.34964650293;65145.67427162834;15260;15438.30527626237;15446.304916278794;15447.768283475756;15462.690746185239;12684.746305073846;12744.235345879277;12793.744125117631;12801.295928723823;12812.167209131376;8074.596270186467;8142.918570814334;8391.244787969112;8401.411901166859;8409.192023714932;7103.218645948941;7131.679074441663;7133.89299160513;7260.96369518149;7294.452916031319;990.3117333453325;1031.6131450705984;1046.4610725476234;1094.0099210214769;1095.0965453500735;32771.83614081962;33078.3384332312;33158.502622460685;33272;33519.83240083784;1019.202289714723;1159.368144361325;1205.7829590673764;1224.088054399478;1286.067600989294;17455.127243637766;17530.64938701219;17536.736948945778;17604;17734.645307093786;511754;512417;516229;516885;519048;59209.70395148083;59710.70144649249;59735;59754.10368844471;59787;1239.5723475400973;1349.5546469664987;1381.7236552689503;1482.5700546841294;1578.2976575423827;30023.249418764706;33300.833495833336;33855.661443386234;34027;34662.82668586641;53079.734601326745;53773.462265376846;53906.46093539015;54069.18896216639;54154.45845541494;482550;489451;490245;490532;490622;39334.80332598319;44801.77599111984;47049;47086.293705595126;47527.52472475231;49303.7534812338;49848.252276215164;50397;53190.4680953201;55800.72099639476;42758.144837103704;44480.55519444764;44642;46065.53934460613;47128.29307560321;41600.79199603983;45396.546034539235;45539;45557;46572;43134.78432607817;44413.77793111014;44515.554844451144;45088;45210.77394613006;41409;41567.168656627306;44652;45327.54672453233;45437;33828.66171338354;34790.65209347874;34812.65187348094;35075.649243507236;35295 11:45:35 INFO - PROCESS | 30010 | |2;jquery.html;398.7986067036147;400.92582872168634;404.215821306665;405.0622808199019;416.36100481789174;13817.651728080114;18969;19460.318888838894;19472.12375443105;20795.896020519875;13922.93038534806;19438;19460.805391946076;20096;20376.694349584766;5787.160861675044;5940.584159108864;5951.58338916276;5963.642181469104;5982;5848.356680765112;5920.171176035358;5931.051031834907;5933.317668468117;5975.43233392827;4320.200762858881;6328.778492752755;6636.535442519025;6647.20233571972;6754.932450675479;6170.598911070811;6556.803295901131;6650.800475985704;6716.3283671632935;6731.259561448237;5879.470847623692;5895.764169433218;5896.970515147397;5906.438888305604;5921.141434491975;14981.625459363495;23562.7643723564;24013.27960161198;24210.63684044741;24359.02563897442;2445.9510809783883;2564.6024866145776;2592.6370308156875;2810.2412348665744;2840.815347002438;9552.95223523878;9956.402615843;10151.94924025375;10153.89846101537;10519;1863.2640107157733;1878.962420751591;1880.8965506897116;1948.756405449319;2011.979880201194;82876;85193.14806851913;86930;87147.56426217829;87665.12334876697;11238.325700458;11278;11294.096472282197;11401.94299028516;11559.30644161353;15873.285702143432;15900.12549309799;16000.279987400594;16011;16046.358145674043;7935.801604959922;8111.432199745961;8237.752867413987;8264.090949995496;8294.419390642717;7717.729879454192;7739.110002349689;7783;7847.843043139105;7923.762287131395;4378.233809083397;4817.253325734517;4847.2729090635985;4881.365422495124;8484.703035393855;3272.0081500569268;3502.9474557881654;3512.754107212522;3595.424732042861;3600.4959305697225;794.5629903553023;912.3750231091724;970.839811431106;974.084360700953;975.5853762151057;17306.22122004513;18438.81561184371;18561.5359616008;18794.718079228824;19449.222031118596;883.6421249394006;885.3227281129941;895.0557162193948;903.7876099116702;933.8272419602396;10049.648262310784;10382.688519344281;10462.26764126519;10476.68569942903;10484.63303784364;454980;455734;456349;456890;475519;89661.55169224112;92055.53972230096;92092.53953730188;93613;97949.73578038474;1018.3940555369543;1039.745262410699;1068.385678235018;1073.7476692977143;1075.6611667324767;18286.359977400727;18319.72520412168;18528.44414667562;18756;18887.15007824679;44808.32787508255;45169.77415112904;45222.54777452315;45300.77349613231;47200.29199562078;56026;58126.41873581379;58451;63551.3644863564;63719.68140159455;8708.390412671051;8832.91167088321;8847.259945678203;8912.55437228149;8942.910570894468;10093.446747334525;13564.457421703022;13869.930650346683;14179.22014289224;14462;12106;12309.93845030769;12404.81392779127;12571;12578.993680505355;11533.192676512563;11713.1800773945;11738.237014594024;11792.351420671945;11895.524179032742;12149.574764883188;12269;12309.384530773605;12674.936625316814;12896.41966111546;8266.669333226604;8270.849579109354;8523.957380213307;8813.383063185773;8907.376483646083;11516.942415288206;12496.187747796364;12541.686457838263;12544;12568.811467827807 11:45:35 INFO - PROCESS | 30010 | |3;mootools.html;1233.0936761480316;1498.4530646314095;1503.8571335723104;1640.3110693508734;1794.5334213104588;8396.664133434662;8803.603837827297;8810.030896601376;8816.20654141128;9084.682036128737;8558.529280889548;8843.602037908293;9069.773255668611;9070.773230669236;9071.818563628723;1999.1004048178306;2234.307364716943;2287.1194590082814;2315.0623997281127;2326.8371214015024;2312.7571604981486;2327.9068837246573;2330.5921463743775;2331.207389487574;2331.8484298520543;2288.5880541502524;2304.7925686688195;2308.284431826131;2308.9076436942587;2318.40880575453;1954.765428148617;2189.1462329691435;2259.1641092795608;2259.8757068361233;2268.3308424014954;2277.5786479501344;2293.70181876355;2313.5257272259146;2315.3632750993443;2315.363275099361;9060.954695226548;9082.591283392263;9090.318226133;9091.818163636757;9100.317476189311;5123.1546794778815;5301.628885977983;5304.416514183436;5309.7610607522365;5310.973445132789;3257.5439438478634;3342.181165614416;3350.363430948112;3393.219559501304;3424.8972530824117;1027.091024443373;1048.3133547526381;1052.1004541117388;1056.1550759392533;1056.6143357674393;12576.622701318975;12851.485940562367;12857.935710321388;12858.421371038326;12869.613911582668;4349.3475978603465;4365.2797288447055;4469.865904022885;4483.932741008887;4608.539146085364;10725.356478611307;10782.137429005661;10786.975237352439;10787.946060269649;10791.46042697862;5348.786048558092;5368.248445217672;5372.758225879845;5375.16684913839;5375.3280839895015;4261.765602891809;4775.355327030876;4899.191633380524;4903.068417000758;4997.075541024922;4072.083781149218;4079.6940229482907;4188.518250542615;4192.392103145027;4216.587443206498;1159.47244003978;1268.2770820632152;1286.6767660660175;1316.4668309334734;1337.4382759241141;965.4159233663673;969.0793745941384;970.3692599810039;979.7452662307851;987.8419452887504;6091.786787462506;6124.785632502841;6140;6162.7226774795245;6359.841003974938;983.8720966274338;994.2890833054256;995.9203263738883;1007.4308015970953;1079.8542196803487;4180.143070670505;4287.7641729705165;4305.268104422193;4311.353297005474;4362.650987920959;11167;11580.247283926517;11642.301461912313;11642.76714465706;11647.12646551512;11476.942615286871;11520.76958460826;11522.366269855238;11529.192956492965;11545.595904143314;8075.3539716822515;8394.286485648667;8413.116622754646;8478.152184781473;8627.741167764852;6370.872582548231;6514.967425162844;6526;6542;6558.606483610999;10618.150547955991;10727.731806704895;10773.24587278883;10834.620788272372;10888.782224355627;10481.895181048398;10646.680599582025;11038.17213708959;11066.667999959693;11086.944565277123;7834.138244793182;7941.205879412013;7991.880121798062;8068.7175948841505;8078.757637270891;8432.156784321518;8468.237858592756;8598.656053757779;8789.296856251356;8814.911850881666;6518.837029074311;7789.376849851885;7951.204879512003;8069.475484093515;8080.636371363185;7631.809204769926;7687.654055567401;7799.766007019798;7913.089994650459;8031.959840200761;7049.330313620198;7100.254473280231;7236.927630723625;7304.086989126359;7308.707651693872;5261.421243663118;5288.074586947346;5389;5408.864778380572;5489.451054894478;5204.141316682856;5323.866903327448;5398.46015398457;5423.83728488146;5544.750486228048 11:45:35 INFO - PROCESS | 30010 | |4;prototype.html;317.2876891378854;321.8149564000699;325.4597368368508;329.5649742340112;330.69906385189483;5321.52106310432;5326.360836699591;5349.358077030757;5365.222042803791;5365.436629153941;5257.973710131453;5272.551833094191;5279.894402111955;5286.524212820846;5299.894002119955;3914.823832927518;4020.557738648746;4157.272477316473;4178.728382655133;4181.519125300584;4078.245524577948;4149.481314835645;4154.31453810121;4160.9375859362135;4163.375493675942;3941.408788681692;4119.567445418233;4123.278426275405;4123.319652257389;4137.77242251676;4009.7794621295816;4025.315696331616;4077.7757223352705;4087.8773636790943;4135.1316223593085;3629.256002519477;3639.508666330038;3659.3596120678776;3659.5608526976935;3669.321175582499;5154.587632989352;5163.741812909377;5193.623689722189;5196.948030519722;5241.135212689902;4476;4688.288774597281;4691;4713.091198064789;4717.363155973934;5535.6955367454775;5542.528885044776;5550.27846379968;5554.166874968747;5555;3813.959531650554;3947.5460322063045;3955.2287303975736;3960.5643379228263;3975.6421922026866;5716.485516303553;5720.941625799241;5737.999999999958;5742.961248756673;5760.135979603051;4031.5162180538523;4034.636882680573;4041.777702226406;4045.110075783323;4072.1448495815616;3900.356441187229;4099.954511599523;4108.36320370343;4110.77390743512;4114.6091121343425;4653.348531205634;4668.673192876466;4690.273007683814;4692.601128904013;4700.2244629635725;5552.888942221133;5566.832995010156;5594.321703488937;5602.187682786055;5641.887162256732;1328.7010422655019;1398.9860101398856;1400.865299107726;1407.8662527059912;1411.3931009665941;872.2062922740275;886.2599729226168;887.5828360670474;895.6014573514854;937.5780898595659;5264.263003179558;5273.077211487975;5298.814541491107;5328.413874473805;5353.741870660392;5592;5595.468430499096;5607.738258891798;5635.1828984796975;5636.619028338084;5094.033754686817;5291.920621190685;5330.440303768126;5337.466253374631;5339.359276886798;2989.9402011960074;2993.6108305920525;3003.714647108521;3011.2772934495565;3017.9547306790414;5747.1379293106365;5747.281589801275;5757.050086735725;5767.413961160631;5771.88456230873;5499.642523236057;5703.315602127771;5708.457696518824;5722.713864306768;5739.684317362585;5744.167095771089;5751.654900705971;5772.567057470623;5778.422157784272;5831.35855055952;5437.4562543745305;5591.329040515164;5617;5639.971800140973;5734.19372897536;5655.575831812548;5678.261825962599;5680.971595141998;5683.261176047253;5692.203091567267;5904.409559044061;5923.289205295392;5954.523638108854;5955.731992060454;5962.731677074456;3969.5633480316565;4069.9796501017304;4074.449949256931;4075.2460794753065;4091.1204091120553;5714.257146570919;5714.3999880012;5723.8855222896145;5732.426757324234;5792.739326730391;5410.241671456713;5623.437656234508;5627.774889004394;5645.9153112702525;5660.518855897295;5595.160725891149;5596.524295434933;5600.775968961196;5603.159526070959;5606.6916319602815;5271.920921186109;5518.310211223597;5527.917081243865;5532.336119665505;5548.334199896039;5394.541463975449;5567.55459563242;5630.325595342343;5635.774569017193;5636.830895073154;5459.887863917529;5471.781128754965;5479.698616576126;5486.6133232044185;5488.4785945335125 11:45:35 INFO - PROCESS | 30010 | |5;yui.html;755.5466719968019;755.5693254844741;766.0539234045956;772.7527191298783;865.8008658008658;14216.573502794907;14800.407983680667;15246.847531524681;15316.617084572863;15536.844631553695;15191.848081519182;15384.307706153222;15389.923050384732;15407.229638518085;15531.84468155318;663.2273401487262;776.7126163319551;809.0534075132094;821.2690705272313;826.68999125328;818.8321394114192;822.0669540072001;824.7319621123129;824.8102936324649;825.2943733108197;856.2251162697737;857.4769390671685;858.6393714639835;859.8882145321099;860.2773670117132;697.9162500499947;838.6310023589632;850.4174640371334;853.4196746212573;860.9311255099577;604.0245004317994;605.4702135631322;605.8031139879513;607.1499900139795;609.9115628233926;14370.712585748332;14375.209363485004;14396.280185990763;14397;14455.566333009921;14021;14236.28818559068;14240.715185696332;14282.714345713028;14466.92766536171;12915.612531624067;13004.674883127998;13109.803352949713;13235.014747787813;13360.398782054734;4504.707194032378;4523.615492683126;4541.3188021797;4679.3916790816975;4681.906361872743;65706.67146664285;67630.32369676339;70786.29213707849;71247.64376178086;71646.64176679135;11929.284242945278;11948.462319195656;12011.5586129665;12172.634820955385;12187.146899716934;20813.479663008548;20864;20868.582628347653;20920.37238882836;20983.16067357319;8421.15788421153;8457.788555286044;8623.180797824196;8634.956825215959;8758.518281494578;4723.102610503994;4888.584470319992;4943.728094954812;4956.3804524434445;5014.523620256143;3572.4462708280257;3662.322470342942;3663.5603727552866;3677.9517837416206;3680;567.2002476508104;589.107502134267;631.5295105146695;635.2472320300459;638.9616623002635;666.9828511519971;670.4468813229008;687.5084314714903;692.8094773937207;765.9387249020065;10231.181505479693;10253.109403469893;10353.171746260281;10428.947855260674;10700.464976751287;665.058941597634;709.8296408861836;756.9280918312752;765.6478020110811;785.6739453126888;4326.978365108217;4412.316091005835;4561.110583436264;5155.948440515622;5299.417064122943;112453.43773281081;117642;119653;119657;123811.3809430965;85163;103077;104044.47977760063;104169.47915260527;105209.47395263128;97738.51130744301;98055.50972245094;98645;101253.49373253234;101313.97372052666;10562.775748138314;10629.096526795;10701.304415212986;10813.351198928089;10813.783724325627;53028.46971530235;53427.73286133545;53797.73101134626;54675;56020.439795601524;78719;82691;85862.57068714616;86828;87372;12810.423530940943;13510.93244533771;13533.93233033868;13606.93196534011;13626.863731362955;15167.165805880783;15408.15255160932;15423.537293881202;15674.216289185268;16172.654690618763;13622.727545449234;13683.589492315246;13759.17444953306;14019.579412617637;14831.406743730562;14445.4944076961;14630.34163462668;14784.186869722269;15018.549443516711;15332.31004604773;14413.639659008608;14644.414223430944;14780.63048423755;14873.256337182882;14959.027663201858;10329.793404132026;10948.781024379627;11335.433228338385;11387.145964052563;11489;10529.684109476728;10988.890111099106;11019.83470247931;11693;11852.881471185177 11:45:35 INFO - PROCESS | 30010 | __end_tp_report 11:45:35 INFO - PROCESS | 30010 | __start_cc_report 11:45:35 INFO - PROCESS | 30010 | _x_x_mozilla_cycle_collect,1179 11:45:35 INFO - PROCESS | 30010 | __end_cc_report 11:45:35 INFO - PROCESS | 30010 | __startTimestamp1468608335871__endTimestamp 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | ------- Summary: start ------- 11:45:35 INFO - PROCESS | 30010 | Number of tests: 6 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#0] dojo.html Cycles:165 Average:5322.23 Median:5495.20 stddev:607.21 (11.0%) stddev-sans-first:582.07 11:45:35 INFO - PROCESS | 30010 | Values: 3039.2 3051.0 3071.7 3080.3 3092.4 5288.9 5353.3 5371.8 5411.7 5434.3 5344.3 5396.8 5408.0 5415.6 5465.3 4842.0 4886.6 4893.0 4931.6 5084.2 5032.0 5038.6 5048.7 5050.3 5052.2 4795.7 4830.3 4839.7 4967.6 4987.9 4885.5 4886.2 4887.6 4888.8 4894.6 4831.0 4835.5 4842.8 4854.4 4861.1 5256.6 5323.7 5364.0 5394.9 5409.6 5674.3 5674.8 5710.2 5723.5 5725.9 5800.0 5813.9 5851.1 5865.8 5869.6 5240.5 5288.2 5315.0 5335.7 5399.6 5844.9 5858.8 5874.9 5903.5 5910.6 5632.2 5637.2 5640.4 5641.2 5643.7 5610.5 5613.9 5646.2 5650.9 5661.4 5667.7 5671.2 5688.8 5728.0 5731.1 5621.7 5659.6 5670.4 5676.5 5679.2 4318.8 4329.2 4346.4 4353.6 4357.6 3866.8 3884.5 3894.0 3922.6 3928.3 5260.9 5400.5 5403.6 5409.9 5418.6 5694.7 5708.3 5748.1 5751.8 5787.9 5453.4 5457.0 5461.3 5465.2 5466.7 4706.4 4751.6 4858.1 4861.8 4913.8 5876.9 5919.8 5930.2 5944.3 5979.2 5706.4 5709.1 5713.1 5715.3 5729.9 5734.5 5872.8 5891.1 5899.5 5909.5 5634.6 5635.0 5638.5 5654.9 5704.8 5712.9 5745.9 5750.5 5761.1 5776.4 5467.8 5603.7 5607.4 5674.4 5691.7 5628.3 5655.7 5658.4 5665.4 5668.0 5606.8 5619.9 5625.5 5635.9 5639.4 5457.2 5495.2 5622.4 5684.4 5692.5 5389.3 5445.3 5472.9 5479.1 5480.8 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#1] ext.html Cycles:180 Average:63336.16 Median:23158.00 stddev:122612.03 (529.5%) stddev-sans-first:122865.48 11:45:35 INFO - PROCESS | 30010 | Values: 586.9 615.6 640.9 644.5 648.2 30433.8 43291.6 44474.0 44492.1 44543.9 44629.8 45172.0 45209.3 45268.5 45337.0 8080.1 8791.3 8959.6 10395.8 10679.0 10644.6 10655.5 10655.9 10759.2 10767.3 10826.0 10863.2 10868.0 10869.2 10876.3 10744.0 10746.7 10771.2 10783.9 10811.5 10675.7 10726.8 10730.3 10731.7 10734.7 41811.8 42113.0 42173.0 42275.8 42284.2 3517.7 3541.5 3585.4 3611.5 3660.5 32482.2 32672.8 32923.7 33437.8 34411.8 4028.8 4085.7 4157.6 4203.0 4214.5 372299.0 373243.0 373323.0 375269.0 375533.0 56496.4 56549.4 62736.7 62963.0 62975.4 64664.7 64686.0 64761.4 65035.3 65145.7 15260.0 15438.3 15446.3 15447.8 15462.7 12684.7 12744.2 12793.7 12801.3 12812.2 8074.6 8142.9 8391.2 8401.4 8409.2 7103.2 7131.7 7133.9 7261.0 7294.5 990.3 1031.6 1046.5 1094.0 1095.1 32771.8 33078.3 33158.5 33272.0 33519.8 1019.2 1159.4 1205.8 1224.1 1286.1 17455.1 17530.6 17536.7 17604.0 17734.6 511754.0 512417.0 516229.0 516885.0 519048.0 59209.7 59710.7 59735.0 59754.1 59787.0 1239.6 1349.6 1381.7 1482.6 1578.3 30023.2 33300.8 33855.7 34027.0 34662.8 53079.7 53773.5 53906.5 54069.2 54154.5 482550.0 489451.0 490245.0 490532.0 490622.0 39334.8 44801.8 47049.0 47086.3 47527.5 49303.8 49848.3 50397.0 53190.5 55800.7 42758.1 44480.6 44642.0 46065.5 47128.3 41600.8 45396.5 45539.0 45557.0 46572.0 43134.8 44413.8 44515.6 45088.0 45210.8 41409.0 41567.2 44652.0 45327.5 45437.0 33828.7 34790.7 34812.7 35075.6 35295.0 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#2] jquery.html Cycles:180 Average:28651.99 Median:404.64 stddev:76140.35 (18816.9%) stddev-sans-first:76324.39 11:45:35 INFO - PROCESS | 30010 | Values: 398.8 400.9 404.2 405.1 416.4 13817.7 18969.0 19460.3 19472.1 20795.9 13922.9 19438.0 19460.8 20096.0 20376.7 5787.2 5940.6 5951.6 5963.6 5982.0 5848.4 5920.2 5931.1 5933.3 5975.4 4320.2 6328.8 6636.5 6647.2 6754.9 6170.6 6556.8 6650.8 6716.3 6731.3 5879.5 5895.8 5897.0 5906.4 5921.1 14981.6 23562.8 24013.3 24210.6 24359.0 2446.0 2564.6 2592.6 2810.2 2840.8 9553.0 9956.4 10151.9 10153.9 10519.0 1863.3 1879.0 1880.9 1948.8 2012.0 82876.0 85193.1 86930.0 87147.6 87665.1 11238.3 11278.0 11294.1 11401.9 11559.3 15873.3 15900.1 16000.3 16011.0 16046.4 7935.8 8111.4 8237.8 8264.1 8294.4 7717.7 7739.1 7783.0 7847.8 7923.8 4378.2 4817.3 4847.3 4881.4 8484.7 3272.0 3502.9 3512.8 3595.4 3600.5 794.6 912.4 970.8 974.1 975.6 17306.2 18438.8 18561.5 18794.7 19449.2 883.6 885.3 895.1 903.8 933.8 10049.6 10382.7 10462.3 10476.7 10484.6 454980.0 455734.0 456349.0 456890.0 475519.0 89661.6 92055.5 92092.5 93613.0 97949.7 1018.4 1039.7 1068.4 1073.7 1075.7 18286.4 18319.7 18528.4 18756.0 18887.2 44808.3 45169.8 45222.5 45300.8 47200.3 56026.0 58126.4 58451.0 63551.4 63719.7 8708.4 8832.9 8847.3 8912.6 8942.9 10093.4 13564.5 13869.9 14179.2 14462.0 12106.0 12309.9 12404.8 12571.0 12579.0 11533.2 11713.2 11738.2 11792.4 11895.5 12149.6 12269.0 12309.4 12674.9 12896.4 8266.7 8270.8 8524.0 8813.4 8907.4 11516.9 12496.2 12541.7 12544.0 12568.8 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#3] mootools.html Cycles:180 Average:5972.96 Median:4476.90 stddev:3478.00 (77.7%) stddev-sans-first:3469.52 11:45:35 INFO - PROCESS | 30010 | Values: 1233.1 1498.5 1503.9 1640.3 1794.5 8396.7 8803.6 8810.0 8816.2 9084.7 8558.5 8843.6 9069.8 9070.8 9071.8 1999.1 2234.3 2287.1 2315.1 2326.8 2312.8 2327.9 2330.6 2331.2 2331.8 2288.6 2304.8 2308.3 2308.9 2318.4 1954.8 2189.1 2259.2 2259.9 2268.3 2277.6 2293.7 2313.5 2315.4 2315.4 9061.0 9082.6 9090.3 9091.8 9100.3 5123.2 5301.6 5304.4 5309.8 5311.0 3257.5 3342.2 3350.4 3393.2 3424.9 1027.1 1048.3 1052.1 1056.2 1056.6 12576.6 12851.5 12857.9 12858.4 12869.6 4349.3 4365.3 4469.9 4483.9 4608.5 10725.4 10782.1 10787.0 10787.9 10791.5 5348.8 5368.2 5372.8 5375.2 5375.3 4261.8 4775.4 4899.2 4903.1 4997.1 4072.1 4079.7 4188.5 4192.4 4216.6 1159.5 1268.3 1286.7 1316.5 1337.4 965.4 969.1 970.4 979.7 987.8 6091.8 6124.8 6140.0 6162.7 6359.8 983.9 994.3 995.9 1007.4 1079.9 4180.1 4287.8 4305.3 4311.4 4362.7 11167.0 11580.2 11642.3 11642.8 11647.1 11476.9 11520.8 11522.4 11529.2 11545.6 8075.4 8394.3 8413.1 8478.2 8627.7 6370.9 6515.0 6526.0 6542.0 6558.6 10618.2 10727.7 10773.2 10834.6 10888.8 10481.9 10646.7 11038.2 11066.7 11086.9 7834.1 7941.2 7991.9 8068.7 8078.8 8432.2 8468.2 8598.7 8789.3 8814.9 6518.8 7789.4 7951.2 8069.5 8080.6 7631.8 7687.7 7799.8 7913.1 8032.0 7049.3 7100.3 7236.9 7304.1 7308.7 5261.4 5288.1 5389.0 5408.9 5489.5 5204.1 5323.9 5398.5 5423.8 5544.8 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#4] prototype.html Cycles:180 Average:4662.94 Median:5338.41 stddev:1380.30 (25.9%) stddev-sans-first:1345.08 11:45:35 INFO - PROCESS | 30010 | Values: 317.3 321.8 325.5 329.6 330.7 5321.5 5326.4 5349.4 5365.2 5365.4 5258.0 5272.6 5279.9 5286.5 5299.9 3914.8 4020.6 4157.3 4178.7 4181.5 4078.2 4149.5 4154.3 4160.9 4163.4 3941.4 4119.6 4123.3 4123.3 4137.8 4009.8 4025.3 4077.8 4087.9 4135.1 3629.3 3639.5 3659.4 3659.6 3669.3 5154.6 5163.7 5193.6 5196.9 5241.1 4476.0 4688.3 4691.0 4713.1 4717.4 5535.7 5542.5 5550.3 5554.2 5555.0 3814.0 3947.5 3955.2 3960.6 3975.6 5716.5 5720.9 5738.0 5743.0 5760.1 4031.5 4034.6 4041.8 4045.1 4072.1 3900.4 4100.0 4108.4 4110.8 4114.6 4653.3 4668.7 4690.3 4692.6 4700.2 5552.9 5566.8 5594.3 5602.2 5641.9 1328.7 1399.0 1400.9 1407.9 1411.4 872.2 886.3 887.6 895.6 937.6 5264.3 5273.1 5298.8 5328.4 5353.7 5592.0 5595.5 5607.7 5635.2 5636.6 5094.0 5291.9 5330.4 5337.5 5339.4 2989.9 2993.6 3003.7 3011.3 3018.0 5747.1 5747.3 5757.1 5767.4 5771.9 5499.6 5703.3 5708.5 5722.7 5739.7 5744.2 5751.7 5772.6 5778.4 5831.4 5437.5 5591.3 5617.0 5640.0 5734.2 5655.6 5678.3 5681.0 5683.3 5692.2 5904.4 5923.3 5954.5 5955.7 5962.7 3969.6 4070.0 4074.4 4075.2 4091.1 5714.3 5714.4 5723.9 5732.4 5792.7 5410.2 5623.4 5627.8 5645.9 5660.5 5595.2 5596.5 5600.8 5603.2 5606.7 5271.9 5518.3 5527.9 5532.3 5548.3 5394.5 5567.6 5630.3 5635.8 5636.8 5459.9 5471.8 5479.7 5486.6 5488.5 11:45:35 INFO - PROCESS | 30010 | 11:45:35 INFO - PROCESS | 30010 | [#5] yui.html Cycles:180 Average:21681.44 Median:12277.80 stddev:31430.10 (256.0%) stddev-sans-first:31478.99 11:45:35 INFO - PROCESS | 30010 | Values: 755.5 755.6 766.1 772.8 865.8 14216.6 14800.4 15246.8 15316.6 15536.8 15191.8 15384.3 15389.9 15407.2 15531.8 663.2 776.7 809.1 821.3 826.7 818.8 822.1 824.7 824.8 825.3 856.2 857.5 858.6 859.9 860.3 697.9 838.6 850.4 853.4 860.9 604.0 605.5 605.8 607.1 609.9 14370.7 14375.2 14396.3 14397.0 14455.6 14021.0 14236.3 14240.7 14282.7 14466.9 12915.6 13004.7 13109.8 13235.0 13360.4 4504.7 4523.6 4541.3 4679.4 4681.9 65706.7 67630.3 70786.3 71247.6 71646.6 11929.3 11948.5 12011.6 12172.6 12187.1 20813.5 20864.0 20868.6 20920.4 20983.2 8421.2 8457.8 8623.2 8635.0 8758.5 4723.1 4888.6 4943.7 4956.4 5014.5 3572.4 3662.3 3663.6 3678.0 3680.0 567.2 589.1 631.5 635.2 639.0 667.0 670.4 687.5 692.8 765.9 10231.2 10253.1 10353.2 10428.9 10700.5 665.1 709.8 756.9 765.6 785.7 4327.0 4412.3 4561.1 5155.9 5299.4 112453.4 117642.0 119653.0 119657.0 123811.4 85163.0 103077.0 104044.5 104169.5 105209.5 97738.5 98055.5 98645.0 101253.5 101314.0 10562.8 10629.1 10701.3 10813.4 10813.8 53028.5 53427.7 53797.7 54675.0 56020.4 78719.0 82691.0 85862.6 86828.0 87372.0 12810.4 13510.9 13533.9 13606.9 13626.9 15167.2 15408.2 15423.5 15674.2 16172.7 13622.7 13683.6 13759.2 14019.6 14831.4 14445.5 14630.3 14784.2 15018.5 15332.3 14413.6 14644.4 14780.6 14873.3 14959.0 10329.8 10948.8 11335.4 11387.1 11489.0 10529.7 10988.9 11019.8 11693.0 11852.9 11:45:35 INFO - PROCESS | 30010 | -------- Summary: end -------- 11:45:35 INFO - PROCESS | 30010 | 11:45:36 INFO - TEST-INFO | 30010: exit 0 11:45:36 INFO - TEST-OK | dromaeo_css | took 1114044ms 11:45:36 INFO - TEST-START | kraken 11:45:36 INFO - operating with platform_type : linux_ 11:45:36 INFO - Initialising browser for kraken test... 11:45:36 INFO - TEST-INFO | started process 30197 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwK1olT/profile http://localhost:36838/getInfo.html) 11:45:40 INFO - PROCESS | 30197 | __metrics Screen width/height:1600/1200 11:45:40 INFO - PROCESS | 30197 | colorDepth:24 11:45:40 INFO - PROCESS | 30197 | Browser inner width/height: 1024/697 11:45:40 INFO - PROCESS | 30197 | __metrics 11:45:41 INFO - PROCESS | 30197 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:45:41 INFO - PROCESS | 30197 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:45:41 INFO - PROCESS | 30197 | console.error: PushService: 11:45:41 INFO - PROCESS | 30197 | stateChangeProcessEnqueue: Error transitioning state 11:45:41 INFO - PROCESS | 30197 | UnknownError 11:45:42 INFO - TEST-INFO | 30197: exit 0 11:45:42 INFO - Browser initialized. 11:45:42 INFO - Running cycle 1/1 for kraken test... 11:45:42 INFO - TEST-INFO | started process 30279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwK1olT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:54 INFO - PROCESS | 30279 | RSS: Main: 157691904 11:45:54 INFO - PROCESS | 30279 | 11:45:56 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36838/tests/kraken/driver.html?testName=audio-beat-detection) 11:45:57 INFO - PROCESS | 30279 | RSS: Main: 217174016 11:45:57 INFO - PROCESS | 30279 | 11:46:00 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36838/tests/kraken/driver.html?testName=audio-dft) 11:46:00 INFO - PROCESS | 30279 | RSS: Main: 204763136 11:46:00 INFO - PROCESS | 30279 | 11:46:03 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36838/tests/kraken/driver.html?testName=audio-fft) 11:46:04 INFO - PROCESS | 30279 | RSS: Main: 270811136 11:46:04 INFO - PROCESS | 30279 | 11:46:06 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36838/tests/kraken/driver.html?testName=audio-oscillator) 11:46:07 INFO - PROCESS | 30279 | RSS: Main: 180674560 11:46:07 INFO - PROCESS | 30279 | 11:46:09 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36838/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:09 INFO - PROCESS | 30279 | RSS: Main: 184832000 11:46:09 INFO - PROCESS | 30279 | 11:46:12 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36838/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:13 INFO - PROCESS | 30279 | RSS: Main: 219394048 11:46:13 INFO - PROCESS | 30279 | 11:46:16 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36838/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:17 INFO - PROCESS | 30279 | RSS: Main: 221306880 11:46:17 INFO - PROCESS | 30279 | 11:46:20 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36838/tests/kraken/driver.html?testName=json-parse-financial) 11:46:20 INFO - PROCESS | 30279 | RSS: Main: 221569024 11:46:20 INFO - PROCESS | 30279 | 11:46:22 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36838/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:22 INFO - PROCESS | 30279 | RSS: Main: 210206720 11:46:22 INFO - PROCESS | 30279 | 11:46:24 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:25 INFO - PROCESS | 30279 | RSS: Main: 214323200 11:46:25 INFO - PROCESS | 30279 | 11:46:27 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:28 INFO - PROCESS | 30279 | RSS: Main: 211185664 11:46:28 INFO - PROCESS | 30279 | 11:46:30 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:31 INFO - PROCESS | 30279 | RSS: Main: 222478336 11:46:31 INFO - PROCESS | 30279 | 11:46:33 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:34 INFO - PROCESS | 30279 | RSS: Main: 224997376 11:46:34 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | Cycle 1(1): loaded http://localhost:36838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36838/tests/kraken/driver.html?testName=ai-astar) 11:46:36 INFO - PROCESS | 30279 | RSS: Main: 267300864 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | __start_tp_report 11:46:36 INFO - PROCESS | 30279 | _x_x_mozilla_page_load 11:46:36 INFO - PROCESS | 30279 | _x_x_mozilla_page_load_details 11:46:36 INFO - PROCESS | 30279 | |i|pagename|runs| 11:46:36 INFO - PROCESS | 30279 | |0;ai-astar;110;111;109;109;111;110;110;110;110;111 11:46:36 INFO - PROCESS | 30279 | |1;audio-beat-detection;151;119;117;170;118;124;150;116;159;116 11:46:36 INFO - PROCESS | 30279 | |2;audio-dft;161;160;161;160;163;161;162;163;163;161 11:46:36 INFO - PROCESS | 30279 | |3;audio-fft;75;73;75;113;75;75;76;76;98;75 11:46:36 INFO - PROCESS | 30279 | |4;audio-oscillator;83;83;113;83;84;82;121;85;83;124 11:46:36 INFO - PROCESS | 30279 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 11:46:36 INFO - PROCESS | 30279 | |6;imaging-darkroom;146;146;145;145;147;146;146;145;146;146 11:46:36 INFO - PROCESS | 30279 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;94 11:46:36 INFO - PROCESS | 30279 | |8;json-parse-financial;52;49;51;50;50;51;51;51;50;56 11:46:36 INFO - PROCESS | 30279 | |9;json-stringify-tinderbox;63;64;62;63;63;62;67;63;62;63 11:46:36 INFO - PROCESS | 30279 | |10;stanford-crypto-aes;76;75;74;81;75;75;74;73;80;77 11:46:36 INFO - PROCESS | 30279 | |11;stanford-crypto-ccm;132;117;126;127;120;118;118;117;194;130 11:46:36 INFO - PROCESS | 30279 | |12;stanford-crypto-pbkdf2;155;154;155;154;157;156;160;161;159;154 11:46:36 INFO - PROCESS | 30279 | |13;stanford-crypto-sha256-iterative;72;63;63;63;62;62;62;62;62;64 11:46:36 INFO - PROCESS | 30279 | __end_tp_report 11:46:36 INFO - PROCESS | 30279 | __start_cc_report 11:46:36 INFO - PROCESS | 30279 | _x_x_mozilla_cycle_collect,1611 11:46:36 INFO - PROCESS | 30279 | __end_cc_report 11:46:36 INFO - PROCESS | 30279 | __startTimestamp1468608396281__endTimestamp 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | ------- Summary: start ------- 11:46:36 INFO - PROCESS | 30279 | Number of tests: 14 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 11:46:36 INFO - PROCESS | 30279 | Values: 110.0 111.0 109.0 109.0 111.0 110.0 110.0 110.0 110.0 111.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:137.00 stddev:21.04 (15.4%) stddev-sans-first:21.40 11:46:36 INFO - PROCESS | 30279 | Values: 151.0 119.0 117.0 170.0 118.0 124.0 150.0 116.0 159.0 116.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.18 (0.7%) stddev-sans-first:1.24 11:46:36 INFO - PROCESS | 30279 | Values: 161.0 160.0 161.0 160.0 163.0 161.0 162.0 163.0 163.0 161.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.36 (17.8%) stddev-sans-first:13.99 11:46:36 INFO - PROCESS | 30279 | Values: 75.0 73.0 75.0 113.0 75.0 75.0 76.0 76.0 98.0 75.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.00 stddev:17.63 (21.2%) stddev-sans-first:18.24 11:46:36 INFO - PROCESS | 30279 | Values: 83.0 83.0 113.0 83.0 84.0 82.0 121.0 85.0 83.0 124.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 11:46:36 INFO - PROCESS | 30279 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 11:46:36 INFO - PROCESS | 30279 | Values: 146.0 146.0 145.0 145.0 147.0 146.0 146.0 145.0 146.0 146.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:46:36 INFO - PROCESS | 30279 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:1.91 (3.7%) stddev-sans-first:2.00 11:46:36 INFO - PROCESS | 30279 | Values: 52.0 49.0 51.0 50.0 50.0 51.0 51.0 51.0 50.0 56.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:1.48 (2.3%) stddev-sans-first:1.56 11:46:36 INFO - PROCESS | 30279 | Values: 63.0 64.0 62.0 63.0 63.0 62.0 67.0 63.0 62.0 63.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:75.50 stddev:2.62 (3.5%) stddev-sans-first:2.78 11:46:36 INFO - PROCESS | 30279 | Values: 76.0 75.0 74.0 81.0 75.0 75.0 74.0 73.0 80.0 77.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:126.50 stddev:23.22 (18.4%) stddev-sans-first:24.61 11:46:36 INFO - PROCESS | 30279 | Values: 132.0 117.0 126.0 127.0 120.0 118.0 118.0 117.0 194.0 130.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:156.50 stddev:2.64 (1.7%) stddev-sans-first:2.74 11:46:36 INFO - PROCESS | 30279 | Values: 155.0 154.0 155.0 154.0 157.0 156.0 160.0 161.0 159.0 154.0 11:46:36 INFO - PROCESS | 30279 | 11:46:36 INFO - PROCESS | 30279 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:3.06 (4.9%) stddev-sans-first:0.73 11:46:36 INFO - PROCESS | 30279 | Values: 72.0 63.0 63.0 63.0 62.0 62.0 62.0 62.0 62.0 64.0 11:46:36 INFO - PROCESS | 30279 | -------- Summary: end -------- 11:46:36 INFO - PROCESS | 30279 | 11:46:37 INFO - TEST-INFO | 30279: exit 0 11:46:37 INFO - TEST-OK | kraken | took 60586ms 11:46:37 INFO - SUITE-END | took 1174s 11:46:37 INFO - Completed test suite (00:19:34) 11:46:37 INFO - javascript benchmark 11:46:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3039.2098054505827, 3050.9542356864663, 3071.708187722167, 3080.260737423018, 3092.38152369526, 5288.92066619001, 5353.277307563478, 5371.785128594852, 5411.729413529322, 5434.3207099112615, 5344.251804747329, 5396.83809485716, 5408.0, 5415.593830462712, 5465.344158700951, 4841.975790121044, 4886.63350248731, 4893.04585605807, 4931.580815630676, 5084.2373643953515, 5032.0, 5038.59691224703, 5048.671836330626, 5050.3434553508, 5052.166392545226, 4795.664303498739, 4830.275458681191, 4839.7338146401935, 4967.627427942901, 4987.900241995158, 4885.5358740919555, 4886.169351210267, 4887.584555312803, 4888.804447822083, 4894.583960363373, 4830.975845120787, 4835.468098509161, 4842.757862106881, 4854.393200849894, 4861.052094841507, 5256.553192978601, 5323.680579165224, 5363.973180134074, 5394.946050539523, 5409.6213265071465, 5674.290713660793, 5674.773009079632, 5710.229119068956, 5723.51350135239, 5725.856853578653, 5800.0, 5813.88372232553, 5851.093080572518, 5865.794697185621, 5869.559783016291, 5240.528352448298, 5288.233206185082, 5315.01672190646, 5335.67985920846, 5399.649022813504, 5844.9415505845245, 5858.794942177004, 5874.9118763218585, 5903.527717782567, 5910.586258961831, 5632.239647647597, 5637.210790489335, 5640.351359593699, 5641.153826925912, 5643.689597072201, 5610.4670056344585, 5613.887722245614, 5646.181303710939, 5650.85872853182, 5661.377248502661, 5667.688277144714, 5671.234383358194, 5688.800891968761, 5727.971360143256, 5731.140328950607, 5621.690807005654, 5659.6321239119325, 5670.432956704297, 5676.517496012802, 5679.2333035040565, 4318.762468064224, 4329.242382583036, 4346.413234213341, 4353.608175264241, 4357.564243575617, 3866.7679939203736, 3884.475595794588, 3894.0, 3922.607739226112, 3928.2929072766606, 5260.894782104337, 5400.540954018874, 5403.594730395236, 5409.864753381197, 5418.566514678895, 5694.715264236772, 5708.257926469532, 5748.051571490741, 5751.827445176651, 5787.913181302284, 5453.372862120827, 5457.017736807413, 5461.290032295776, 5465.152901300305, 5466.726663666801, 4706.388169538007, 4751.619870410359, 4858.076965376567, 4861.80552777892, 4913.778879950411, 5876.941230587725, 5919.792807251725, 5930.169776231331, 5944.256967879015, 5979.222701048836, 5706.400827913009, 5709.115087161496, 5713.05734553794, 5715.342735585295, 5729.8567535811935, 5734.5125664319, 5872.794452194153, 5891.057430811221, 5899.4985426239255, 5909.52723782105, 5634.6337488063145, 5635.042042852643, 5638.464345887134, 5654.85862853432, 5704.828855134353, 5712.857178570569, 5745.856353591026, 5750.453706897838, 5761.135829625589, 5776.364599893925, 5467.753951072291, 5603.691796951207, 5607.41122182165, 5674.375818659862, 5691.658500489983, 5628.268325117709, 5655.7172141393585, 5658.377578466448, 5665.433456654301, 5668.03643380618, 5606.775728970795, 5619.887602247851, 5625.46558076982, 5635.915461268002, 5639.379668236573, 5457.235986961908, 5495.203195536705, 5622.437756224344, 5684.431556844448, 5692.516136128475, 5389.299391079135, 5445.264889239902, 5472.945270547243, 5479.068558345011, 5480.780768769205], "value": 5280.631291687938, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.9031609784386, 615.5937081526192, 640.9166808314918, 644.4554351572925, 648.2188962300429, 30433.847830760842, 43291.567084329145, 44474.0, 44492.11015779682, 44543.886402839904, 44629.7768511157, 45172.0, 45209.321860172124, 45268.54731452685, 45337.0, 8080.070791858952, 8791.252743516809, 8959.552022398886, 10395.792084158313, 10679.0, 10644.62743803967, 10655.520501577408, 10655.946720266387, 10759.193060520452, 10767.300125491818, 10826.0, 10863.18526110545, 10868.0, 10869.239153259276, 10876.293040952352, 10744.0, 10746.73133171677, 10771.24601277911, 10783.946080269588, 10811.459427028618, 10675.679729608124, 10726.839097413545, 10730.302530335503, 10731.731706707316, 10734.677959661223, 41811.7909410454, 42113.0, 42173.0, 42275.7886210567, 42284.15431691349, 3517.7009954153673, 3541.5395998520025, 3585.3904836177912, 3611.5124458198334, 3660.4509323601405, 32482.18794530132, 32672.836635816908, 32923.6707632923, 33437.83281083604, 34411.827940860385, 4028.7582745035393, 4085.7140000199997, 4157.605027522381, 4203.0, 4214.515330736974, 372299.0, 373243.0, 373323.0, 375269.0, 375533.0, 56496.43503564994, 56549.43450565442, 62736.68631656813, 62963.0, 62975.37024629787, 64664.67667661631, 64686.0, 64761.35238647553, 65035.34964650293, 65145.67427162834, 15260.0, 15438.30527626237, 15446.304916278794, 15447.768283475756, 15462.690746185239, 12684.746305073846, 12744.235345879277, 12793.744125117631, 12801.295928723823, 12812.167209131376, 8074.596270186467, 8142.918570814334, 8391.244787969112, 8401.411901166859, 8409.192023714932, 7103.218645948941, 7131.679074441663, 7133.89299160513, 7260.96369518149, 7294.452916031319, 990.3117333453325, 1031.6131450705984, 1046.4610725476234, 1094.0099210214769, 1095.0965453500735, 32771.83614081962, 33078.3384332312, 33158.502622460685, 33272.0, 33519.83240083784, 1019.202289714723, 1159.368144361325, 1205.7829590673764, 1224.088054399478, 1286.067600989294, 17455.127243637766, 17530.64938701219, 17536.736948945778, 17604.0, 17734.645307093786, 511754.0, 512417.0, 516229.0, 516885.0, 519048.0, 59209.70395148083, 59710.70144649249, 59735.0, 59754.10368844471, 59787.0, 1239.5723475400973, 1349.5546469664987, 1381.7236552689503, 1482.5700546841294, 1578.2976575423827, 30023.249418764706, 33300.833495833336, 33855.661443386234, 34027.0, 34662.82668586641, 53079.734601326745, 53773.462265376846, 53906.46093539015, 54069.18896216639, 54154.45845541494, 482550.0, 489451.0, 490245.0, 490532.0, 490622.0, 39334.80332598319, 44801.77599111984, 47049.0, 47086.293705595126, 47527.52472475231, 49303.7534812338, 49848.252276215164, 50397.0, 53190.4680953201, 55800.72099639476, 42758.144837103704, 44480.55519444764, 44642.0, 46065.53934460613, 47128.29307560321, 41600.79199603983, 45396.546034539235, 45539.0, 45557.0, 46572.0, 43134.78432607817, 44413.77793111014, 44515.554844451144, 45088.0, 45210.77394613006, 41409.0, 41567.168656627306, 44652.0, 45327.54672453233, 45437.0, 33828.66171338354, 34790.65209347874, 34812.65187348094, 35075.649243507236, 35295.0], "value": 21081.176709657277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.7986067036147, 400.92582872168634, 404.215821306665, 405.0622808199019, 416.36100481789174, 13817.651728080114, 18969.0, 19460.318888838894, 19472.12375443105, 20795.896020519875, 13922.93038534806, 19438.0, 19460.805391946076, 20096.0, 20376.694349584766, 5787.160861675044, 5940.584159108864, 5951.58338916276, 5963.642181469104, 5982.0, 5848.356680765112, 5920.171176035358, 5931.051031834907, 5933.317668468117, 5975.43233392827, 4320.200762858881, 6328.778492752755, 6636.535442519025, 6647.20233571972, 6754.932450675479, 6170.598911070811, 6556.803295901131, 6650.800475985704, 6716.3283671632935, 6731.259561448237, 5879.470847623692, 5895.764169433218, 5896.970515147397, 5906.438888305604, 5921.141434491975, 14981.625459363495, 23562.7643723564, 24013.27960161198, 24210.63684044741, 24359.02563897442, 2445.9510809783883, 2564.6024866145776, 2592.6370308156875, 2810.2412348665744, 2840.815347002438, 9552.95223523878, 9956.402615843, 10151.94924025375, 10153.89846101537, 10519.0, 1863.2640107157733, 1878.962420751591, 1880.8965506897116, 1948.756405449319, 2011.979880201194, 82876.0, 85193.14806851913, 86930.0, 87147.56426217829, 87665.12334876697, 11238.325700458, 11278.0, 11294.096472282197, 11401.94299028516, 11559.30644161353, 15873.285702143432, 15900.12549309799, 16000.279987400594, 16011.0, 16046.358145674043, 7935.801604959922, 8111.432199745961, 8237.752867413987, 8264.090949995496, 8294.419390642717, 7717.729879454192, 7739.110002349689, 7783.0, 7847.843043139105, 7923.762287131395, 4378.233809083397, 4817.253325734517, 4847.2729090635985, 4881.365422495124, 8484.703035393855, 3272.0081500569268, 3502.9474557881654, 3512.754107212522, 3595.424732042861, 3600.4959305697225, 794.5629903553023, 912.3750231091724, 970.839811431106, 974.084360700953, 975.5853762151057, 17306.22122004513, 18438.81561184371, 18561.5359616008, 18794.718079228824, 19449.222031118596, 883.6421249394006, 885.3227281129941, 895.0557162193948, 903.7876099116702, 933.8272419602396, 10049.648262310784, 10382.688519344281, 10462.26764126519, 10476.68569942903, 10484.63303784364, 454980.0, 455734.0, 456349.0, 456890.0, 475519.0, 89661.55169224112, 92055.53972230096, 92092.53953730188, 93613.0, 97949.73578038474, 1018.3940555369543, 1039.745262410699, 1068.385678235018, 1073.7476692977143, 1075.6611667324767, 18286.359977400727, 18319.72520412168, 18528.44414667562, 18756.0, 18887.15007824679, 44808.32787508255, 45169.77415112904, 45222.54777452315, 45300.77349613231, 47200.29199562078, 56026.0, 58126.41873581379, 58451.0, 63551.3644863564, 63719.68140159455, 8708.390412671051, 8832.91167088321, 8847.259945678203, 8912.55437228149, 8942.910570894468, 10093.446747334525, 13564.457421703022, 13869.930650346683, 14179.22014289224, 14462.0, 12106.0, 12309.93845030769, 12404.81392779127, 12571.0, 12578.993680505355, 11533.192676512563, 11713.1800773945, 11738.237014594024, 11792.351420671945, 11895.524179032742, 12149.574764883188, 12269.0, 12309.384530773605, 12674.936625316814, 12896.41966111546, 8266.669333226604, 8270.849579109354, 8523.957380213307, 8813.383063185773, 8907.376483646083, 11516.942415288206, 12496.187747796364, 12541.686457838263, 12544.0, 12568.811467827807], "value": 9573.438095034828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.0936761480316, 1498.4530646314095, 1503.8571335723104, 1640.3110693508734, 1794.5334213104588, 8396.664133434662, 8803.603837827297, 8810.030896601376, 8816.20654141128, 9084.682036128737, 8558.529280889548, 8843.602037908293, 9069.773255668611, 9070.773230669236, 9071.818563628723, 1999.1004048178306, 2234.307364716943, 2287.1194590082814, 2315.0623997281127, 2326.8371214015024, 2312.7571604981486, 2327.9068837246573, 2330.5921463743775, 2331.207389487574, 2331.8484298520543, 2288.5880541502524, 2304.7925686688195, 2308.284431826131, 2308.9076436942587, 2318.40880575453, 1954.765428148617, 2189.1462329691435, 2259.1641092795608, 2259.8757068361233, 2268.3308424014954, 2277.5786479501344, 2293.70181876355, 2313.5257272259146, 2315.3632750993443, 2315.363275099361, 9060.954695226548, 9082.591283392263, 9090.318226133, 9091.818163636757, 9100.317476189311, 5123.1546794778815, 5301.628885977983, 5304.416514183436, 5309.7610607522365, 5310.973445132789, 3257.5439438478634, 3342.181165614416, 3350.363430948112, 3393.219559501304, 3424.8972530824117, 1027.091024443373, 1048.3133547526381, 1052.1004541117388, 1056.1550759392533, 1056.6143357674393, 12576.622701318975, 12851.485940562367, 12857.935710321388, 12858.421371038326, 12869.613911582668, 4349.3475978603465, 4365.2797288447055, 4469.865904022885, 4483.932741008887, 4608.539146085364, 10725.356478611307, 10782.137429005661, 10786.975237352439, 10787.946060269649, 10791.46042697862, 5348.786048558092, 5368.248445217672, 5372.758225879845, 5375.16684913839, 5375.3280839895015, 4261.765602891809, 4775.355327030876, 4899.191633380524, 4903.068417000758, 4997.075541024922, 4072.083781149218, 4079.6940229482907, 4188.518250542615, 4192.392103145027, 4216.587443206498, 1159.47244003978, 1268.2770820632152, 1286.6767660660175, 1316.4668309334734, 1337.4382759241141, 965.4159233663673, 969.0793745941384, 970.3692599810039, 979.7452662307851, 987.8419452887504, 6091.786787462506, 6124.785632502841, 6140.0, 6162.7226774795245, 6359.841003974938, 983.8720966274338, 994.2890833054256, 995.9203263738883, 1007.4308015970953, 1079.8542196803487, 4180.143070670505, 4287.7641729705165, 4305.268104422193, 4311.353297005474, 4362.650987920959, 11167.0, 11580.247283926517, 11642.301461912313, 11642.76714465706, 11647.12646551512, 11476.942615286871, 11520.76958460826, 11522.366269855238, 11529.192956492965, 11545.595904143314, 8075.3539716822515, 8394.286485648667, 8413.116622754646, 8478.152184781473, 8627.741167764852, 6370.872582548231, 6514.967425162844, 6526.0, 6542.0, 6558.606483610999, 10618.150547955991, 10727.731806704895, 10773.24587278883, 10834.620788272372, 10888.782224355627, 10481.895181048398, 10646.680599582025, 11038.17213708959, 11066.667999959693, 11086.944565277123, 7834.138244793182, 7941.205879412013, 7991.880121798062, 8068.7175948841505, 8078.757637270891, 8432.156784321518, 8468.237858592756, 8598.656053757779, 8789.296856251356, 8814.911850881666, 6518.837029074311, 7789.376849851885, 7951.204879512003, 8069.475484093515, 8080.636371363185, 7631.809204769926, 7687.654055567401, 7799.766007019798, 7913.089994650459, 8031.959840200761, 7049.330313620198, 7100.254473280231, 7236.927630723625, 7304.086989126359, 7308.707651693872, 5261.421243663118, 5288.074586947346, 5389.0, 5408.864778380572, 5489.451054894478, 5204.141316682856, 5323.866903327448, 5398.46015398457, 5423.83728488146, 5544.750486228048], "value": 4727.6450241704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.2876891378854, 321.8149564000699, 325.4597368368508, 329.5649742340112, 330.69906385189483, 5321.52106310432, 5326.360836699591, 5349.358077030757, 5365.222042803791, 5365.436629153941, 5257.973710131453, 5272.551833094191, 5279.894402111955, 5286.524212820846, 5299.894002119955, 3914.823832927518, 4020.557738648746, 4157.272477316473, 4178.728382655133, 4181.519125300584, 4078.245524577948, 4149.481314835645, 4154.31453810121, 4160.9375859362135, 4163.375493675942, 3941.408788681692, 4119.567445418233, 4123.278426275405, 4123.319652257389, 4137.77242251676, 4009.7794621295816, 4025.315696331616, 4077.7757223352705, 4087.8773636790943, 4135.1316223593085, 3629.256002519477, 3639.508666330038, 3659.3596120678776, 3659.5608526976935, 3669.321175582499, 5154.587632989352, 5163.741812909377, 5193.623689722189, 5196.948030519722, 5241.135212689902, 4476.0, 4688.288774597281, 4691.0, 4713.091198064789, 4717.363155973934, 5535.6955367454775, 5542.528885044776, 5550.27846379968, 5554.166874968747, 5555.0, 3813.959531650554, 3947.5460322063045, 3955.2287303975736, 3960.5643379228263, 3975.6421922026866, 5716.485516303553, 5720.941625799241, 5737.999999999958, 5742.961248756673, 5760.135979603051, 4031.5162180538523, 4034.636882680573, 4041.777702226406, 4045.110075783323, 4072.1448495815616, 3900.356441187229, 4099.954511599523, 4108.36320370343, 4110.77390743512, 4114.6091121343425, 4653.348531205634, 4668.673192876466, 4690.273007683814, 4692.601128904013, 4700.2244629635725, 5552.888942221133, 5566.832995010156, 5594.321703488937, 5602.187682786055, 5641.887162256732, 1328.7010422655019, 1398.9860101398856, 1400.865299107726, 1407.8662527059912, 1411.3931009665941, 872.2062922740275, 886.2599729226168, 887.5828360670474, 895.6014573514854, 937.5780898595659, 5264.263003179558, 5273.077211487975, 5298.814541491107, 5328.413874473805, 5353.741870660392, 5592.0, 5595.468430499096, 5607.738258891798, 5635.1828984796975, 5636.619028338084, 5094.033754686817, 5291.920621190685, 5330.440303768126, 5337.466253374631, 5339.359276886798, 2989.9402011960074, 2993.6108305920525, 3003.714647108521, 3011.2772934495565, 3017.9547306790414, 5747.1379293106365, 5747.281589801275, 5757.050086735725, 5767.413961160631, 5771.88456230873, 5499.642523236057, 5703.315602127771, 5708.457696518824, 5722.713864306768, 5739.684317362585, 5744.167095771089, 5751.654900705971, 5772.567057470623, 5778.422157784272, 5831.35855055952, 5437.4562543745305, 5591.329040515164, 5617.0, 5639.971800140973, 5734.19372897536, 5655.575831812548, 5678.261825962599, 5680.971595141998, 5683.261176047253, 5692.203091567267, 5904.409559044061, 5923.289205295392, 5954.523638108854, 5955.731992060454, 5962.731677074456, 3969.5633480316565, 4069.9796501017304, 4074.449949256931, 4075.2460794753065, 4091.1204091120553, 5714.257146570919, 5714.3999880012, 5723.8855222896145, 5732.426757324234, 5792.739326730391, 5410.241671456713, 5623.437656234508, 5627.774889004394, 5645.9153112702525, 5660.518855897295, 5595.160725891149, 5596.524295434933, 5600.775968961196, 5603.159526070959, 5606.6916319602815, 5271.920921186109, 5518.310211223597, 5527.917081243865, 5532.336119665505, 5548.334199896039, 5394.541463975449, 5567.55459563242, 5630.325595342343, 5635.774569017193, 5636.830895073154, 5459.887863917529, 5471.781128754965, 5479.698616576126, 5486.6133232044185, 5488.4785945335125], "value": 4216.408600378402, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5466719968019, 755.5693254844741, 766.0539234045956, 772.7527191298783, 865.8008658008658, 14216.573502794907, 14800.407983680667, 15246.847531524681, 15316.617084572863, 15536.844631553695, 15191.848081519182, 15384.307706153222, 15389.923050384732, 15407.229638518085, 15531.84468155318, 663.2273401487262, 776.7126163319551, 809.0534075132094, 821.2690705272313, 826.68999125328, 818.8321394114192, 822.0669540072001, 824.7319621123129, 824.8102936324649, 825.2943733108197, 856.2251162697737, 857.4769390671685, 858.6393714639835, 859.8882145321099, 860.2773670117132, 697.9162500499947, 838.6310023589632, 850.4174640371334, 853.4196746212573, 860.9311255099577, 604.0245004317994, 605.4702135631322, 605.8031139879513, 607.1499900139795, 609.9115628233926, 14370.712585748332, 14375.209363485004, 14396.280185990763, 14397.0, 14455.566333009921, 14021.0, 14236.28818559068, 14240.715185696332, 14282.714345713028, 14466.92766536171, 12915.612531624067, 13004.674883127998, 13109.803352949713, 13235.014747787813, 13360.398782054734, 4504.707194032378, 4523.615492683126, 4541.3188021797, 4679.3916790816975, 4681.906361872743, 65706.67146664285, 67630.32369676339, 70786.29213707849, 71247.64376178086, 71646.64176679135, 11929.284242945278, 11948.462319195656, 12011.5586129665, 12172.634820955385, 12187.146899716934, 20813.479663008548, 20864.0, 20868.582628347653, 20920.37238882836, 20983.16067357319, 8421.15788421153, 8457.788555286044, 8623.180797824196, 8634.956825215959, 8758.518281494578, 4723.102610503994, 4888.584470319992, 4943.728094954812, 4956.3804524434445, 5014.523620256143, 3572.4462708280257, 3662.322470342942, 3663.5603727552866, 3677.9517837416206, 3680.0, 567.2002476508104, 589.107502134267, 631.5295105146695, 635.2472320300459, 638.9616623002635, 666.9828511519971, 670.4468813229008, 687.5084314714903, 692.8094773937207, 765.9387249020065, 10231.181505479693, 10253.109403469893, 10353.171746260281, 10428.947855260674, 10700.464976751287, 665.058941597634, 709.8296408861836, 756.9280918312752, 765.6478020110811, 785.6739453126888, 4326.978365108217, 4412.316091005835, 4561.110583436264, 5155.948440515622, 5299.417064122943, 112453.43773281081, 117642.0, 119653.0, 119657.0, 123811.3809430965, 85163.0, 103077.0, 104044.47977760063, 104169.47915260527, 105209.47395263128, 97738.51130744301, 98055.50972245094, 98645.0, 101253.49373253234, 101313.97372052666, 10562.775748138314, 10629.096526795, 10701.304415212986, 10813.351198928089, 10813.783724325627, 53028.46971530235, 53427.73286133545, 53797.73101134626, 54675.0, 56020.439795601524, 78719.0, 82691.0, 85862.57068714616, 86828.0, 87372.0, 12810.423530940943, 13510.93244533771, 13533.93233033868, 13606.93196534011, 13626.863731362955, 15167.165805880783, 15408.15255160932, 15423.537293881202, 15674.216289185268, 16172.654690618763, 13622.727545449234, 13683.589492315246, 13759.17444953306, 14019.579412617637, 14831.406743730562, 14445.4944076961, 14630.34163462668, 14784.186869722269, 15018.549443516711, 15332.31004604773, 14413.639659008608, 14644.414223430944, 14780.63048423755, 14873.256337182882, 14959.027663201858, 10329.793404132026, 10948.781024379627, 11335.433228338385, 11387.145964052563, 11489.0, 10529.684109476728, 10988.890111099106, 11019.83470247931, 11693.0, 11852.881471185177], "value": 7779.328582504768, "unit": "score"}], "name": "dromaeo_css", "value": 7408.055692662248, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 109.0, 109.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 117.0, 170.0, 118.0, 124.0, 150.0, 116.0, 159.0, 116.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 163.0, 161.0, 162.0, 163.0, 163.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 113.0, 75.0, 75.0, 76.0, 76.0, 98.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 113.0, 83.0, 84.0, 82.0, 121.0, 85.0, 83.0, 124.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 50.0, 50.0, 51.0, 51.0, 51.0, 50.0, 56.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 63.0, 63.0, 62.0, 67.0, 63.0, 62.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 81.0, 75.0, 75.0, 74.0, 73.0, 80.0, 77.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 117.0, 126.0, 127.0, 120.0, 118.0, 118.0, 117.0, 194.0, 130.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 155.0, 154.0, 157.0, 156.0, 160.0, 161.0, 159.0, 154.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 64.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1478.6000000000001, "alertThreshold": 2.0}]} 11:46:37 INFO - Return code: 0 11:46:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:37 INFO - # TBPL SUCCESS # 11:46:37 INFO - Running post-action listener: _resource_record_post_action 11:46:37 INFO - ##### 11:46:37 INFO - ##### Finished run-tests step (success) 11:46:37 INFO - ##### 11:46:37 INFO - Running post-run listener: _resource_record_post_run 11:46:37 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 558465024; Read time: 20; Write time: 455680 11:46:37 INFO - TinderboxPrint: CPU usage
12.9% 11:46:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 11:46:37 INFO - TinderboxPrint: I/O write bytes / time
558,465,024 / 455,680 11:46:37 INFO - TinderboxPrint: CPU idle
8,282.5 (87.0%) 11:46:37 INFO - TinderboxPrint: CPU user
1,188.7 (12.5%) 11:46:37 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:37 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 263970816; Read time: 0; Write time: 273644 11:46:37 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 290594816; Read time: 0; Write time: 181572 11:46:38 INFO - Running post-run listener: _upload_blobber_files 11:46:38 INFO - Blob upload gear active. 11:46:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:46:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:46:38 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:46:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:46:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:39 INFO - (blobuploader) - INFO - Done attempting. 11:46:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:43 INFO - (blobuploader) - INFO - Done attempting. 11:46:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:46:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:44 INFO - (blobuploader) - INFO - Done attempting. 11:46:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:44 INFO - Return code: 0 11:46:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12b85c73727765305284840ecdfb49270e12fe43f134f7d132e1a59f03ff3f14e0b45f30e89394c80dd28100e6406826f9e78f958aad890e74c3481e01578a0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef3eb4483b3149de697e6baa5fb545081173e08979503cbbd7561e35ec14a03f786bea69ba3df7e981379873e60c8aae8d300dda6c51091f6f0c93f6cec26b6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/90de5458d9e910294dcf4434dfff29f6c3bd182487b81c1476c424d313f5a114c41fbb55b3c7746486832685fb8af975ef79f9a4bfc1f61a34f92b1068bac415"} 11:46:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:44 INFO - Contents: 11:46:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12b85c73727765305284840ecdfb49270e12fe43f134f7d132e1a59f03ff3f14e0b45f30e89394c80dd28100e6406826f9e78f958aad890e74c3481e01578a0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef3eb4483b3149de697e6baa5fb545081173e08979503cbbd7561e35ec14a03f786bea69ba3df7e981379873e60c8aae8d300dda6c51091f6f0c93f6cec26b6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/90de5458d9e910294dcf4434dfff29f6c3bd182487b81c1476c424d313f5a114c41fbb55b3c7746486832685fb8af975ef79f9a4bfc1f61a34f92b1068bac415"} 11:46:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:44 INFO - Copying logs to upload dir... 11:46:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.619241 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-15 11:46:44.069468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:46:44.073419) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12b85c73727765305284840ecdfb49270e12fe43f134f7d132e1a59f03ff3f14e0b45f30e89394c80dd28100e6406826f9e78f958aad890e74c3481e01578a0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef3eb4483b3149de697e6baa5fb545081173e08979503cbbd7561e35ec14a03f786bea69ba3df7e981379873e60c8aae8d300dda6c51091f6f0c93f6cec26b6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/90de5458d9e910294dcf4434dfff29f6c3bd182487b81c1476c424d313f5a114c41fbb55b3c7746486832685fb8af975ef79f9a4bfc1f61a34f92b1068bac415"} build_url:https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/aQvAVuH7TjSjFx4XkiV5Gw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12b85c73727765305284840ecdfb49270e12fe43f134f7d132e1a59f03ff3f14e0b45f30e89394c80dd28100e6406826f9e78f958aad890e74c3481e01578a0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef3eb4483b3149de697e6baa5fb545081173e08979503cbbd7561e35ec14a03f786bea69ba3df7e981379873e60c8aae8d300dda6c51091f6f0c93f6cec26b6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/90de5458d9e910294dcf4434dfff29f6c3bd182487b81c1476c424d313f5a114c41fbb55b3c7746486832685fb8af975ef79f9a4bfc1f61a34f92b1068bac415"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:46:44.098925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:46:44.099263) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1468607169.837470-661508881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:46:44.183746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:46:44.184074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:46:44.184375) ========= ========= Total master_lag: 0.17 =========