builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-030 starttime: 1462770035.89 results: success (0) buildid: 20160508193137 builduid: 49205ae2634743e6b10e02a6836dc9f9 revision: 827f631db4deadf61200f3e5ab05e44834f73ee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.889068) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.889463) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.889731) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.921323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.921611) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-08 22:00:36-- 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% 37.5M=0s 2016-05-08 22:00:36 (37.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146542 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.082171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.082466) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019655 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.134280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.134634) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 827f631db4deadf61200f3e5ab05e44834f73ee0 --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 827f631db4deadf61200f3e5ab05e44834f73ee0 --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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-08 22:00:36,500 truncating revision to first 12 chars 2016-05-08 22:00:36,500 Setting DEBUG logging. 2016-05-08 22:00:36,500 attempt 1/10 2016-05-08 22:00:36,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/827f631db4de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-08 22:00:36,753 unpacking tar archive at: mozilla-inbound-827f631db4de/testing/mozharness/ program finished with exit code 0 elapsedTime=0.544928 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.692346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.692665) ========= script_repo_revision: 827f631db4deadf61200f3e5ab05e44834f73ee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.693062) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.693326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.703847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-08 22:00:36.704221) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:37 INFO - MultiFileLogger online at 20160508 22:00:37 in /builds/slave/test 22:00:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:00:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:37 INFO - {'append_to_log': False, 22:00:37 INFO - 'base_work_dir': '/builds/slave/test', 22:00:37 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:37 INFO - 'branch': 'Mozilla-Inbound', 22:00:37 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:37 INFO - 'config_files': ('talos/linux_config.py',), 22:00:37 INFO - 'default_actions': ('clobber', 22:00:37 INFO - 'read-buildbot-config', 22:00:37 INFO - 'download-and-extract', 22:00:37 INFO - 'populate-webroot', 22:00:37 INFO - 'create-virtualenv', 22:00:37 INFO - 'install', 22:00:37 INFO - 'run-tests'), 22:00:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:37 INFO - 'download_minidump_stackwalk': True, 22:00:37 INFO - 'download_symbols': 'ondemand', 22:00:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:37 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:37 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:37 INFO - 'installer_path': 'installer.exe', 22:00:37 INFO - 'log_level': 'info', 22:00:37 INFO - 'log_name': 'talos', 22:00:37 INFO - 'log_to_console': True, 22:00:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:37 INFO - 'opt_config_files': (), 22:00:37 INFO - 'pip_index': False, 22:00:37 INFO - 'sps_profile': False, 22:00:37 INFO - 'sps_profile_interval': 0, 22:00:37 INFO - 'suite': 'dromaeojs-e10s', 22:00:37 INFO - 'system_bits': '32', 22:00:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:37 INFO - 'title': 'talos-linux64-ix-030', 22:00:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:37 INFO - 'use_talos_json': True, 22:00:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:37 INFO - 'work_dir': 'build'} 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running clobber step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: clobber 22:00:37 INFO - rmtree: /builds/slave/test/build 22:00:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:38 INFO - Running post-action listener: _resource_record_post_action 22:00:38 INFO - ##### 22:00:38 INFO - ##### Finished clobber step (success) 22:00:38 INFO - ##### 22:00:38 INFO - ##### 22:00:38 INFO - ##### Running read-buildbot-config step. 22:00:38 INFO - ##### 22:00:38 INFO - Running pre-action listener: _resource_record_pre_action 22:00:38 INFO - Running main action method: read_buildbot_config 22:00:38 INFO - Using buildbot properties: 22:00:38 INFO - { 22:00:38 INFO - "project": "", 22:00:38 INFO - "product": "firefox", 22:00:38 INFO - "script_repo_revision": "production", 22:00:38 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:00:38 INFO - "repository": "", 22:00:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:00:38 INFO - "buildid": "20160508193137", 22:00:38 INFO - "pgo_build": "True", 22:00:38 INFO - "basedir": "/builds/slave/test", 22:00:38 INFO - "buildnumber": 626, 22:00:38 INFO - "slavename": "talos-linux64-ix-030", 22:00:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:00:38 INFO - "platform": "ubuntu64_hw", 22:00:38 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:38 INFO - "branch": "mozilla-inbound", 22:00:38 INFO - "repo_path": "integration/mozilla-inbound", 22:00:38 INFO - "revision": "827f631db4deadf61200f3e5ab05e44834f73ee0", 22:00:38 INFO - "stage_platform": "linux64-pgo", 22:00:38 INFO - "builduid": "49205ae2634743e6b10e02a6836dc9f9", 22:00:38 INFO - "slavebuilddir": "test" 22:00:38 INFO - } 22:00:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:00:38 INFO - Running post-action listener: _resource_record_post_action 22:00:38 INFO - ##### 22:00:38 INFO - ##### Finished read-buildbot-config step (success) 22:00:38 INFO - ##### 22:00:38 INFO - ##### 22:00:38 INFO - ##### Running download-and-extract step. 22:00:38 INFO - ##### 22:00:38 INFO - Running pre-action listener: _resource_record_pre_action 22:00:38 INFO - Running main action method: download_and_extract 22:00:38 INFO - mkdir: /builds/slave/test/build/tests 22:00:38 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')]} 22:00:38 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:00:48 INFO - Downloaded 1361 bytes. 22:00:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:48 INFO - Using the following test package requirements: 22:00:48 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'jsshell-linux-x86_64.zip'], 22:00:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:48 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:48 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')]} 22:00:48 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:59 INFO - Downloaded 23896445 bytes. 22:00:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:01:01 INFO - Return code: 0 22:01:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:01:01 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')]} 22:01:01 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:01:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:01:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:01:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:01:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:03 INFO - Downloaded 23896445 bytes. 22:01:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:01:05 INFO - Return code: 0 22:01:05 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')]} 22:01:05 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:01:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:01:06 INFO - Downloaded 10840152 bytes. 22:01:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:01:06 INFO - Return code: 0 22:01:06 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')]} 22:01:06 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:01:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:01:07 INFO - Downloaded 58423036 bytes. 22:01:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:07 INFO - mkdir: /builds/slave/test/properties 22:01:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:07 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:07 INFO - Contents: 22:01:07 INFO - build_url:https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:08 INFO - Running post-action listener: _resource_record_post_action 22:01:08 INFO - Running post-action listener: set_extra_try_arguments 22:01:08 INFO - ##### 22:01:08 INFO - ##### Finished download-and-extract step (success) 22:01:08 INFO - ##### 22:01:08 INFO - ##### 22:01:08 INFO - ##### Running populate-webroot step. 22:01:08 INFO - ##### 22:01:08 INFO - Running pre-action listener: _resource_record_pre_action 22:01:08 INFO - Running main action method: populate_webroot 22:01:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:08 INFO - '--tppagecycles', 22:01:08 INFO - '7'], 22:01:08 INFO - 'tests': ['tsvgm']}}, 22:01:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['damp', 'tps']}, 22:01:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'tests': ['damp', 'tps']}, 22:01:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['dromaeo_dom']}, 22:01:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['a11yr', 22:01:08 INFO - 'ts_paint', 22:01:08 INFO - 'tpaint', 22:01:08 INFO - 'sessionrestore', 22:01:08 INFO - 'sessionrestore_no_auto_restore', 22:01:08 INFO - 'tabpaint']}, 22:01:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['tsvgx', 22:01:08 INFO - 'tsvgr_opacity', 22:01:08 INFO - 'tart', 22:01:08 INFO - 'tscrollx', 22:01:08 INFO - 'cart']}, 22:01:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:08 INFO - 'tsvgr_opacity', 22:01:08 INFO - 'tart', 22:01:08 INFO - 'tscrollx', 22:01:08 INFO - 'cart']}, 22:01:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'talos_options': ['--disable-e10s'], 22:01:08 INFO - 'tests': ['tp5o']}, 22:01:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'tests': ['tp5o']}, 22:01:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'talos_options': ['--disable-e10s', 22:01:08 INFO - '--xperf_path', 22:01:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:08 INFO - 'tests': ['tp5n']}, 22:01:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:08 INFO - 'talos_options': ['--xperf_path', 22:01:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:08 INFO - 'tests': ['tp5n']}}, 22:01:08 INFO - 'talos.zip': {'path': '', 22:01:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:08 INFO - Running post-action listener: _resource_record_post_action 22:01:08 INFO - ##### 22:01:08 INFO - ##### Finished populate-webroot step (success) 22:01:08 INFO - ##### 22:01:08 INFO - ##### 22:01:08 INFO - ##### Running create-virtualenv step. 22:01:08 INFO - ##### 22:01:08 INFO - Running pre-action listener: _resource_record_pre_action 22:01:08 INFO - Running main action method: create_virtualenv 22:01:08 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:08 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 22:01:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:08 INFO - Using real prefix '/usr' 22:01:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:09 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:11 INFO - Installing pip.................done. 22:01:11 INFO - Return code: 0 22:01:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:11 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')]} 22:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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')]} 22:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:11 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 22:01:11 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 22:01:11 INFO - Using env: {'DISPLAY': ':0', 22:01:11 INFO - 'HOME': '/home/cltbld', 22:01:11 INFO - 'LANG': 'en_US.UTF-8', 22:01:11 INFO - 'LANGUAGE': 'en_US:en', 22:01:11 INFO - 'LOGNAME': 'cltbld', 22:01:11 INFO - 'MAIL': '/var/mail/cltbld', 22:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:11 INFO - 'MOZ_NO_REMOTE': '1', 22:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:11 INFO - 'NO_EM_RESTART': '1', 22:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:11 INFO - 'PWD': '/builds/slave/test', 22:01:11 INFO - 'SHELL': '/bin/bash', 22:01:11 INFO - 'SHLVL': '1', 22:01:11 INFO - 'TERM': 'linux', 22:01:11 INFO - 'TMOUT': '86400', 22:01:11 INFO - 'USER': 'cltbld', 22:01:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:11 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 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 22:01:11 INFO - Cleaning up... 22:01:11 INFO - Return code: 0 22:01:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:01:11 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')]} 22:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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')]} 22:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:11 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>=0.7.1'] in /builds/slave/test/build 22:01:11 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>=0.7.1 22:01:11 INFO - Using env: {'DISPLAY': ':0', 22:01:11 INFO - 'HOME': '/home/cltbld', 22:01:11 INFO - 'LANG': 'en_US.UTF-8', 22:01:11 INFO - 'LANGUAGE': 'en_US:en', 22:01:11 INFO - 'LOGNAME': 'cltbld', 22:01:11 INFO - 'MAIL': '/var/mail/cltbld', 22:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:11 INFO - 'MOZ_NO_REMOTE': '1', 22:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:11 INFO - 'NO_EM_RESTART': '1', 22:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:11 INFO - 'PWD': '/builds/slave/test', 22:01:11 INFO - 'SHELL': '/bin/bash', 22:01:11 INFO - 'SHLVL': '1', 22:01:11 INFO - 'TERM': 'linux', 22:01:11 INFO - 'TMOUT': '86400', 22:01:11 INFO - 'USER': 'cltbld', 22:01:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:11 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 INFO - Downloading/unpacking psutil>=0.7.1 22:01:11 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 22:01:11 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 22:01:11 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 22:01:11 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 22:01:11 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 22:01:11 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 22:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:13 INFO - Installing collected packages: psutil 22:01:13 INFO - Running setup.py install for psutil 22:01:13 INFO - building 'psutil._psutil_linux' extension 22:01:13 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 22:01:14 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 22:01:14 INFO - building 'psutil._psutil_posix' extension 22:01:14 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 22:01:14 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 22:01:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:14 INFO - Successfully installed psutil 22:01:14 INFO - Cleaning up... 22:01:14 INFO - Return code: 0 22:01:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:14 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:14 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.0.0'] in /builds/slave/test/build 22:01:14 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.0.0 22:01:14 INFO - Using env: {'DISPLAY': ':0', 22:01:14 INFO - 'HOME': '/home/cltbld', 22:01:14 INFO - 'LANG': 'en_US.UTF-8', 22:01:14 INFO - 'LANGUAGE': 'en_US:en', 22:01:14 INFO - 'LOGNAME': 'cltbld', 22:01:14 INFO - 'MAIL': '/var/mail/cltbld', 22:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:14 INFO - 'MOZ_NO_REMOTE': '1', 22:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:14 INFO - 'NO_EM_RESTART': '1', 22:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:14 INFO - 'PWD': '/builds/slave/test', 22:01:14 INFO - 'SHELL': '/bin/bash', 22:01:14 INFO - 'SHLVL': '1', 22:01:14 INFO - 'TERM': 'linux', 22:01:14 INFO - 'TMOUT': '86400', 22:01:14 INFO - 'USER': 'cltbld', 22:01:14 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:14 INFO - '_': '/tools/buildbot/bin/python'} 22:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:01:14 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 22:01:14 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 22:01:14 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 22:01:14 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 22:01:14 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 22:01:14 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 22:01:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:01:16 INFO - Installing collected packages: mozsystemmonitor 22:01:16 INFO - Running setup.py install for mozsystemmonitor 22:01:16 INFO - Successfully installed mozsystemmonitor 22:01:16 INFO - Cleaning up... 22:01:16 INFO - Return code: 0 22:01:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:16 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')]} 22:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:16 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')]} 22:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:16 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:16 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 22:01:16 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 22:01:16 INFO - Using env: {'DISPLAY': ':0', 22:01:16 INFO - 'HOME': '/home/cltbld', 22:01:16 INFO - 'LANG': 'en_US.UTF-8', 22:01:16 INFO - 'LANGUAGE': 'en_US:en', 22:01:16 INFO - 'LOGNAME': 'cltbld', 22:01:16 INFO - 'MAIL': '/var/mail/cltbld', 22:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:16 INFO - 'MOZ_NO_REMOTE': '1', 22:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:16 INFO - 'NO_EM_RESTART': '1', 22:01:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:16 INFO - 'PWD': '/builds/slave/test', 22:01:16 INFO - 'SHELL': '/bin/bash', 22:01:16 INFO - 'SHLVL': '1', 22:01:16 INFO - 'TERM': 'linux', 22:01:16 INFO - 'TMOUT': '86400', 22:01:16 INFO - 'USER': 'cltbld', 22:01:16 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:16 INFO - '_': '/tools/buildbot/bin/python'} 22:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:16 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 22:01:16 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 22:01:16 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 22:01:16 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 22:01:16 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 22:01:16 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 22:01:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:19 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 22:01:19 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 22:01:19 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 22:01:19 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 22:01:19 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 22:01:19 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 22:01:19 INFO - Downloading docopt-0.6.1.tar.gz 22:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:19 INFO - Running setup.py install for blobuploader 22:01:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:19 INFO - Running setup.py install for requests 22:01:19 INFO - Running setup.py install for docopt 22:01:19 INFO - Successfully installed blobuploader requests docopt 22:01:20 INFO - Cleaning up... 22:01:20 INFO - Return code: 0 22:01:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:20 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')]} 22:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:20 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')]} 22:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:20 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:20 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 22:01:20 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 22:01:20 INFO - Using env: {'DISPLAY': ':0', 22:01:20 INFO - 'HOME': '/home/cltbld', 22:01:20 INFO - 'LANG': 'en_US.UTF-8', 22:01:20 INFO - 'LANGUAGE': 'en_US:en', 22:01:20 INFO - 'LOGNAME': 'cltbld', 22:01:20 INFO - 'MAIL': '/var/mail/cltbld', 22:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:20 INFO - 'MOZ_NO_REMOTE': '1', 22:01:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:20 INFO - 'NO_EM_RESTART': '1', 22:01:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:20 INFO - 'PWD': '/builds/slave/test', 22:01:20 INFO - 'SHELL': '/bin/bash', 22:01:20 INFO - 'SHLVL': '1', 22:01:20 INFO - 'TERM': 'linux', 22:01:20 INFO - 'TMOUT': '86400', 22:01:20 INFO - 'USER': 'cltbld', 22:01:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:20 INFO - '_': '/tools/buildbot/bin/python'} 22:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:20 INFO - Running setup.py (path:/tmp/pip-MEUF87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:20 INFO - Running setup.py (path:/tmp/pip-d5OcLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:20 INFO - Running setup.py (path:/tmp/pip-1E3eVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:20 INFO - Running setup.py (path:/tmp/pip-CgF_x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:20 INFO - Running setup.py (path:/tmp/pip-Knrfdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:20 INFO - Running setup.py (path:/tmp/pip-GfnYEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:20 INFO - Running setup.py (path:/tmp/pip-zJIRE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:20 INFO - Running setup.py (path:/tmp/pip-T2GDho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:20 INFO - Running setup.py (path:/tmp/pip-1c9D1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:20 INFO - Running setup.py (path:/tmp/pip-zDYMuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:20 INFO - Running setup.py (path:/tmp/pip-pIKg4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:21 INFO - Running setup.py (path:/tmp/pip-JKmvA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:21 INFO - Running setup.py (path:/tmp/pip-0FKROx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:21 INFO - Running setup.py (path:/tmp/pip-odxNuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:21 INFO - Running setup.py (path:/tmp/pip-_4jTGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:21 INFO - Running setup.py (path:/tmp/pip-ugkNkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:21 INFO - Running setup.py (path:/tmp/pip-YqxHwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:21 INFO - Running setup.py install for manifestparser 22:01:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:21 INFO - Running setup.py install for mozcrash 22:01:21 INFO - Running setup.py install for mozdebug 22:01:21 INFO - Running setup.py install for mozdevice 22:01:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:21 INFO - Running setup.py install for mozfile 22:01:22 INFO - Running setup.py install for mozhttpd 22:01:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozinfo 22:01:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozInstall 22:01:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozleak 22:01:22 INFO - Running setup.py install for mozlog 22:01:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for moznetwork 22:01:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozprocess 22:01:22 INFO - Running setup.py install for mozprofile 22:01:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozrunner 22:01:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:22 INFO - Running setup.py install for mozscreenshot 22:01:23 INFO - Running setup.py install for moztest 22:01:23 INFO - Running setup.py install for mozversion 22:01:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:23 INFO - Cleaning up... 22:01:23 INFO - Return code: 0 22:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:23 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')]} 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 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')]} 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:23 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 22:01:23 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 22:01:23 INFO - Using env: {'DISPLAY': ':0', 22:01:23 INFO - 'HOME': '/home/cltbld', 22:01:23 INFO - 'LANG': 'en_US.UTF-8', 22:01:23 INFO - 'LANGUAGE': 'en_US:en', 22:01:23 INFO - 'LOGNAME': 'cltbld', 22:01:23 INFO - 'MAIL': '/var/mail/cltbld', 22:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:23 INFO - 'MOZ_NO_REMOTE': '1', 22:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:23 INFO - 'NO_EM_RESTART': '1', 22:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:23 INFO - 'PWD': '/builds/slave/test', 22:01:23 INFO - 'SHELL': '/bin/bash', 22:01:23 INFO - 'SHLVL': '1', 22:01:23 INFO - 'TERM': 'linux', 22:01:23 INFO - 'TMOUT': '86400', 22:01:23 INFO - 'USER': 'cltbld', 22:01:23 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:23 INFO - '_': '/tools/buildbot/bin/python'} 22:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:23 INFO - Running setup.py (path:/tmp/pip-b2059h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:23 INFO - Running setup.py (path:/tmp/pip-fHBvRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:23 INFO - Running setup.py (path:/tmp/pip-M2yXNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:23 INFO - Running setup.py (path:/tmp/pip-B_iTMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:23 INFO - Running setup.py (path:/tmp/pip-DPhL3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:23 INFO - Running setup.py (path:/tmp/pip-JGpjCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:23 INFO - Running setup.py (path:/tmp/pip-HMiLaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:23 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)) 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:23 INFO - Running setup.py (path:/tmp/pip-lkG2Ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:24 INFO - Running setup.py (path:/tmp/pip-LBY6_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:24 INFO - Running setup.py (path:/tmp/pip-0znSm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:24 INFO - Running setup.py (path:/tmp/pip-1zJriD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:24 INFO - Running setup.py (path:/tmp/pip-Z2j6ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:24 INFO - Running setup.py (path:/tmp/pip-Z6uj5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:24 INFO - Running setup.py (path:/tmp/pip-wNvlYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:24 INFO - Running setup.py (path:/tmp/pip-3l3eCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:24 INFO - Running setup.py (path:/tmp/pip-_SRQJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:24 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)) 22:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:24 INFO - Running setup.py (path:/tmp/pip-QhCXWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:24 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)) 22:01:24 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)) 22:01:24 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)) 22:01:24 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)) 22:01:24 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)) 22:01:24 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)) 22:01:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:26 INFO - Downloading blessings-1.6.tar.gz 22:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:26 INFO - Installing collected packages: blessings 22:01:26 INFO - Running setup.py install for blessings 22:01:26 INFO - Successfully installed blessings 22:01:26 INFO - Cleaning up... 22:01:26 INFO - Return code: 0 22:01:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:27 INFO - Reading from file tmpfile_stdout 22:01:27 INFO - Current package versions: 22:01:27 INFO - argparse == 1.2.1 22:01:27 INFO - blessings == 1.6 22:01:27 INFO - blobuploader == 1.2.4 22:01:27 INFO - docopt == 0.6.1 22:01:27 INFO - manifestparser == 1.1 22:01:27 INFO - mozInstall == 1.12 22:01:27 INFO - mozcrash == 0.17 22:01:27 INFO - mozdebug == 0.1 22:01:27 INFO - mozdevice == 0.48 22:01:27 INFO - mozfile == 1.2 22:01:27 INFO - mozhttpd == 0.7 22:01:27 INFO - mozinfo == 0.9 22:01:27 INFO - mozleak == 0.1 22:01:27 INFO - mozlog == 3.1 22:01:27 INFO - moznetwork == 0.27 22:01:27 INFO - mozprocess == 0.22 22:01:27 INFO - mozprofile == 0.28 22:01:27 INFO - mozrunner == 6.11 22:01:27 INFO - mozscreenshot == 0.1 22:01:27 INFO - mozsystemmonitor == 0.0 22:01:27 INFO - moztest == 0.7 22:01:27 INFO - mozversion == 1.4 22:01:27 INFO - psutil == 3.1.1 22:01:27 INFO - requests == 1.2.3 22:01:27 INFO - wsgiref == 0.1.2 22:01:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:27 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')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:27 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 22:01:27 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 22:01:27 INFO - Using env: {'DISPLAY': ':0', 22:01:27 INFO - 'HOME': '/home/cltbld', 22:01:27 INFO - 'LANG': 'en_US.UTF-8', 22:01:27 INFO - 'LANGUAGE': 'en_US:en', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MAIL': '/var/mail/cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SHLVL': '1', 22:01:27 INFO - 'TERM': 'linux', 22:01:27 INFO - 'TMOUT': '86400', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - '_': '/tools/buildbot/bin/python'} 22:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 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)) 22:01:27 INFO - Cleaning up... 22:01:27 INFO - Return code: 0 22:01:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:27 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')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:27 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 22:01:27 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 22:01:27 INFO - Using env: {'DISPLAY': ':0', 22:01:27 INFO - 'HOME': '/home/cltbld', 22:01:27 INFO - 'LANG': 'en_US.UTF-8', 22:01:27 INFO - 'LANGUAGE': 'en_US:en', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MAIL': '/var/mail/cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SHLVL': '1', 22:01:27 INFO - 'TERM': 'linux', 22:01:27 INFO - 'TMOUT': '86400', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - '_': '/tools/buildbot/bin/python'} 22:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:27 INFO - Downloading/unpacking jsonschema 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:29 INFO - Downloading/unpacking functools32 (from jsonschema) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:29 INFO - warning: no files found matching '*.txt' 22:01:29 INFO - no previously-included directories found matching 'build' 22:01:29 INFO - no previously-included directories found matching 'dist' 22:01:29 INFO - no previously-included directories found matching '.git*' 22:01:29 INFO - Installing collected packages: jsonschema, functools32 22:01:29 INFO - Running setup.py install for functools32 22:01:29 INFO - warning: no files found matching '*.txt' 22:01:29 INFO - no previously-included directories found matching 'build' 22:01:29 INFO - no previously-included directories found matching 'dist' 22:01:29 INFO - no previously-included directories found matching '.git*' 22:01:29 INFO - Successfully installed jsonschema functools32 22:01:29 INFO - Cleaning up... 22:01:29 INFO - Return code: 0 22:01:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:01:29 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')]} 22:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 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')]} 22:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1b8df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xec0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, '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': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', '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 22:01:29 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', 'flake8'] in /builds/slave/test/build 22:01:29 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 flake8 22:01:29 INFO - Using env: {'DISPLAY': ':0', 22:01:29 INFO - 'HOME': '/home/cltbld', 22:01:29 INFO - 'LANG': 'en_US.UTF-8', 22:01:29 INFO - 'LANGUAGE': 'en_US:en', 22:01:29 INFO - 'LOGNAME': 'cltbld', 22:01:29 INFO - 'MAIL': '/var/mail/cltbld', 22:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:29 INFO - 'MOZ_NO_REMOTE': '1', 22:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:29 INFO - 'NO_EM_RESTART': '1', 22:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:29 INFO - 'PWD': '/builds/slave/test', 22:01:29 INFO - 'SHELL': '/bin/bash', 22:01:29 INFO - 'SHLVL': '1', 22:01:29 INFO - 'TERM': 'linux', 22:01:29 INFO - 'TMOUT': '86400', 22:01:29 INFO - 'USER': 'cltbld', 22:01:29 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:29 INFO - '_': '/tools/buildbot/bin/python'} 22:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:30 INFO - Downloading/unpacking flake8 22:01:30 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 22:01:30 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 22:01:30 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 22:01:30 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 22:01:30 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 22:01:30 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 22:01:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:32 INFO - no previously-included directories found matching 'docs/_build' 22:01:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:32 INFO - Running setup.py install for pep8 22:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:32 INFO - no previously-included directories found matching 'docs/_build' 22:01:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:32 INFO - Cleaning up... 22:01:32 INFO - Return code: 0 22:01:32 INFO - Running post-action listener: _resource_record_post_action 22:01:32 INFO - Running post-action listener: _start_resource_monitoring 22:01:32 INFO - Starting resource monitoring. 22:01:32 INFO - ##### 22:01:32 INFO - ##### Finished create-virtualenv step (success) 22:01:32 INFO - ##### 22:01:32 INFO - ##### 22:01:32 INFO - ##### Running install step. 22:01:32 INFO - ##### 22:01:32 INFO - Running pre-action listener: _resource_record_pre_action 22:01:32 INFO - Running main action method: install 22:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:33 INFO - Reading from file tmpfile_stdout 22:01:33 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:33 INFO - Reading from file tmpfile_stdout 22:01:33 INFO - Output received: 22:01:33 INFO - Usage: mozinstall [options] installer 22:01:33 INFO - Options: 22:01:33 INFO - -h, --help show this help message and exit 22:01:33 INFO - -d DEST, --destination=DEST 22:01:33 INFO - Directory to install application into. [default: 22:01:33 INFO - "/builds/slave/test"] 22:01:33 INFO - --app=APP Application being installed. [default: firefox] 22:01:33 INFO - mkdir: /builds/slave/test/build/application 22:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:49 INFO - Reading from file tmpfile_stdout 22:01:49 INFO - Output received: 22:01:49 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:49 INFO - Running post-action listener: _resource_record_post_action 22:01:49 INFO - ##### 22:01:49 INFO - ##### Finished install step (success) 22:01:49 INFO - ##### 22:01:49 INFO - ##### 22:01:49 INFO - ##### Running run-tests step. 22:01:49 INFO - ##### 22:01:49 INFO - Running pre-action listener: _resource_record_pre_action 22:01:49 INFO - Running main action method: run_tests 22:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:49 INFO - Python 2.7.3 22:01:49 INFO - Return code: 0 22:01:49 INFO - grabbing minidump binary from tooltool 22:01:49 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')]} 22:01:49 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4f9c0>, '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 0xf508c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf50d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:49 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 22:01:49 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 22:01:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:49 INFO - Return code: 0 22:01:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:50 INFO - Return code: 0 22:01:50 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:01:50 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:01:50 INFO - Using env: {'DISPLAY': ':0', 22:01:50 INFO - 'HOME': '/home/cltbld', 22:01:50 INFO - 'LANG': 'en_US.UTF-8', 22:01:50 INFO - 'LANGUAGE': 'en_US:en', 22:01:50 INFO - 'LOGNAME': 'cltbld', 22:01:50 INFO - 'MAIL': '/var/mail/cltbld', 22:01:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:50 INFO - 'MOZ_NO_REMOTE': '1', 22:01:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:50 INFO - 'NO_EM_RESTART': '1', 22:01:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:50 INFO - 'PWD': '/builds/slave/test', 22:01:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:50 INFO - 'SHELL': '/bin/bash', 22:01:50 INFO - 'SHLVL': '1', 22:01:50 INFO - 'TERM': 'linux', 22:01:50 INFO - 'TMOUT': '86400', 22:01:50 INFO - 'USER': 'cltbld', 22:01:50 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983', 22:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:50 INFO - '_': '/tools/buildbot/bin/python'} 22:01:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:01:50 INFO - mozversion application_buildid: 20160508193137 22:01:50 INFO - mozversion application_changeset: 827f631db4deadf61200f3e5ab05e44834f73ee0 22:01:50 INFO - mozversion application_display_name: Nightly 22:01:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:50 INFO - mozversion application_name: Firefox 22:01:50 INFO - mozversion application_remotingname: firefox 22:01:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:50 INFO - mozversion application_vendor: Mozilla 22:01:50 INFO - mozversion application_version: 49.0a1 22:01:50 INFO - mozversion platform_buildid: 20160508193137 22:01:50 INFO - mozversion platform_changeset: 827f631db4deadf61200f3e5ab05e44834f73ee0 22:01:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:50 INFO - mozversion platform_version: 49.0a1 22:01:50 INFO - using testdate: 1462770110 22:01:50 INFO - actual date: 1462770110 22:01:50 INFO - starting webserver on 'localhost:39079' 22:01:50 INFO - SUITE-START | Running 2 tests 22:01:50 INFO - TEST-START | dromaeo_css 22:01:50 INFO - operating with platform_type : linux_ 22:01:50 INFO - Initialising browser for dromaeo_css test... 22:01:50 INFO - TEST-INFO | started process 25999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGX9jJ/profile http://localhost:39079/getInfo.html) 22:01:54 INFO - PROCESS | 25999 | 22:01:54 INFO - PROCESS | 25999 | (process:26066): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:01:55 INFO - PROCESS | 25999 | __metrics Screen width/height:1600/1200 22:01:55 INFO - PROCESS | 25999 | colorDepth:24 22:01:55 INFO - PROCESS | 25999 | Browser inner width/height: 1024/697 22:01:55 INFO - PROCESS | 25999 | __metrics 22:01:56 ERROR - PROCESS | 25999 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:01:57 INFO - TEST-INFO | 25999: exit 0 22:01:57 INFO - Browser initialized. 22:01:57 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:57 INFO - TEST-INFO | started process 26117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGX9jJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:58 INFO - PROCESS | 26117 | 22:01:58 INFO - PROCESS | 26117 | (process:26176): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:02:09 INFO - PROCESS | 26117 | RSS: Main: 162820096 22:02:09 INFO - PROCESS | 26117 | 22:02:58 INFO - PROCESS | 26117 | 1462770178568 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] 22:05:05 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39079/tests/dromaeo/cssquery-ext.html) 22:05:06 INFO - PROCESS | 26117 | RSS: Main: 165314560 22:05:06 INFO - PROCESS | 26117 | 22:08:08 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-ext.html (next: http://localhost:39079/tests/dromaeo/cssquery-jquery.html) 22:08:09 INFO - PROCESS | 26117 | RSS: Main: 166236160 22:08:09 INFO - PROCESS | 26117 | 22:11:11 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39079/tests/dromaeo/cssquery-mootools.html) 22:11:12 INFO - PROCESS | 26117 | RSS: Main: 167198720 22:11:12 INFO - PROCESS | 26117 | 22:14:13 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39079/tests/dromaeo/cssquery-prototype.html) 22:14:14 INFO - PROCESS | 26117 | RSS: Main: 164659200 22:14:14 INFO - PROCESS | 26117 | 22:17:16 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39079/tests/dromaeo/cssquery-yui.html) 22:17:17 INFO - PROCESS | 26117 | RSS: Main: 166662144 22:17:17 INFO - PROCESS | 26117 | 22:20:19 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:39079/tests/dromaeo/cssquery-yui.html (next: http://localhost:39079/tests/dromaeo/cssquery-dojo.html) 22:20:20 INFO - PROCESS | 26117 | RSS: Main: 169230336 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | __start_tp_report 22:20:20 INFO - PROCESS | 26117 | _x_x_mozilla_page_load 22:20:20 INFO - PROCESS | 26117 | _x_x_mozilla_page_load_details 22:20:20 INFO - PROCESS | 26117 | |i|pagename|runs| 22:20:20 INFO - PROCESS | 26117 | |0;dojo.html;3170.365926814637;3175.396674631821;3200.199950012496;3218.4045951498974;3221.0658908916403;5423.783048678053;5452.781888724451;5487.972560137194;5516.779328826847;5585.636933599313;5462.3445186577665;5506.724663766806;5537.944620553793;5538.418466061066;5622.690752008638;4946.653734238605;4980.750962451881;5110.207917772751;5136.332276804011;5182.740862956837;637.3787511463875;5010.048090862742;5031.496850314976;5061.54446099851;5083.822066227683;5025.924611130836;5060.2409638554145;5064.24036394542;5066.797328106872;5118.206677964903;4943.8022479101155;5084.084864724348;5102.897942041139;5107.3360463139925;5109.540141387293;4957.950420495785;4962.503749625044;4999.400071991348;5010.624203184776;5055.039542486916;5356.946430535722;5442.074847275933;5444.8094316698725;5465.480779325958;5528.5024347808485;5848.502877255439;5876.206712093898;5880.6765627890445;5934.762609495659;5943;5966.67183304918;5992.520598352121;5997.430244126801;6025.6083354581815;6089.117078023662;5588.972055139699;5606.943930560724;5616;5620.353659329188;5623.184638227475;5968.104784282393;6037.486813620803;6042.3655516170165;6084.817455476343;6115.816525504242;5839.211706419579;5843.1235314703135;5845.766169353178;5865.120231965239;5865.296164460292;5963.5825492216;5963.642181469126;5971.28344598651;5975.611585246945;5977;5677.432256774372;5788.392218816964;5804.361520232771;5844.883102338014;5876.471117599436;5858.619189752652;5886.941130588725;5943.138244954457;5978.073398623221;5979.103134529855;4432.357308190294;4435.423394958635;4443.266860967904;4450.888727781831;4492.460904691458;3889.8638547651262;3892.338302488584;3900.414937759302;3935.763854168759;3942.4677668514955;5677.574181936371;5691.26013618227;5693.3168019837885;5695.45893140151;5753.108268218432;5799.9130013049835;5955.493783028404;5990.490808281258;5998.220231369894;6004.189434426296;5645.1532270159805;5693.117566777156;5703.201551782754;5720.8283751487525;5742.339630942495;4948.455669876311;4954.851354459372;4954.925676114861;4967.5777558907175;5032.622553308516;5968.015277479254;6096.254562767882;6203.7828675996125;6205.193324867829;6208.099825525273;5965.403459654087;6039.335673075871;6041.546883983631;6049.78825741097;6076.574639775174;6180.6600636965395;6186.226721659793;6201.844953876189;6205.317415084427;6216.44052035319;5760.683162426107;5762;5792.855178620568;5897.174395584708;5915.201447804491;5993.130996005642;5994.520438365008;6000.51995840323;6000.759969601167;6008.24896887889;5758.884822303614;5762.538996880324;5813.79651712188;5827.329857066322;5845.473907348189;5804.100364443518;5826.970865145647;5827.213326200908;5830.329512106162;5864.765409383747;5555.361133469541;5650.971745141413;5661.716914154194;5686.175504551899;5715.31416230055;5835.387284335254;5864;5912.201852749996;5927.614705044159;5979.342272350125;5775.653460792366;5783.450572195635;5793.623414478046;5803.303603567599;5806.477417032487 22:20:20 INFO - PROCESS | 26117 | |1;ext.html;616.109721452501;658.4567731621412;662.6057495790003;664.4983037806455;676.7157793726632;44283.778581107086;45654.086918261615;45739.77130114349;45774.771126144406;49166.50833491664;45569;46625.53374466254;46670.766646166725;46840.53159468404;46944.88748006339;8747.650093996248;8883.555822208864;8891;8895.77760555988;8903.688370907019;8985.77535561613;9057.184853363196;9059.22996545291;9059.637614495445;9065.32010099242;8980;9032.14194651507;9034.503102329369;9092.727218183465;9109.590068446903;9045.502497362642;9080.955690095625;9082.40964337316;9083;9083.364164508486;8837.29301655866;8839.60221790021;8874.46753194804;8885.644574217024;8896.110388961117;42419.787901060605;42713.78643106796;42744.3588346175;42852.78573607143;42945.35581966273;3438.2607739336163;3469.60099588548;3543.273628906094;3550.928981420383;3620.855165793365;30250.697493025007;32310.51534226965;33011.174720631934;33069.3386132276;33509.32981340384;4028.5769994150487;4105.7331273467125;4127.814248358831;4138.503379594438;4162.83348666053;296044;297868.5106574475;298140;299190;300830;35741;36793.81603092021;60834;60858.39141608616;61188.694056529435;38078.61921380806;38122.23755524874;38194.42708359377;38339.424908626395;38362.23275534529;14972;15241.390344386322;15245.23773811305;15281.9235903822;15282.159481228638;12638.304893230777;12656.557020504239;12698.936505317415;12742.68143296425;12758;8661.090585488433;8667.349948753868;8713.738587842376;8780.692675756445;8781.385303028726;3292.9506057409158;3305.272839975202;3309;3337.4660054391666;3344.8662053517587;1064.4039337970762;1068.551208492435;1076.4241130995038;1088.918331125153;1169.6724917023248;31108;31612.209694757355;31888.362232755215;31916;32261.838690806395;1163.6450882480756;1210.4613447015993;1215.3680086355046;1244.6017274472083;1263.4188273394159;16941.322347106223;17079.914600426917;17122.486325410257;17190.74213886793;17219.31122755076;450320;450649;454485;455040;456350;57751.133732994036;58140;58354.12468812886;58610.12084818731;58653.12020319613;1387.9791803122962;1422.8008078868966;1423.7721964485424;1427.0581416265215;1431.2628996067033;30192.24519387033;30648.540271896385;31004.68995310018;31277;31482.52776208313;52077.7396113017;52281;52307.21539176839;52646;52771.736141319045;371786;373465;375382;376079;379803;29016.12951611455;29288.121356359345;30313.393732125674;30405.695943040286;31333.373332533676;50175.74912125416;50417;51245.48754512407;51943;55588.444115558326;42130.157396852504;42472.57527424686;43271.78364108285;43631.56368436402;44137;41443.37834932418;41747.582524174366;41962.160756785306;42723.7863810679;43232.35151472667;42540.78729606456;43389.349159761994;43792;44220.55779442164;45876;35836.10409739777;36020.73927412528;36574.634253657125;37121.81439092787;37159.25681486409;28479.572806407505;28818.27954301159;28829.423411531232;29117.563236551046;29433 22:20:20 INFO - PROCESS | 26117 | |2;jquery.html;397.76333081816307;410.9732867363619;411.22689344033233;413.5988091551196;415.53875198529624;13435.932820335885;17822.554436139104;18695.532611684714;19482.318118865842;19582.943790901965;18390.448286551393;18770.343037993673;18917.337893173742;19638.312659056937;19661.901690491562;5596.4403559643915;5722.685252311132;5870.823875283749;5871.970640146793;5895.941040589603;5740.913886291688;5785.334686511042;5799.739011744482;5810.389909059551;5854.209681692981;5538.529225015878;5777.682227477487;5818.44724751148;5820;5826.533877289807;5567.749451274703;5748.281464816898;5771.394003629622;5773.2494775679315;5806.158107074471;5152.227165925141;5168.9224661630105;5174.689518628857;5174.793008279702;5180.740962951875;22366.217182398537;22852.77147228523;22921.77078229213;23556.64665030026;23717.169899053624;2203.889805509718;2350.9647355289685;2592.066855931863;2610.8172427930053;2722.3194201449637;9970.15253703436;10524.31591946521;10610.257281990263;10699.251052426333;10911.618093366695;1969.4485544047689;1979.6436641404541;2059.794020597943;2085.4786303424144;2096.8322534197227;69553.30446695519;71682.64158679225;78575.60712196403;78835.21164788336;79271.20728792695;10986.505607247693;11185.328880267047;11256.324620522795;11359.943200283946;11481.253718508271;15843.841561584237;15908.363665453484;15914.283857226452;15920.283587238602;15994.280257388446;8153.510789352657;8431;8469.153084691481;8479.321654267644;8618.827623447622;7963.203679631991;7971.322437592754;7982.880256796153;8190.467619604826;8204.630791614392;4763.404574428197;4887.193613053806;4938.111139994764;4979.278004689299;4985.9501404985485;1986.5629561496448;2010.9195632174549;2018.5054661608;2052.2406709517572;2086.123827992247;1005.7133716890651;1007.2747621712356;1049.223574554824;1053.6573783223605;1089.5750657243682;19486.902565487082;19615.901920490593;19705.60588788216;19857.60284794296;20225.49436264076;992.5235886774244;1013.7921726046143;1038.9532471038822;1092.093562343253;1111.5109351885144;9022;10836.566537338576;11195.216334856645;11238.157138214667;11254.662360129209;341479;348583;348620;471190;506180;90491.64262536068;96327;103837.48081259546;105188;105305;1103.4593049405732;1129.401417248843;1145.5818626201544;1146.7190538318332;1155.5204590095016;18010;18050.27798888082;18114.27542898269;18126.522491950454;18217.362392316736;41758.58241417547;42598;42890;43687.126257475305;44087;56782.432175679365;57247.42752572421;60804.39195608164;61863.690681548105;62387.6880615594;7737.14891361961;8594.828103438022;8637.481751094954;8638.438501497383;8676.219140277406;9491.05089491073;13204.867951320364;13900.443982240597;13928.164310141425;13998.650033749238;12099.455524501242;12182.756344872876;12183.147179697336;12269.57056503018;12308.630741077783;11553.13351498624;11587.768244635228;11608.535658573563;11719.355435451133;11731.706707332043;11778.76442471163;12462.626121216377;12499;12547.49810007626;12748.171368860994;8645.654173832976;8715.869261960948;8758.255548278468;8768.736937891874;8855.424397414148;10731.581326273661;11946.641600751991;11990.760184796081;12076.094292928241;12224.572139975058 22:20:20 INFO - PROCESS | 26117 | |3;mootools.html;1514.6062023873792;1567.5062355358061;1706.9061201633913;1787.982120178798;1844.8247416495428;7467.25327467253;8177.672893084277;8233.79415514611;8733.91266087339;8739.825203495935;8290.792730181749;8464.492130472176;8589.871151932726;8589.91410085899;8601.311895048397;2348.283773449097;2373.311739595515;2379.3456799380137;2390.103711108334;2394.580948334043;2365.113082594027;2368.3605426534828;2394.832361734679;2423.0550085466684;2430.3438071720625;2379.9048038078454;2389.0921449849034;2398.8560686358874;2400.95198096038;2407.939801504968;2244.461329280969;2265.762094980028;2267.682524446579;2270.8410411271216;2356;2282.315305408371;2299.977000229993;2325.302409277227;2327.5810354136247;2330.3242059802637;8601.784955376103;8621.224089831883;8637.870431943526;8673.653053877837;8701.651933922698;5141.897162056738;5230.712310822903;5242;5247.973760131213;5260.053190425722;3162.2094476380903;3206.1022913584234;3259.6740325967453;3385.4752513360468;3388.6272510023878;1092.6503518873963;1125.4035361258545;1144.9084073274119;1148.8908553687386;1161.2742036227357;11768.058555315554;11778.116641251854;11799.882001179963;11824.763504729943;11850.288982660983;4479.596836284685;4545.318202269686;4640.6751527393435;4642.651801114863;4643.885467487778;9879.950600247097;9936;9936.602535898628;9946.502674866228;9951.950240248752;5147.408048074444;5181.481851814789;5198.610104242197;5205.167173252262;5213.113770658922;4285.292926667065;4482.708623939434;4569.086182763461;4607.6083532900075;4632.5830675239395;4008.959910400917;4037.7375470594316;4103.179364127186;4149.605787450248;4162.687798415131;1321.5903070048316;1321.6563693439775;1330.8203392542077;1334.793107068406;1342.6240652617282;737.8634952533798;753.1038064702985;806.1696452653777;810.4003037752021;899.3614533681028;5339.839804805862;5405.675659460445;5553.805616803474;5555.194496797942;5626.2967129108865;936.101342711005;941.4351389166445;951.2104952889224;956.6938579654444;1005.5324274212526;3853.094522787143;3855.094052897567;3873;3875.4380614810693;3946;10205.183585313158;10721.195910306758;10791.6222932197;10792.082672972729;10807.297525660806;10534.104601108838;10582.894171058344;10619.787604248026;10651.041406273473;10671.893281067245;7988.800279993046;8029.759107226676;8079.757607271791;8083.110857805636;8242.2581967622;5532.253145825262;5576.191452239484;5581.49766521015;5596.720163991703;5674.546036317167;9749.512524373895;9787.265955053257;10082.193424525874;10088.092071713581;10260.435676037889;9726.270529710158;9819.11627953459;9855.950720246354;10162.949185254321;10175.338602990782;6535.5425120241125;6902;7087.82280442993;7102.751403700846;7230;8203.097659257357;8205.876911846208;8213.38399620019;8271.503709777433;8274.255317021498;7195.676194571152;7208.783736487913;7512.13610434807;7569.280918312751;7616.3906887447765;7351.154617219088;7371.926280737124;7445.553266804009;7515.323620874147;7583.810404739705;6616.4045235929;6617.966910165418;6646.0695502628705;6683.264840867403;6687.364700353459;4894.437139728977;4928.2853986171085;4937.333459982857;4948.6535942485125;4978.850634480971;4999.900001999867;5011.724355160502;5030.974845125751;5067.6199285053035;5100.209467532539 22:20:20 INFO - PROCESS | 26117 | |4;prototype.html;348.41118509718575;350.29590523048665;353.1401038471324;354.1253104831068;356.34610489751304;5604.439556044404;5650.660960342382;5653.830385088444;5678.296511046685;5721.368170641499;5532.2531458253125;5537;5547.77808887645;5555.833325000246;5556.166465066529;3946.881593552198;4035.7690904274186;4283.293256612671;4285.642896406979;4316.892077698067;4299.699021068526;4330.84842030529;4333.349997500369;4362.432883725127;4366.934495982563;4281.550437204095;4292.935605965913;4295.334223195409;4315.82736690532;4336.8265269389185;4177.4778152730905;4321.719088259253;4332.935005974913;4335.593745471262;4371.606555410012;3747.4940882980723;3805.7906815125157;3823.6558709715982;3838.405047217686;3844.4233364995193;5348.411674715817;5364.651297665679;5365.275687782178;5378.596605254622;5382.4886635769535;4672.2057250267535;4967.205247160473;4971.701697898137;5020.0963826511215;5046.268291097807;5787.50806181475;5806.941930580682;5859.882802343929;5873.647581145102;5877.706114694291;4142.2958097123565;4150.3566947123245;4183.707140500166;4196.328587426027;4205.66354691627;5894.616849904785;5990.371011043865;6087.6956152192215;6177.938220617782;6212.378762123797;4192.727472714264;4237.724547904377;4238.21593005295;4251.553586873396;4263.893402664896;4337.200061974246;4338.696291259645;4342.196693611629;4342.457192850894;4352.39066530686;4679.859604211879;4917.704937703749;4985.227289770091;4987.775550100254;4996.800767815698;5903.675297858659;5934.762609495659;5935.821925342247;5957.702114894238;5960.8807823844145;1442.8845692344587;1456.5557504961089;1459.3870574358796;1459.8248210214842;1466.1935935235579;935.7099299217265;963.6482683820495;980.7302991677345;1008.8045665885949;1018.2770233134557;5440.591955603267;5453.563714902798;5454.290942177491;5481.177823326453;5496.285482887199;5764.884702306014;5804.200697783701;5929.703514824328;5993.9810232260625;5995.280566332068;5427.48438898304;5603.159526071122;5612.747426365823;5642.520385767174;5645.576581756384;3117.968820311768;3133.937321253562;3147.7324427423923;3148.2286839724416;3166.477531207325;5945.970270148621;6113.083037544404;6113.868934247179;6132.877342453126;6142.232220972379;5875.588708790429;5886.6173698709445;5913.29040515141;5968.283805943314;6055.788047418408;6224.470919971853;6240.375962403723;6261.906071408933;6270.561060725706;6282.178168331224;5927.281088484291;5981.52147828164;6028.849278768066;6049.667268300285;6065.757369705162;5944.187022958039;5954.523638109027;5980.222571065734;6010.248718910137;6054.152418661303;6206.510437495059;6217.797834115344;6240.28236752761;6254.810816214289;6277.843053923689;4024.1750441158906;4242.893927651833;4246.278132717383;4308.052228509723;4327.6321512671775;6085.847853803691;6086.3305036446845;6150.1082343059115;6151.569390142647;6167.198264225622;5835.591508594358;5875.6474611523445;5879.470847623735;6017.699115044318;6045.455908968214;5586.497215250484;5924.289085309791;5948.702564871653;5953.821385358447;5958.285005799332;5734.881698068836;5761.654300741969;5782.277215348082;5785.710714464344;5813.476787089182;5711.714414279352;5893.675847828411;5919.348871624032;5944.464998150014;5962.731677074456;5776.826695198983;5845.240118784531;5851.1223316502865;5857.9121313179485;5862.853428664317 22:20:20 INFO - PROCESS | 26117 | |5;yui.html;679.7077256779586;717.3507975282371;743.8549482850844;753.9585322807243;785.4737325991587;12608.243505389682;12679.936600317009;12742.23546587203;13250.66873328166;13795.379207935643;13007.47970081198;13061.542846000391;13124;13144.73710525789;13269.800952985714;659.4328877165634;786.2805532937384;788.2944764435827;792.1721800718263;805.1586092533316;789.4592204340046;798.5208874675177;800.2917418085008;802.5184889066571;803.2770506544127;844.4511067805914;847.4915050969399;850.9106543812903;852.3011130872687;853.3471894001094;851.6337974670889;852.2244757270915;852.3309202276214;852.5396286005549;853.3301358433631;619.0003144920965;621.5835390288472;621.6705146272483;621.7854840080211;623.6227082614998;12623.684407889785;12677.746445071047;12701.872981270162;12722.236665800081;12741.490340386374;13316.73366532664;13501.594952151352;13817.930910345383;13835.169889806644;13835.377408016664;10468.52891619879;10675.199360048027;10702.518386672618;10839.970202830718;10843.945780271128;3752.9437058444146;3763.247350529905;3799.5820459749407;3817.9809100954317;3897.2595206910596;50274.497255027345;50305.74847125778;50372.49627503715;50655;50823;10147.644832430977;10196.490175491195;10335.483225838678;10399.584016639401;10431.530581123867;18239.361622343153;18801.623967520572;18807.811921880606;19572.608547828964;19655.312064077687;8289.8342033159;8352.206540378655;8356.665733370719;8491.660333586588;8659.52372619512;4564.1556312082375;4614.10025045113;4650.18621741194;4708.058388322349;4749.287606858929;3062.7549796016265;3162.2726772841916;3165.525171224289;3167.572377729023;3173.5715678383585;636.78667646339;642.6882961763541;647.1457675867904;663.7013343995221;665.2682049745241;658.4205898809017;664.85705573301;670.383247412372;674.3559900295206;686.8488932434956;8866.689665861664;9568.95215523918;9634.9036509634;9651.71044868655;9759.560819763128;581.7672930827619;634.4828964394004;637.4422380417135;677.9457942898741;699.9160100787938;4293.162833247487;4404.11917616478;4429.114177164515;4478.328250762412;4499.820007199741;62327.376726233066;63552;65011.67494162593;66095.66952165305;66229.66885165543;56919.71540142356;57065.42934570684;57334.42665573374;58970.11544826831;59693;52280.21579676308;52301.47698523042;52395;52852.73573632184;53038.73480632572;8349.791255218424;8495.320374369912;8549.829003420022;8724.78188045304;8917.866232006654;32488.51267230946;32593.348133036732;32756.83621581877;33444.99665010053;33533.66466335403;43328.56671433245;43600.12799744051;43666.56333436752;43710.781446092566;46808.53191468178;11612.767744645229;11673.824892626448;11682.88317116818;11710.531578737096;11855.407229638657;13650.453981840616;13895.494633144272;14170.291485425894;14197.219152946687;14747.336369863597;12747.936260318638;12763.51684961793;12845.486180552673;13060.281684507443;13478.86521134776;12827.679308017374;13336.19982801035;13338.533151339656;13442.46230150783;13688.337794938712;13470.393832277767;13534.323283835965;13612.727745444838;13827.360181316208;13840.723185536031;10341;10467.476626168813;11092.168087393316;11193.944030279796;11234.88765112371;10119.59521619127;10326.277160598685;10367.32612380193;10791;11136.777264454828 22:20:20 INFO - PROCESS | 26117 | __end_tp_report 22:20:20 INFO - PROCESS | 26117 | __start_cc_report 22:20:20 INFO - PROCESS | 26117 | _x_x_mozilla_cycle_collect,334 22:20:20 INFO - PROCESS | 26117 | __end_cc_report 22:20:20 INFO - PROCESS | 26117 | __startTimestamp1462771220128__endTimestamp 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | ------- Summary: start ------- 22:20:20 INFO - PROCESS | 26117 | Number of tests: 6 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#0] dojo.html Cycles:165 Average:5485.65 Median:5762.00 stddev:753.39 (13.1%) stddev-sans-first:733.47 22:20:20 INFO - PROCESS | 26117 | Values: 3170.4 3175.4 3200.2 3218.4 3221.1 5423.8 5452.8 5488.0 5516.8 5585.6 5462.3 5506.7 5537.9 5538.4 5622.7 4946.7 4980.8 5110.2 5136.3 5182.7 637.4 5010.0 5031.5 5061.5 5083.8 5025.9 5060.2 5064.2 5066.8 5118.2 4943.8 5084.1 5102.9 5107.3 5109.5 4958.0 4962.5 4999.4 5010.6 5055.0 5356.9 5442.1 5444.8 5465.5 5528.5 5848.5 5876.2 5880.7 5934.8 5943.0 5966.7 5992.5 5997.4 6025.6 6089.1 5589.0 5606.9 5616.0 5620.4 5623.2 5968.1 6037.5 6042.4 6084.8 6115.8 5839.2 5843.1 5845.8 5865.1 5865.3 5963.6 5963.6 5971.3 5975.6 5977.0 5677.4 5788.4 5804.4 5844.9 5876.5 5858.6 5886.9 5943.1 5978.1 5979.1 4432.4 4435.4 4443.3 4450.9 4492.5 3889.9 3892.3 3900.4 3935.8 3942.5 5677.6 5691.3 5693.3 5695.5 5753.1 5799.9 5955.5 5990.5 5998.2 6004.2 5645.2 5693.1 5703.2 5720.8 5742.3 4948.5 4954.9 4954.9 4967.6 5032.6 5968.0 6096.3 6203.8 6205.2 6208.1 5965.4 6039.3 6041.5 6049.8 6076.6 6180.7 6186.2 6201.8 6205.3 6216.4 5760.7 5762.0 5792.9 5897.2 5915.2 5993.1 5994.5 6000.5 6000.8 6008.2 5758.9 5762.5 5813.8 5827.3 5845.5 5804.1 5827.0 5827.2 5830.3 5864.8 5555.4 5651.0 5661.7 5686.2 5715.3 5835.4 5864.0 5912.2 5927.6 5979.3 5775.7 5783.5 5793.6 5803.3 5806.5 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#1] ext.html Cycles:180 Average:54120.04 Median:22790.94 stddev:100568.65 (441.3%) stddev-sans-first:100770.54 22:20:20 INFO - PROCESS | 26117 | Values: 616.1 658.5 662.6 664.5 676.7 44283.8 45654.1 45739.8 45774.8 49166.5 45569.0 46625.5 46670.8 46840.5 46944.9 8747.7 8883.6 8891.0 8895.8 8903.7 8985.8 9057.2 9059.2 9059.6 9065.3 8980.0 9032.1 9034.5 9092.7 9109.6 9045.5 9081.0 9082.4 9083.0 9083.4 8837.3 8839.6 8874.5 8885.6 8896.1 42419.8 42713.8 42744.4 42852.8 42945.4 3438.3 3469.6 3543.3 3550.9 3620.9 30250.7 32310.5 33011.2 33069.3 33509.3 4028.6 4105.7 4127.8 4138.5 4162.8 296044.0 297868.5 298140.0 299190.0 300830.0 35741.0 36793.8 60834.0 60858.4 61188.7 38078.6 38122.2 38194.4 38339.4 38362.2 14972.0 15241.4 15245.2 15281.9 15282.2 12638.3 12656.6 12698.9 12742.7 12758.0 8661.1 8667.3 8713.7 8780.7 8781.4 3293.0 3305.3 3309.0 3337.5 3344.9 1064.4 1068.6 1076.4 1088.9 1169.7 31108.0 31612.2 31888.4 31916.0 32261.8 1163.6 1210.5 1215.4 1244.6 1263.4 16941.3 17079.9 17122.5 17190.7 17219.3 450320.0 450649.0 454485.0 455040.0 456350.0 57751.1 58140.0 58354.1 58610.1 58653.1 1388.0 1422.8 1423.8 1427.1 1431.3 30192.2 30648.5 31004.7 31277.0 31482.5 52077.7 52281.0 52307.2 52646.0 52771.7 371786.0 373465.0 375382.0 376079.0 379803.0 29016.1 29288.1 30313.4 30405.7 31333.4 50175.7 50417.0 51245.5 51943.0 55588.4 42130.2 42472.6 43271.8 43631.6 44137.0 41443.4 41747.6 41962.2 42723.8 43232.4 42540.8 43389.3 43792.0 44220.6 45876.0 35836.1 36020.7 36574.6 37121.8 37159.3 28479.6 28818.3 28829.4 29117.6 29433.0 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#2] jquery.html Cycles:180 Average:26836.87 Median:22609.49 stddev:68232.65 (301.8%) stddev-sans-first:68395.18 22:20:20 INFO - PROCESS | 26117 | Values: 397.8 411.0 411.2 413.6 415.5 13435.9 17822.6 18695.5 19482.3 19582.9 18390.4 18770.3 18917.3 19638.3 19661.9 5596.4 5722.7 5870.8 5872.0 5895.9 5740.9 5785.3 5799.7 5810.4 5854.2 5538.5 5777.7 5818.4 5820.0 5826.5 5567.7 5748.3 5771.4 5773.2 5806.2 5152.2 5168.9 5174.7 5174.8 5180.7 22366.2 22852.8 22921.8 23556.6 23717.2 2203.9 2351.0 2592.1 2610.8 2722.3 9970.2 10524.3 10610.3 10699.3 10911.6 1969.4 1979.6 2059.8 2085.5 2096.8 69553.3 71682.6 78575.6 78835.2 79271.2 10986.5 11185.3 11256.3 11359.9 11481.3 15843.8 15908.4 15914.3 15920.3 15994.3 8153.5 8431.0 8469.2 8479.3 8618.8 7963.2 7971.3 7982.9 8190.5 8204.6 4763.4 4887.2 4938.1 4979.3 4986.0 1986.6 2010.9 2018.5 2052.2 2086.1 1005.7 1007.3 1049.2 1053.7 1089.6 19486.9 19615.9 19705.6 19857.6 20225.5 992.5 1013.8 1039.0 1092.1 1111.5 9022.0 10836.6 11195.2 11238.2 11254.7 341479.0 348583.0 348620.0 471190.0 506180.0 90491.6 96327.0 103837.5 105188.0 105305.0 1103.5 1129.4 1145.6 1146.7 1155.5 18010.0 18050.3 18114.3 18126.5 18217.4 41758.6 42598.0 42890.0 43687.1 44087.0 56782.4 57247.4 60804.4 61863.7 62387.7 7737.1 8594.8 8637.5 8638.4 8676.2 9491.1 13204.9 13900.4 13928.2 13998.7 12099.5 12182.8 12183.1 12269.6 12308.6 11553.1 11587.8 11608.5 11719.4 11731.7 11778.8 12462.6 12499.0 12547.5 12748.2 8645.7 8715.9 8758.3 8768.7 8855.4 10731.6 11946.6 11990.8 12076.1 12224.6 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#3] mootools.html Cycles:180 Average:5630.64 Median:4989.38 stddev:3167.25 (63.5%) stddev-sans-first:3161.03 22:20:20 INFO - PROCESS | 26117 | Values: 1514.6 1567.5 1706.9 1788.0 1844.8 7467.3 8177.7 8233.8 8733.9 8739.8 8290.8 8464.5 8589.9 8589.9 8601.3 2348.3 2373.3 2379.3 2390.1 2394.6 2365.1 2368.4 2394.8 2423.1 2430.3 2379.9 2389.1 2398.9 2401.0 2407.9 2244.5 2265.8 2267.7 2270.8 2356.0 2282.3 2300.0 2325.3 2327.6 2330.3 8601.8 8621.2 8637.9 8673.7 8701.7 5141.9 5230.7 5242.0 5248.0 5260.1 3162.2 3206.1 3259.7 3385.5 3388.6 1092.7 1125.4 1144.9 1148.9 1161.3 11768.1 11778.1 11799.9 11824.8 11850.3 4479.6 4545.3 4640.7 4642.7 4643.9 9880.0 9936.0 9936.6 9946.5 9952.0 5147.4 5181.5 5198.6 5205.2 5213.1 4285.3 4482.7 4569.1 4607.6 4632.6 4009.0 4037.7 4103.2 4149.6 4162.7 1321.6 1321.7 1330.8 1334.8 1342.6 737.9 753.1 806.2 810.4 899.4 5339.8 5405.7 5553.8 5555.2 5626.3 936.1 941.4 951.2 956.7 1005.5 3853.1 3855.1 3873.0 3875.4 3946.0 10205.2 10721.2 10791.6 10792.1 10807.3 10534.1 10582.9 10619.8 10651.0 10671.9 7988.8 8029.8 8079.8 8083.1 8242.3 5532.3 5576.2 5581.5 5596.7 5674.5 9749.5 9787.3 10082.2 10088.1 10260.4 9726.3 9819.1 9856.0 10162.9 10175.3 6535.5 6902.0 7087.8 7102.8 7230.0 8203.1 8205.9 8213.4 8271.5 8274.3 7195.7 7208.8 7512.1 7569.3 7616.4 7351.2 7371.9 7445.6 7515.3 7583.8 6616.4 6618.0 6646.1 6683.3 6687.4 4894.4 4928.3 4937.3 4948.7 4978.9 4999.9 5011.7 5031.0 5067.6 5100.2 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#4] prototype.html Cycles:180 Average:4917.76 Median:5608.59 stddev:1462.93 (26.1%) stddev-sans-first:1426.26 22:20:20 INFO - PROCESS | 26117 | Values: 348.4 350.3 353.1 354.1 356.3 5604.4 5650.7 5653.8 5678.3 5721.4 5532.3 5537.0 5547.8 5555.8 5556.2 3946.9 4035.8 4283.3 4285.6 4316.9 4299.7 4330.8 4333.3 4362.4 4366.9 4281.6 4292.9 4295.3 4315.8 4336.8 4177.5 4321.7 4332.9 4335.6 4371.6 3747.5 3805.8 3823.7 3838.4 3844.4 5348.4 5364.7 5365.3 5378.6 5382.5 4672.2 4967.2 4971.7 5020.1 5046.3 5787.5 5806.9 5859.9 5873.6 5877.7 4142.3 4150.4 4183.7 4196.3 4205.7 5894.6 5990.4 6087.7 6177.9 6212.4 4192.7 4237.7 4238.2 4251.6 4263.9 4337.2 4338.7 4342.2 4342.5 4352.4 4679.9 4917.7 4985.2 4987.8 4996.8 5903.7 5934.8 5935.8 5957.7 5960.9 1442.9 1456.6 1459.4 1459.8 1466.2 935.7 963.6 980.7 1008.8 1018.3 5440.6 5453.6 5454.3 5481.2 5496.3 5764.9 5804.2 5929.7 5994.0 5995.3 5427.5 5603.2 5612.7 5642.5 5645.6 3118.0 3133.9 3147.7 3148.2 3166.5 5946.0 6113.1 6113.9 6132.9 6142.2 5875.6 5886.6 5913.3 5968.3 6055.8 6224.5 6240.4 6261.9 6270.6 6282.2 5927.3 5981.5 6028.8 6049.7 6065.8 5944.2 5954.5 5980.2 6010.2 6054.2 6206.5 6217.8 6240.3 6254.8 6277.8 4024.2 4242.9 4246.3 4308.1 4327.6 6085.8 6086.3 6150.1 6151.6 6167.2 5835.6 5875.6 5879.5 6017.7 6045.5 5586.5 5924.3 5948.7 5953.8 5958.3 5734.9 5761.7 5782.3 5785.7 5813.5 5711.7 5893.7 5919.3 5944.5 5962.7 5776.8 5845.2 5851.1 5857.9 5862.9 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - PROCESS | 26117 | [#5] yui.html Cycles:180 Average:14771.53 Median:4416.62 stddev:17308.74 (391.9%) stddev-sans-first:17324.94 22:20:20 INFO - PROCESS | 26117 | Values: 679.7 717.4 743.9 754.0 785.5 12608.2 12679.9 12742.2 13250.7 13795.4 13007.5 13061.5 13124.0 13144.7 13269.8 659.4 786.3 788.3 792.2 805.2 789.5 798.5 800.3 802.5 803.3 844.5 847.5 850.9 852.3 853.3 851.6 852.2 852.3 852.5 853.3 619.0 621.6 621.7 621.8 623.6 12623.7 12677.7 12701.9 12722.2 12741.5 13316.7 13501.6 13817.9 13835.2 13835.4 10468.5 10675.2 10702.5 10840.0 10843.9 3752.9 3763.2 3799.6 3818.0 3897.3 50274.5 50305.7 50372.5 50655.0 50823.0 10147.6 10196.5 10335.5 10399.6 10431.5 18239.4 18801.6 18807.8 19572.6 19655.3 8289.8 8352.2 8356.7 8491.7 8659.5 4564.2 4614.1 4650.2 4708.1 4749.3 3062.8 3162.3 3165.5 3167.6 3173.6 636.8 642.7 647.1 663.7 665.3 658.4 664.9 670.4 674.4 686.8 8866.7 9569.0 9634.9 9651.7 9759.6 581.8 634.5 637.4 677.9 699.9 4293.2 4404.1 4429.1 4478.3 4499.8 62327.4 63552.0 65011.7 66095.7 66229.7 56919.7 57065.4 57334.4 58970.1 59693.0 52280.2 52301.5 52395.0 52852.7 53038.7 8349.8 8495.3 8549.8 8724.8 8917.9 32488.5 32593.3 32756.8 33445.0 33533.7 43328.6 43600.1 43666.6 43710.8 46808.5 11612.8 11673.8 11682.9 11710.5 11855.4 13650.5 13895.5 14170.3 14197.2 14747.3 12747.9 12763.5 12845.5 13060.3 13478.9 12827.7 13336.2 13338.5 13442.5 13688.3 13470.4 13534.3 13612.7 13827.4 13840.7 10341.0 10467.5 11092.2 11193.9 11234.9 10119.6 10326.3 10367.3 10791.0 11136.8 22:20:20 INFO - PROCESS | 26117 | -------- Summary: end -------- 22:20:20 INFO - PROCESS | 26117 | 22:20:20 INFO - TEST-INFO | 26117: exit 0 22:20:20 INFO - TEST-OK | dromaeo_css | took 1109867ms 22:20:20 INFO - TEST-START | kraken 22:20:20 INFO - operating with platform_type : linux_ 22:20:20 INFO - Initialising browser for kraken test... 22:20:20 INFO - TEST-INFO | started process 26349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIfv_6t/profile http://localhost:39079/getInfo.html) 22:20:24 INFO - PROCESS | 26349 | 22:20:24 INFO - PROCESS | 26349 | (process:26415): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:20:25 INFO - PROCESS | 26349 | __metrics Screen width/height:1600/1200 22:20:25 INFO - PROCESS | 26349 | colorDepth:24 22:20:25 INFO - PROCESS | 26349 | Browser inner width/height: 1024/697 22:20:25 INFO - PROCESS | 26349 | __metrics 22:20:25 ERROR - PROCESS | 26349 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:20:26 INFO - TEST-INFO | 26349: exit 0 22:20:26 INFO - Browser initialized. 22:20:26 INFO - Running cycle 1/1 for kraken test... 22:20:26 INFO - TEST-INFO | started process 26466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIfv_6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:28 INFO - PROCESS | 26466 | 22:20:28 INFO - PROCESS | 26466 | (process:26529): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:20:38 INFO - PROCESS | 26466 | RSS: Main: 163233792 22:20:38 INFO - PROCESS | 26466 | 22:20:41 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39079/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:41 INFO - PROCESS | 26466 | RSS: Main: 167796736 22:20:41 INFO - PROCESS | 26466 | 22:20:44 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39079/tests/kraken/driver.html?testName=audio-dft) 22:20:45 INFO - PROCESS | 26466 | RSS: Main: 168259584 22:20:45 INFO - PROCESS | 26466 | 22:20:48 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39079/tests/kraken/driver.html?testName=audio-fft) 22:20:48 INFO - PROCESS | 26466 | RSS: Main: 162492416 22:20:48 INFO - PROCESS | 26466 | 22:20:50 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39079/tests/kraken/driver.html?testName=audio-oscillator) 22:20:51 INFO - PROCESS | 26466 | RSS: Main: 163319808 22:20:51 INFO - PROCESS | 26466 | 22:20:53 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39079/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:54 INFO - PROCESS | 26466 | RSS: Main: 163500032 22:20:54 INFO - PROCESS | 26466 | 22:20:57 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39079/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:58 INFO - PROCESS | 26466 | RSS: Main: 164065280 22:20:58 INFO - PROCESS | 26466 | 22:21:01 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39079/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:02 INFO - PROCESS | 26466 | RSS: Main: 163696640 22:21:02 INFO - PROCESS | 26466 | 22:21:05 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39079/tests/kraken/driver.html?testName=json-parse-financial) 22:21:05 INFO - PROCESS | 26466 | RSS: Main: 163872768 22:21:05 INFO - PROCESS | 26466 | 22:21:07 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39079/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:08 INFO - PROCESS | 26466 | RSS: Main: 164073472 22:21:08 INFO - PROCESS | 26466 | 22:21:09 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:10 INFO - PROCESS | 26466 | RSS: Main: 164491264 22:21:10 INFO - PROCESS | 26466 | 22:21:12 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:13 INFO - PROCESS | 26466 | RSS: Main: 164761600 22:21:13 INFO - PROCESS | 26466 | 22:21:15 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:16 INFO - PROCESS | 26466 | RSS: Main: 164773888 22:21:16 INFO - PROCESS | 26466 | 22:21:19 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:20 INFO - PROCESS | 26466 | RSS: Main: 164937728 22:21:20 INFO - PROCESS | 26466 | 22:21:21 INFO - PROCESS | 26466 | Cycle 1(1): loaded http://localhost:39079/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39079/tests/kraken/driver.html?testName=ai-astar) 22:21:22 INFO - PROCESS | 26466 | RSS: Main: 165986304 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | __start_tp_report 22:21:22 INFO - PROCESS | 26466 | _x_x_mozilla_page_load 22:21:22 INFO - PROCESS | 26466 | _x_x_mozilla_page_load_details 22:21:22 INFO - PROCESS | 26466 | |i|pagename|runs| 22:21:22 INFO - PROCESS | 26466 | |0;ai-astar;110;112;106;112;112;112;104;115;111;111 22:21:22 INFO - PROCESS | 26466 | |1;audio-beat-detection;154;126;158;213;126;152;155;127;189;129 22:21:22 INFO - PROCESS | 26466 | |2;audio-dft;162;163;162;163;164;165;170;162;163;161 22:21:22 INFO - PROCESS | 26466 | |3;audio-fft;76;76;76;164;76;76;74;75;178;76 22:21:22 INFO - PROCESS | 26466 | |4;audio-oscillator;92;92;175;92;92;92;145;93;93;138 22:21:22 INFO - PROCESS | 26466 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 22:21:22 INFO - PROCESS | 26466 | |6;imaging-darkroom;145;145;146;147;146;146;148;145;145;146 22:21:22 INFO - PROCESS | 26466 | |7;imaging-desaturate;95;96;95;96;95;95;96;95;94;95 22:21:22 INFO - PROCESS | 26466 | |8;json-parse-financial;57;58;58;57;58;59;59;115;56;57 22:21:22 INFO - PROCESS | 26466 | |9;json-stringify-tinderbox;60;62;60;60;61;60;60;60;60;61 22:21:22 INFO - PROCESS | 26466 | |10;stanford-crypto-aes;80;79;79;80;83;80;79;83;81;81 22:21:22 INFO - PROCESS | 26466 | |11;stanford-crypto-ccm;134;125;129;127;137;134;127;138;196;126 22:21:22 INFO - PROCESS | 26466 | |12;stanford-crypto-pbkdf2;174;171;169;169;169;171;171;171;172;169 22:21:22 INFO - PROCESS | 26466 | |13;stanford-crypto-sha256-iterative;65;66;68;65;63;64;67;65;68;63 22:21:22 INFO - PROCESS | 26466 | __end_tp_report 22:21:22 INFO - PROCESS | 26466 | __start_cc_report 22:21:22 INFO - PROCESS | 26466 | _x_x_mozilla_cycle_collect,828 22:21:22 INFO - PROCESS | 26466 | __end_cc_report 22:21:22 INFO - PROCESS | 26466 | __startTimestamp1462771282064__endTimestamp 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | ------- Summary: start ------- 22:21:22 INFO - PROCESS | 26466 | Number of tests: 14 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.21 (2.9%) stddev-sans-first:3.40 22:21:22 INFO - PROCESS | 26466 | Values: 110.0 112.0 106.0 112.0 112.0 112.0 104.0 115.0 111.0 111.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#1] audio-beat-detection Cycles:10 Average:152.90 Median:154.50 stddev:29.13 (18.9%) stddev-sans-first:30.89 22:21:22 INFO - PROCESS | 26466 | Values: 154.0 126.0 158.0 213.0 126.0 152.0 155.0 127.0 189.0 129.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:2.55 (1.6%) stddev-sans-first:2.65 22:21:22 INFO - PROCESS | 26466 | Values: 162.0 163.0 162.0 163.0 164.0 165.0 170.0 162.0 163.0 161.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#3] audio-fft Cycles:10 Average:94.70 Median:76.00 stddev:40.35 (53.1%) stddev-sans-first:42.23 22:21:22 INFO - PROCESS | 26466 | Values: 76.0 76.0 76.0 164.0 76.0 76.0 74.0 75.0 178.0 76.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:92.00 stddev:30.61 (33.3%) stddev-sans-first:31.73 22:21:22 INFO - PROCESS | 26466 | Values: 92.0 92.0 175.0 92.0 92.0 92.0 145.0 93.0 93.0 138.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 22:21:22 INFO - PROCESS | 26466 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 22:21:22 INFO - PROCESS | 26466 | Values: 145.0 145.0 146.0 147.0 146.0 146.0 148.0 145.0 145.0 146.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:21:22 INFO - PROCESS | 26466 | Values: 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 94.0 95.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:58.00 stddev:18.15 (31.3%) stddev-sans-first:19.11 22:21:22 INFO - PROCESS | 26466 | Values: 57.0 58.0 58.0 57.0 58.0 59.0 59.0 115.0 56.0 57.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 22:21:22 INFO - PROCESS | 26466 | Values: 60.0 62.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.50 stddev:1.51 (1.9%) stddev-sans-first:1.59 22:21:22 INFO - PROCESS | 26466 | Values: 80.0 79.0 79.0 80.0 83.0 80.0 79.0 83.0 81.0 81.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:134.00 stddev:21.16 (15.8%) stddev-sans-first:22.41 22:21:22 INFO - PROCESS | 26466 | Values: 134.0 125.0 129.0 127.0 137.0 134.0 127.0 138.0 196.0 126.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:1.65 (1.0%) stddev-sans-first:1.20 22:21:22 INFO - PROCESS | 26466 | Values: 174.0 171.0 169.0 169.0 169.0 171.0 171.0 171.0 172.0 169.0 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - PROCESS | 26466 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.84 (2.8%) stddev-sans-first:1.94 22:21:22 INFO - PROCESS | 26466 | Values: 65.0 66.0 68.0 65.0 63.0 64.0 67.0 65.0 68.0 63.0 22:21:22 INFO - PROCESS | 26466 | -------- Summary: end -------- 22:21:22 INFO - PROCESS | 26466 | 22:21:22 INFO - TEST-INFO | 26466: exit 0 22:21:23 INFO - TEST-OK | kraken | took 62181ms 22:21:23 INFO - SUITE-END | took 1172s 22:21:23 INFO - Completed test suite (00:19:32) 22:21:23 INFO - javascript benchmark 22:21:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.365926814637, 3175.396674631821, 3200.199950012496, 3218.4045951498974, 3221.0658908916403, 5423.783048678053, 5452.781888724451, 5487.972560137194, 5516.779328826847, 5585.636933599313, 5462.3445186577665, 5506.724663766806, 5537.944620553793, 5538.418466061066, 5622.690752008638, 4946.653734238605, 4980.750962451881, 5110.207917772751, 5136.332276804011, 5182.740862956837, 637.3787511463875, 5010.048090862742, 5031.496850314976, 5061.54446099851, 5083.822066227683, 5025.924611130836, 5060.2409638554145, 5064.24036394542, 5066.797328106872, 5118.206677964903, 4943.8022479101155, 5084.084864724348, 5102.897942041139, 5107.3360463139925, 5109.540141387293, 4957.950420495785, 4962.503749625044, 4999.400071991348, 5010.624203184776, 5055.039542486916, 5356.946430535722, 5442.074847275933, 5444.8094316698725, 5465.480779325958, 5528.5024347808485, 5848.502877255439, 5876.206712093898, 5880.6765627890445, 5934.762609495659, 5943.0, 5966.67183304918, 5992.520598352121, 5997.430244126801, 6025.6083354581815, 6089.117078023662, 5588.972055139699, 5606.943930560724, 5616.0, 5620.353659329188, 5623.184638227475, 5968.104784282393, 6037.486813620803, 6042.3655516170165, 6084.817455476343, 6115.816525504242, 5839.211706419579, 5843.1235314703135, 5845.766169353178, 5865.120231965239, 5865.296164460292, 5963.5825492216, 5963.642181469126, 5971.28344598651, 5975.611585246945, 5977.0, 5677.432256774372, 5788.392218816964, 5804.361520232771, 5844.883102338014, 5876.471117599436, 5858.619189752652, 5886.941130588725, 5943.138244954457, 5978.073398623221, 5979.103134529855, 4432.357308190294, 4435.423394958635, 4443.266860967904, 4450.888727781831, 4492.460904691458, 3889.8638547651262, 3892.338302488584, 3900.414937759302, 3935.763854168759, 3942.4677668514955, 5677.574181936371, 5691.26013618227, 5693.3168019837885, 5695.45893140151, 5753.108268218432, 5799.9130013049835, 5955.493783028404, 5990.490808281258, 5998.220231369894, 6004.189434426296, 5645.1532270159805, 5693.117566777156, 5703.201551782754, 5720.8283751487525, 5742.339630942495, 4948.455669876311, 4954.851354459372, 4954.925676114861, 4967.5777558907175, 5032.622553308516, 5968.015277479254, 6096.254562767882, 6203.7828675996125, 6205.193324867829, 6208.099825525273, 5965.403459654087, 6039.335673075871, 6041.546883983631, 6049.78825741097, 6076.574639775174, 6180.6600636965395, 6186.226721659793, 6201.844953876189, 6205.317415084427, 6216.44052035319, 5760.683162426107, 5762.0, 5792.855178620568, 5897.174395584708, 5915.201447804491, 5993.130996005642, 5994.520438365008, 6000.51995840323, 6000.759969601167, 6008.24896887889, 5758.884822303614, 5762.538996880324, 5813.79651712188, 5827.329857066322, 5845.473907348189, 5804.100364443518, 5826.970865145647, 5827.213326200908, 5830.329512106162, 5864.765409383747, 5555.361133469541, 5650.971745141413, 5661.716914154194, 5686.175504551899, 5715.31416230055, 5835.387284335254, 5864.0, 5912.201852749996, 5927.614705044159, 5979.342272350125, 5775.653460792366, 5783.450572195635, 5793.623414478046, 5803.303603567599, 5806.477417032487], "value": 5434.72036901601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.109721452501, 658.4567731621412, 662.6057495790003, 664.4983037806455, 676.7157793726632, 44283.778581107086, 45654.086918261615, 45739.77130114349, 45774.771126144406, 49166.50833491664, 45569.0, 46625.53374466254, 46670.766646166725, 46840.53159468404, 46944.88748006339, 8747.650093996248, 8883.555822208864, 8891.0, 8895.77760555988, 8903.688370907019, 8985.77535561613, 9057.184853363196, 9059.22996545291, 9059.637614495445, 9065.32010099242, 8980.0, 9032.14194651507, 9034.503102329369, 9092.727218183465, 9109.590068446903, 9045.502497362642, 9080.955690095625, 9082.40964337316, 9083.0, 9083.364164508486, 8837.29301655866, 8839.60221790021, 8874.46753194804, 8885.644574217024, 8896.110388961117, 42419.787901060605, 42713.78643106796, 42744.3588346175, 42852.78573607143, 42945.35581966273, 3438.2607739336163, 3469.60099588548, 3543.273628906094, 3550.928981420383, 3620.855165793365, 30250.697493025007, 32310.51534226965, 33011.174720631934, 33069.3386132276, 33509.32981340384, 4028.5769994150487, 4105.7331273467125, 4127.814248358831, 4138.503379594438, 4162.83348666053, 296044.0, 297868.5106574475, 298140.0, 299190.0, 300830.0, 35741.0, 36793.81603092021, 60834.0, 60858.39141608616, 61188.694056529435, 38078.61921380806, 38122.23755524874, 38194.42708359377, 38339.424908626395, 38362.23275534529, 14972.0, 15241.390344386322, 15245.23773811305, 15281.9235903822, 15282.159481228638, 12638.304893230777, 12656.557020504239, 12698.936505317415, 12742.68143296425, 12758.0, 8661.090585488433, 8667.349948753868, 8713.738587842376, 8780.692675756445, 8781.385303028726, 3292.9506057409158, 3305.272839975202, 3309.0, 3337.4660054391666, 3344.8662053517587, 1064.4039337970762, 1068.551208492435, 1076.4241130995038, 1088.918331125153, 1169.6724917023248, 31108.0, 31612.209694757355, 31888.362232755215, 31916.0, 32261.838690806395, 1163.6450882480756, 1210.4613447015993, 1215.3680086355046, 1244.6017274472083, 1263.4188273394159, 16941.322347106223, 17079.914600426917, 17122.486325410257, 17190.74213886793, 17219.31122755076, 450320.0, 450649.0, 454485.0, 455040.0, 456350.0, 57751.133732994036, 58140.0, 58354.12468812886, 58610.12084818731, 58653.12020319613, 1387.9791803122962, 1422.8008078868966, 1423.7721964485424, 1427.0581416265215, 1431.2628996067033, 30192.24519387033, 30648.540271896385, 31004.68995310018, 31277.0, 31482.52776208313, 52077.7396113017, 52281.0, 52307.21539176839, 52646.0, 52771.736141319045, 371786.0, 373465.0, 375382.0, 376079.0, 379803.0, 29016.12951611455, 29288.121356359345, 30313.393732125674, 30405.695943040286, 31333.373332533676, 50175.74912125416, 50417.0, 51245.48754512407, 51943.0, 55588.444115558326, 42130.157396852504, 42472.57527424686, 43271.78364108285, 43631.56368436402, 44137.0, 41443.37834932418, 41747.582524174366, 41962.160756785306, 42723.7863810679, 43232.35151472667, 42540.78729606456, 43389.349159761994, 43792.0, 44220.55779442164, 45876.0, 35836.10409739777, 36020.73927412528, 36574.634253657125, 37121.81439092787, 37159.25681486409, 28479.572806407505, 28818.27954301159, 28829.423411531232, 29117.563236551046, 29433.0], "value": 19043.621774753137, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.76333081816307, 410.9732867363619, 411.22689344033233, 413.5988091551196, 415.53875198529624, 13435.932820335885, 17822.554436139104, 18695.532611684714, 19482.318118865842, 19582.943790901965, 18390.448286551393, 18770.343037993673, 18917.337893173742, 19638.312659056937, 19661.901690491562, 5596.4403559643915, 5722.685252311132, 5870.823875283749, 5871.970640146793, 5895.941040589603, 5740.913886291688, 5785.334686511042, 5799.739011744482, 5810.389909059551, 5854.209681692981, 5538.529225015878, 5777.682227477487, 5818.44724751148, 5820.0, 5826.533877289807, 5567.749451274703, 5748.281464816898, 5771.394003629622, 5773.2494775679315, 5806.158107074471, 5152.227165925141, 5168.9224661630105, 5174.689518628857, 5174.793008279702, 5180.740962951875, 22366.217182398537, 22852.77147228523, 22921.77078229213, 23556.64665030026, 23717.169899053624, 2203.889805509718, 2350.9647355289685, 2592.066855931863, 2610.8172427930053, 2722.3194201449637, 9970.15253703436, 10524.31591946521, 10610.257281990263, 10699.251052426333, 10911.618093366695, 1969.4485544047689, 1979.6436641404541, 2059.794020597943, 2085.4786303424144, 2096.8322534197227, 69553.30446695519, 71682.64158679225, 78575.60712196403, 78835.21164788336, 79271.20728792695, 10986.505607247693, 11185.328880267047, 11256.324620522795, 11359.943200283946, 11481.253718508271, 15843.841561584237, 15908.363665453484, 15914.283857226452, 15920.283587238602, 15994.280257388446, 8153.510789352657, 8431.0, 8469.153084691481, 8479.321654267644, 8618.827623447622, 7963.203679631991, 7971.322437592754, 7982.880256796153, 8190.467619604826, 8204.630791614392, 4763.404574428197, 4887.193613053806, 4938.111139994764, 4979.278004689299, 4985.9501404985485, 1986.5629561496448, 2010.9195632174549, 2018.5054661608, 2052.2406709517572, 2086.123827992247, 1005.7133716890651, 1007.2747621712356, 1049.223574554824, 1053.6573783223605, 1089.5750657243682, 19486.902565487082, 19615.901920490593, 19705.60588788216, 19857.60284794296, 20225.49436264076, 992.5235886774244, 1013.7921726046143, 1038.9532471038822, 1092.093562343253, 1111.5109351885144, 9022.0, 10836.566537338576, 11195.216334856645, 11238.157138214667, 11254.662360129209, 341479.0, 348583.0, 348620.0, 471190.0, 506180.0, 90491.64262536068, 96327.0, 103837.48081259546, 105188.0, 105305.0, 1103.4593049405732, 1129.401417248843, 1145.5818626201544, 1146.7190538318332, 1155.5204590095016, 18010.0, 18050.27798888082, 18114.27542898269, 18126.522491950454, 18217.362392316736, 41758.58241417547, 42598.0, 42890.0, 43687.126257475305, 44087.0, 56782.432175679365, 57247.42752572421, 60804.39195608164, 61863.690681548105, 62387.6880615594, 7737.14891361961, 8594.828103438022, 8637.481751094954, 8638.438501497383, 8676.219140277406, 9491.05089491073, 13204.867951320364, 13900.443982240597, 13928.164310141425, 13998.650033749238, 12099.455524501242, 12182.756344872876, 12183.147179697336, 12269.57056503018, 12308.630741077783, 11553.13351498624, 11587.768244635228, 11608.535658573563, 11719.355435451133, 11731.706707332043, 11778.76442471163, 12462.626121216377, 12499.0, 12547.49810007626, 12748.171368860994, 8645.654173832976, 8715.869261960948, 8758.255548278468, 8768.736937891874, 8855.424397414148, 10731.581326273661, 11946.641600751991, 11990.760184796081, 12076.094292928241, 12224.572139975058], "value": 9351.673585114166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.6062023873792, 1567.5062355358061, 1706.9061201633913, 1787.982120178798, 1844.8247416495428, 7467.25327467253, 8177.672893084277, 8233.79415514611, 8733.91266087339, 8739.825203495935, 8290.792730181749, 8464.492130472176, 8589.871151932726, 8589.91410085899, 8601.311895048397, 2348.283773449097, 2373.311739595515, 2379.3456799380137, 2390.103711108334, 2394.580948334043, 2365.113082594027, 2368.3605426534828, 2394.832361734679, 2423.0550085466684, 2430.3438071720625, 2379.9048038078454, 2389.0921449849034, 2398.8560686358874, 2400.95198096038, 2407.939801504968, 2244.461329280969, 2265.762094980028, 2267.682524446579, 2270.8410411271216, 2356.0, 2282.315305408371, 2299.977000229993, 2325.302409277227, 2327.5810354136247, 2330.3242059802637, 8601.784955376103, 8621.224089831883, 8637.870431943526, 8673.653053877837, 8701.651933922698, 5141.897162056738, 5230.712310822903, 5242.0, 5247.973760131213, 5260.053190425722, 3162.2094476380903, 3206.1022913584234, 3259.6740325967453, 3385.4752513360468, 3388.6272510023878, 1092.6503518873963, 1125.4035361258545, 1144.9084073274119, 1148.8908553687386, 1161.2742036227357, 11768.058555315554, 11778.116641251854, 11799.882001179963, 11824.763504729943, 11850.288982660983, 4479.596836284685, 4545.318202269686, 4640.6751527393435, 4642.651801114863, 4643.885467487778, 9879.950600247097, 9936.0, 9936.602535898628, 9946.502674866228, 9951.950240248752, 5147.408048074444, 5181.481851814789, 5198.610104242197, 5205.167173252262, 5213.113770658922, 4285.292926667065, 4482.708623939434, 4569.086182763461, 4607.6083532900075, 4632.5830675239395, 4008.959910400917, 4037.7375470594316, 4103.179364127186, 4149.605787450248, 4162.687798415131, 1321.5903070048316, 1321.6563693439775, 1330.8203392542077, 1334.793107068406, 1342.6240652617282, 737.8634952533798, 753.1038064702985, 806.1696452653777, 810.4003037752021, 899.3614533681028, 5339.839804805862, 5405.675659460445, 5553.805616803474, 5555.194496797942, 5626.2967129108865, 936.101342711005, 941.4351389166445, 951.2104952889224, 956.6938579654444, 1005.5324274212526, 3853.094522787143, 3855.094052897567, 3873.0, 3875.4380614810693, 3946.0, 10205.183585313158, 10721.195910306758, 10791.6222932197, 10792.082672972729, 10807.297525660806, 10534.104601108838, 10582.894171058344, 10619.787604248026, 10651.041406273473, 10671.893281067245, 7988.800279993046, 8029.759107226676, 8079.757607271791, 8083.110857805636, 8242.2581967622, 5532.253145825262, 5576.191452239484, 5581.49766521015, 5596.720163991703, 5674.546036317167, 9749.512524373895, 9787.265955053257, 10082.193424525874, 10088.092071713581, 10260.435676037889, 9726.270529710158, 9819.11627953459, 9855.950720246354, 10162.949185254321, 10175.338602990782, 6535.5425120241125, 6902.0, 7087.82280442993, 7102.751403700846, 7230.0, 8203.097659257357, 8205.876911846208, 8213.38399620019, 8271.503709777433, 8274.255317021498, 7195.676194571152, 7208.783736487913, 7512.13610434807, 7569.280918312751, 7616.3906887447765, 7351.154617219088, 7371.926280737124, 7445.553266804009, 7515.323620874147, 7583.810404739705, 6616.4045235929, 6617.966910165418, 6646.0695502628705, 6683.264840867403, 6687.364700353459, 4894.437139728977, 4928.2853986171085, 4937.333459982857, 4948.6535942485125, 4978.850634480971, 4999.900001999867, 5011.724355160502, 5030.974845125751, 5067.6199285053035, 5100.209467532539], "value": 4534.7327153926535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.41118509718575, 350.29590523048665, 353.1401038471324, 354.1253104831068, 356.34610489751304, 5604.439556044404, 5650.660960342382, 5653.830385088444, 5678.296511046685, 5721.368170641499, 5532.2531458253125, 5537.0, 5547.77808887645, 5555.833325000246, 5556.166465066529, 3946.881593552198, 4035.7690904274186, 4283.293256612671, 4285.642896406979, 4316.892077698067, 4299.699021068526, 4330.84842030529, 4333.349997500369, 4362.432883725127, 4366.934495982563, 4281.550437204095, 4292.935605965913, 4295.334223195409, 4315.82736690532, 4336.8265269389185, 4177.4778152730905, 4321.719088259253, 4332.935005974913, 4335.593745471262, 4371.606555410012, 3747.4940882980723, 3805.7906815125157, 3823.6558709715982, 3838.405047217686, 3844.4233364995193, 5348.411674715817, 5364.651297665679, 5365.275687782178, 5378.596605254622, 5382.4886635769535, 4672.2057250267535, 4967.205247160473, 4971.701697898137, 5020.0963826511215, 5046.268291097807, 5787.50806181475, 5806.941930580682, 5859.882802343929, 5873.647581145102, 5877.706114694291, 4142.2958097123565, 4150.3566947123245, 4183.707140500166, 4196.328587426027, 4205.66354691627, 5894.616849904785, 5990.371011043865, 6087.6956152192215, 6177.938220617782, 6212.378762123797, 4192.727472714264, 4237.724547904377, 4238.21593005295, 4251.553586873396, 4263.893402664896, 4337.200061974246, 4338.696291259645, 4342.196693611629, 4342.457192850894, 4352.39066530686, 4679.859604211879, 4917.704937703749, 4985.227289770091, 4987.775550100254, 4996.800767815698, 5903.675297858659, 5934.762609495659, 5935.821925342247, 5957.702114894238, 5960.8807823844145, 1442.8845692344587, 1456.5557504961089, 1459.3870574358796, 1459.8248210214842, 1466.1935935235579, 935.7099299217265, 963.6482683820495, 980.7302991677345, 1008.8045665885949, 1018.2770233134557, 5440.591955603267, 5453.563714902798, 5454.290942177491, 5481.177823326453, 5496.285482887199, 5764.884702306014, 5804.200697783701, 5929.703514824328, 5993.9810232260625, 5995.280566332068, 5427.48438898304, 5603.159526071122, 5612.747426365823, 5642.520385767174, 5645.576581756384, 3117.968820311768, 3133.937321253562, 3147.7324427423923, 3148.2286839724416, 3166.477531207325, 5945.970270148621, 6113.083037544404, 6113.868934247179, 6132.877342453126, 6142.232220972379, 5875.588708790429, 5886.6173698709445, 5913.29040515141, 5968.283805943314, 6055.788047418408, 6224.470919971853, 6240.375962403723, 6261.906071408933, 6270.561060725706, 6282.178168331224, 5927.281088484291, 5981.52147828164, 6028.849278768066, 6049.667268300285, 6065.757369705162, 5944.187022958039, 5954.523638109027, 5980.222571065734, 6010.248718910137, 6054.152418661303, 6206.510437495059, 6217.797834115344, 6240.28236752761, 6254.810816214289, 6277.843053923689, 4024.1750441158906, 4242.893927651833, 4246.278132717383, 4308.052228509723, 4327.6321512671775, 6085.847853803691, 6086.3305036446845, 6150.1082343059115, 6151.569390142647, 6167.198264225622, 5835.591508594358, 5875.6474611523445, 5879.470847623735, 6017.699115044318, 6045.455908968214, 5586.497215250484, 5924.289085309791, 5948.702564871653, 5953.821385358447, 5958.285005799332, 5734.881698068836, 5761.654300741969, 5782.277215348082, 5785.710714464344, 5813.476787089182, 5711.714414279352, 5893.675847828411, 5919.348871624032, 5944.464998150014, 5962.731677074456, 5776.826695198983, 5845.240118784531, 5851.1223316502865, 5857.9121313179485, 5862.853428664317], "value": 4450.771996465594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7077256779586, 717.3507975282371, 743.8549482850844, 753.9585322807243, 785.4737325991587, 12608.243505389682, 12679.936600317009, 12742.23546587203, 13250.66873328166, 13795.379207935643, 13007.47970081198, 13061.542846000391, 13124.0, 13144.73710525789, 13269.800952985714, 659.4328877165634, 786.2805532937384, 788.2944764435827, 792.1721800718263, 805.1586092533316, 789.4592204340046, 798.5208874675177, 800.2917418085008, 802.5184889066571, 803.2770506544127, 844.4511067805914, 847.4915050969399, 850.9106543812903, 852.3011130872687, 853.3471894001094, 851.6337974670889, 852.2244757270915, 852.3309202276214, 852.5396286005549, 853.3301358433631, 619.0003144920965, 621.5835390288472, 621.6705146272483, 621.7854840080211, 623.6227082614998, 12623.684407889785, 12677.746445071047, 12701.872981270162, 12722.236665800081, 12741.490340386374, 13316.73366532664, 13501.594952151352, 13817.930910345383, 13835.169889806644, 13835.377408016664, 10468.52891619879, 10675.199360048027, 10702.518386672618, 10839.970202830718, 10843.945780271128, 3752.9437058444146, 3763.247350529905, 3799.5820459749407, 3817.9809100954317, 3897.2595206910596, 50274.497255027345, 50305.74847125778, 50372.49627503715, 50655.0, 50823.0, 10147.644832430977, 10196.490175491195, 10335.483225838678, 10399.584016639401, 10431.530581123867, 18239.361622343153, 18801.623967520572, 18807.811921880606, 19572.608547828964, 19655.312064077687, 8289.8342033159, 8352.206540378655, 8356.665733370719, 8491.660333586588, 8659.52372619512, 4564.1556312082375, 4614.10025045113, 4650.18621741194, 4708.058388322349, 4749.287606858929, 3062.7549796016265, 3162.2726772841916, 3165.525171224289, 3167.572377729023, 3173.5715678383585, 636.78667646339, 642.6882961763541, 647.1457675867904, 663.7013343995221, 665.2682049745241, 658.4205898809017, 664.85705573301, 670.383247412372, 674.3559900295206, 686.8488932434956, 8866.689665861664, 9568.95215523918, 9634.9036509634, 9651.71044868655, 9759.560819763128, 581.7672930827619, 634.4828964394004, 637.4422380417135, 677.9457942898741, 699.9160100787938, 4293.162833247487, 4404.11917616478, 4429.114177164515, 4478.328250762412, 4499.820007199741, 62327.376726233066, 63552.0, 65011.67494162593, 66095.66952165305, 66229.66885165543, 56919.71540142356, 57065.42934570684, 57334.42665573374, 58970.11544826831, 59693.0, 52280.21579676308, 52301.47698523042, 52395.0, 52852.73573632184, 53038.73480632572, 8349.791255218424, 8495.320374369912, 8549.829003420022, 8724.78188045304, 8917.866232006654, 32488.51267230946, 32593.348133036732, 32756.83621581877, 33444.99665010053, 33533.66466335403, 43328.56671433245, 43600.12799744051, 43666.56333436752, 43710.781446092566, 46808.53191468178, 11612.767744645229, 11673.824892626448, 11682.88317116818, 11710.531578737096, 11855.407229638657, 13650.453981840616, 13895.494633144272, 14170.291485425894, 14197.219152946687, 14747.336369863597, 12747.936260318638, 12763.51684961793, 12845.486180552673, 13060.281684507443, 13478.86521134776, 12827.679308017374, 13336.19982801035, 13338.533151339656, 13442.46230150783, 13688.337794938712, 13470.393832277767, 13534.323283835965, 13612.727745444838, 13827.360181316208, 13840.723185536031, 10341.0, 10467.476626168813, 11092.168087393316, 11193.944030279796, 11234.88765112371, 10119.59521619127, 10326.277160598685, 10367.32612380193, 10791.0, 11136.777264454828], "value": 6640.082380839579, "unit": "score"}], "name": "dromaeo_css", "value": 7114.914893562672, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 106.0, 112.0, 112.0, 112.0, 104.0, 115.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 158.0, 213.0, 126.0, 152.0, 155.0, 127.0, 189.0, 129.0], "value": 152.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 163.0, 164.0, 165.0, 170.0, 162.0, 163.0, 161.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 164.0, 76.0, 76.0, 74.0, 75.0, 178.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 175.0, 92.0, 92.0, 92.0, 145.0, 93.0, 93.0, 138.0], "value": 110.4, "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": [145.0, 145.0, 146.0, 147.0, 146.0, 146.0, 148.0, 145.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 58.0, 59.0, 59.0, 115.0, 56.0, 57.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 80.0, 83.0, 80.0, 79.0, 83.0, 81.0, 81.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 129.0, 127.0, 137.0, 134.0, 127.0, 138.0, 196.0, 126.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 169.0, 169.0, 169.0, 171.0, 171.0, 171.0, 172.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 68.0, 65.0, 63.0, 64.0, 67.0, 65.0, 68.0, 63.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1567.7, "alertThreshold": 2.0}]} 22:21:23 INFO - Return code: 0 22:21:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:21:23 ERROR - # TBPL SUCCESS # 22:21:23 INFO - Running post-action listener: _resource_record_post_action 22:21:23 INFO - ##### 22:21:23 INFO - ##### Finished run-tests step (success) 22:21:23 INFO - ##### 22:21:23 INFO - Running post-run listener: _resource_record_post_run 22:21:24 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 537911296; Read time: 12; Write time: 453068 22:21:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 245100544; Read time: 0; Write time: 228056 22:21:24 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292810752; Read time: 12; Write time: 225012 22:21:24 INFO - Running post-run listener: _upload_blobber_files 22:21:24 INFO - Blob upload gear active. 22:21:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:24 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 22:21:24 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'] 22:21:24 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 22:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:21:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:25 INFO - (blobuploader) - INFO - Done attempting. 22:21:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:21:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:21:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:26 INFO - (blobuploader) - INFO - Done attempting. 22:21:26 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:26 INFO - Return code: 0 22:21:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e290cbafc134aacdf909e3b1ad2044c906c33ee738758bea41ef0ef4bd3fc251d335e8ab225a11c728930526df55f851e4c38c20be803eb3d854405ede8a68df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a9d8264009b319e3cf1b1d410ee5e95fecdf66f814e24b003299f0e8d09f77198784ac5a694db805ecbd9bc213871987db53cccea438283d11256801aa6a4a6a"} 22:21:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:26 INFO - Contents: 22:21:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e290cbafc134aacdf909e3b1ad2044c906c33ee738758bea41ef0ef4bd3fc251d335e8ab225a11c728930526df55f851e4c38c20be803eb3d854405ede8a68df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a9d8264009b319e3cf1b1d410ee5e95fecdf66f814e24b003299f0e8d09f77198784ac5a694db805ecbd9bc213871987db53cccea438283d11256801aa6a4a6a"} 22:21:26 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:26 INFO - Copying logs to upload dir... 22:21:26 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.391971 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-08 22:21:26.166518) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:26.170851) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e290cbafc134aacdf909e3b1ad2044c906c33ee738758bea41ef0ef4bd3fc251d335e8ab225a11c728930526df55f851e4c38c20be803eb3d854405ede8a68df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a9d8264009b319e3cf1b1d410ee5e95fecdf66f814e24b003299f0e8d09f77198784ac5a694db805ecbd9bc213871987db53cccea438283d11256801aa6a4a6a"} build_url:https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e290cbafc134aacdf909e3b1ad2044c906c33ee738758bea41ef0ef4bd3fc251d335e8ab225a11c728930526df55f851e4c38c20be803eb3d854405ede8a68df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a9d8264009b319e3cf1b1d410ee5e95fecdf66f814e24b003299f0e8d09f77198784ac5a694db805ecbd9bc213871987db53cccea438283d11256801aa6a4a6a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:26.197853) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:26.198177) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462765694.616158-491731983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:26.278245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 22:21:26.280599) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 22:21:26.280895) ========= ========= Total master_lag: 0.22 =========