builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-043 starttime: 1465347000.42 results: success (0) buildid: 20160607155856 builduid: 5599d117192b4d6bb2389d1dd8852e13 revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.418156) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.418641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.418945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.477893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.478271) ========= 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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 17:50:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-06-07 17:50:00 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.068064 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.555012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.555353) ========= 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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009727 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.602517) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:00.602919) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 17:50:00,946 truncating revision to first 12 chars 2016-06-07 17:50:00,946 Setting DEBUG logging. 2016-06-07 17:50:00,946 attempt 1/10 2016-06-07 17:50:00,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/22047a4eea78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 17:50:01,252 unpacking tar archive at: autoland-22047a4eea78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.594136 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:01.207426) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:01.207781) ========= script_repo_revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:01.208181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:01.208475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 17:50:01.217976) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-07 17:50:01.218372) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:50:01 INFO - MultiFileLogger online at 20160607 17:50:01 in /builds/slave/test 17:50:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 17:50:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:50:01 INFO - {'append_to_log': False, 17:50:01 INFO - 'base_work_dir': '/builds/slave/test', 17:50:01 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 17:50:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:50:01 INFO - 'branch': 'Autoland-Non-PGO', 17:50:01 INFO - 'buildbot_json_path': 'buildprops.json', 17:50:01 INFO - 'config_files': ('talos/linux_config.py',), 17:50:01 INFO - 'default_actions': ('clobber', 17:50:01 INFO - 'read-buildbot-config', 17:50:01 INFO - 'download-and-extract', 17:50:01 INFO - 'populate-webroot', 17:50:01 INFO - 'create-virtualenv', 17:50:01 INFO - 'install', 17:50:01 INFO - 'run-tests'), 17:50:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:50:01 INFO - 'download_minidump_stackwalk': True, 17:50:01 INFO - 'download_symbols': 'ondemand', 17:50:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:50:01 INFO - 'tooltool.py': '/tools/tooltool.py', 17:50:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:50:01 INFO - '/tools/misc-python/virtualenv.py')}, 17:50:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:50:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:50:01 INFO - 'installer_path': 'installer.exe', 17:50:01 INFO - 'log_level': 'info', 17:50:01 INFO - 'log_name': 'talos', 17:50:01 INFO - 'log_to_console': True, 17:50:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:50:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:50:01 INFO - 'opt_config_files': (), 17:50:01 INFO - 'pip_index': False, 17:50:01 INFO - 'sps_profile': False, 17:50:01 INFO - 'sps_profile_interval': 0, 17:50:01 INFO - 'suite': 'dromaeojs-e10s', 17:50:01 INFO - 'system_bits': '32', 17:50:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:50:01 INFO - 'title': 'talos-linux64-ix-043', 17:50:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:50:01 INFO - 'use_talos_json': True, 17:50:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:50:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:50:01 INFO - 'work_dir': 'build'} 17:50:01 INFO - ##### 17:50:01 INFO - ##### Running clobber step. 17:50:01 INFO - ##### 17:50:01 INFO - Running pre-action listener: _resource_record_pre_action 17:50:01 INFO - Running main action method: clobber 17:50:01 INFO - rmtree: /builds/slave/test/build 17:50:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:50:02 INFO - Running post-action listener: _resource_record_post_action 17:50:02 INFO - ##### 17:50:02 INFO - ##### Finished clobber step (success) 17:50:02 INFO - ##### 17:50:02 INFO - ##### 17:50:02 INFO - ##### Running read-buildbot-config step. 17:50:02 INFO - ##### 17:50:02 INFO - Running pre-action listener: _resource_record_pre_action 17:50:02 INFO - Running main action method: read_buildbot_config 17:50:02 INFO - Using buildbot properties: 17:50:02 INFO - { 17:50:02 INFO - "project": "", 17:50:02 INFO - "product": "firefox", 17:50:02 INFO - "script_repo_revision": "production", 17:50:02 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 17:50:02 INFO - "repository": "", 17:50:02 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 17:50:02 INFO - "buildid": "20160607155856", 17:50:02 INFO - "pgo_build": "False", 17:50:02 INFO - "basedir": "/builds/slave/test", 17:50:02 INFO - "buildnumber": 1, 17:50:02 INFO - "slavename": "talos-linux64-ix-043", 17:50:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:50:02 INFO - "platform": "ubuntu64_hw", 17:50:02 INFO - "scheduler": "tests-autoland-linux64-talos", 17:50:02 INFO - "branch": "autoland", 17:50:02 INFO - "repo_path": "integration/autoland", 17:50:02 INFO - "revision": "22047a4eea784c15026c77911c0bd6ea1b70fa68", 17:50:02 INFO - "stage_platform": "linux64", 17:50:02 INFO - "builduid": "5599d117192b4d6bb2389d1dd8852e13", 17:50:02 INFO - "slavebuilddir": "test" 17:50:02 INFO - } 17:50:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:50:02 INFO - Running post-action listener: _resource_record_post_action 17:50:02 INFO - ##### 17:50:02 INFO - ##### Finished read-buildbot-config step (success) 17:50:02 INFO - ##### 17:50:02 INFO - ##### 17:50:02 INFO - ##### Running download-and-extract step. 17:50:02 INFO - ##### 17:50:02 INFO - Running pre-action listener: _resource_record_pre_action 17:50:02 INFO - Running main action method: download_and_extract 17:50:02 INFO - mkdir: /builds/slave/test/build/tests 17:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:02 INFO - https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:50:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:50:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:50:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:03 INFO - Downloaded 1361 bytes. 17:50:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:50:03 INFO - Using the following test package requirements: 17:50:03 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:50:03 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:50:03 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:50:03 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'jsshell-linux-x86_64.zip'], 17:50:03 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:50:03 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:50:03 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:50:03 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:50:03 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:50:03 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:50:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:50:03 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:50:03 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')]} 17:50:03 INFO - https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:50:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:50:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:50:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:04 INFO - Downloaded 22849786 bytes. 17:50:04 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'] 17:50:04 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 17:50:05 INFO - Return code: 0 17:50:05 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 17:50: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')]} 17:50:05 INFO - https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:50:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:50:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:50:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:06 INFO - Downloaded 22849786 bytes. 17:50:06 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'] 17:50:06 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 17:50:08 INFO - Return code: 0 17:50:08 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')]} 17:50:08 INFO - https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:50:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:50:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:50:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:09 INFO - Downloaded 10834055 bytes. 17:50:09 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'] 17:50:09 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 17:50:09 INFO - Return code: 0 17:50:09 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')]} 17:50:09 INFO - https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:50:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:50:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:50:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZFknCZIVQoORbXP5t92MGg/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 17:50:11 INFO - Downloaded 55061897 bytes. 17:50:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:50:11 INFO - mkdir: /builds/slave/test/properties 17:50:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:50:11 INFO - Writing to file /builds/slave/test/properties/build_url 17:50:11 INFO - Contents: 17:50:11 INFO - build_url:https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:50:12 INFO - Running post-action listener: _resource_record_post_action 17:50:12 INFO - Running post-action listener: set_extra_try_arguments 17:50:12 INFO - ##### 17:50:12 INFO - ##### Finished download-and-extract step (success) 17:50:12 INFO - ##### 17:50:12 INFO - ##### 17:50:12 INFO - ##### Running populate-webroot step. 17:50:12 INFO - ##### 17:50:12 INFO - Running pre-action listener: _resource_record_pre_action 17:50:12 INFO - Running main action method: populate_webroot 17:50:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:50:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:50:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:50:12 INFO - '--tppagecycles', 17:50:12 INFO - '7'], 17:50:12 INFO - 'tests': ['tsvgm']}}, 17:50:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:50:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:50:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:50:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:50:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['damp', 'tps']}, 17:50:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'tests': ['damp', 'tps']}, 17:50:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['dromaeo_dom']}, 17:50:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:50:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'other-e10s': {'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['a11yr', 17:50:12 INFO - 'ts_paint', 17:50:12 INFO - 'tpaint', 17:50:12 INFO - 'sessionrestore', 17:50:12 INFO - 'sessionrestore_no_auto_restore', 17:50:12 INFO - 'tabpaint']}, 17:50:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['tsvgx', 17:50:12 INFO - 'tsvgr_opacity', 17:50:12 INFO - 'tart', 17:50:12 INFO - 'tscrollx', 17:50:12 INFO - 'cart']}, 17:50:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:50:12 INFO - 'tsvgr_opacity', 17:50:12 INFO - 'tart', 17:50:12 INFO - 'tscrollx', 17:50:12 INFO - 'cart']}, 17:50:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'talos_options': ['--disable-e10s'], 17:50:12 INFO - 'tests': ['tp5o']}, 17:50:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'tests': ['tp5o']}, 17:50:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'talos_options': ['--disable-e10s', 17:50:12 INFO - '--xperf_path', 17:50:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:12 INFO - 'tests': ['tp5n']}, 17:50:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:12 INFO - 'talos_options': ['--xperf_path', 17:50:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:12 INFO - 'tests': ['tp5n']}}, 17:50:12 INFO - 'talos.zip': {'path': '', 17:50:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:50:12 INFO - Running post-action listener: _resource_record_post_action 17:50:12 INFO - ##### 17:50:12 INFO - ##### Finished populate-webroot step (success) 17:50:12 INFO - ##### 17:50:12 INFO - ##### 17:50:12 INFO - ##### Running create-virtualenv step. 17:50:12 INFO - ##### 17:50:12 INFO - Running pre-action listener: _resource_record_pre_action 17:50:12 INFO - Running main action method: create_virtualenv 17:50:12 INFO - Creating virtualenv /builds/slave/test/build/venv 17:50:12 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 17:50:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:50:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:50:12 INFO - Using real prefix '/usr' 17:50:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:50:12 INFO - Installing distribute.............................................................................................................................................................................................done. 17:50:14 INFO - Installing pip.................done. 17:50:14 INFO - Return code: 0 17:50:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50: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', 'pip>=1.5'] in /builds/slave/test/build 17:50: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 pip>=1.5 17:50:14 INFO - Using env: {'DISPLAY': ':0', 17:50:14 INFO - 'HOME': '/home/cltbld', 17:50:14 INFO - 'LANG': 'en_US.UTF-8', 17:50:14 INFO - 'LANGUAGE': 'en_US:en', 17:50:14 INFO - 'LOGNAME': 'cltbld', 17:50:14 INFO - 'MAIL': '/var/mail/cltbld', 17:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:14 INFO - 'MOZ_NO_REMOTE': '1', 17:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:14 INFO - 'NO_EM_RESTART': '1', 17:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:14 INFO - 'PWD': '/builds/slave/test', 17:50:14 INFO - 'SHELL': '/bin/bash', 17:50:14 INFO - 'SHLVL': '1', 17:50:14 INFO - 'TERM': 'linux', 17:50:14 INFO - 'TMOUT': '86400', 17:50:14 INFO - 'USER': 'cltbld', 17:50:14 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:14 INFO - '_': '/tools/buildbot/bin/python'} 17:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:14 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 17:50:14 INFO - Cleaning up... 17:50:14 INFO - Return code: 0 17:50:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:50: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 psutil>=3.1.1 17:50:14 INFO - Using env: {'DISPLAY': ':0', 17:50:14 INFO - 'HOME': '/home/cltbld', 17:50:14 INFO - 'LANG': 'en_US.UTF-8', 17:50:14 INFO - 'LANGUAGE': 'en_US:en', 17:50:14 INFO - 'LOGNAME': 'cltbld', 17:50:14 INFO - 'MAIL': '/var/mail/cltbld', 17:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:14 INFO - 'MOZ_NO_REMOTE': '1', 17:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:14 INFO - 'NO_EM_RESTART': '1', 17:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:14 INFO - 'PWD': '/builds/slave/test', 17:50:14 INFO - 'SHELL': '/bin/bash', 17:50:14 INFO - 'SHLVL': '1', 17:50:14 INFO - 'TERM': 'linux', 17:50:14 INFO - 'TMOUT': '86400', 17:50:14 INFO - 'USER': 'cltbld', 17:50:14 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:14 INFO - '_': '/tools/buildbot/bin/python'} 17:50:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:15 INFO - Downloading/unpacking psutil>=3.1.1 17:50:15 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 17:50:15 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 17:50:15 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 17:50:15 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 17:50:15 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 17:50:15 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 17:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:50:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:17 INFO - Installing collected packages: psutil 17:50:17 INFO - Running setup.py install for psutil 17:50:17 INFO - building 'psutil._psutil_linux' extension 17:50:17 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 17:50:17 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 17:50:17 INFO - building 'psutil._psutil_posix' extension 17:50:17 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 17:50:17 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 17:50:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:17 INFO - Successfully installed psutil 17:50:17 INFO - Cleaning up... 17:50:17 INFO - Return code: 0 17:50:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:50:17 INFO - Using env: {'DISPLAY': ':0', 17:50:17 INFO - 'HOME': '/home/cltbld', 17:50:17 INFO - 'LANG': 'en_US.UTF-8', 17:50:17 INFO - 'LANGUAGE': 'en_US:en', 17:50:17 INFO - 'LOGNAME': 'cltbld', 17:50:17 INFO - 'MAIL': '/var/mail/cltbld', 17:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:17 INFO - 'MOZ_NO_REMOTE': '1', 17:50:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:17 INFO - 'NO_EM_RESTART': '1', 17:50:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:17 INFO - 'PWD': '/builds/slave/test', 17:50:17 INFO - 'SHELL': '/bin/bash', 17:50:17 INFO - 'SHLVL': '1', 17:50:17 INFO - 'TERM': 'linux', 17:50:17 INFO - 'TMOUT': '86400', 17:50:17 INFO - 'USER': 'cltbld', 17:50:17 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:17 INFO - '_': '/tools/buildbot/bin/python'} 17:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:50:17 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 17:50:17 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 17:50:17 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 17:50:17 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 17:50:17 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 17:50:17 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 17:50:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:50:19 INFO - Installing collected packages: mozsystemmonitor 17:50:19 INFO - Running setup.py install for mozsystemmonitor 17:50:19 INFO - Successfully installed mozsystemmonitor 17:50:19 INFO - Cleaning up... 17:50:19 INFO - Return code: 0 17:50:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:19 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 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:19 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 17:50:19 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 17:50:19 INFO - Using env: {'DISPLAY': ':0', 17:50:19 INFO - 'HOME': '/home/cltbld', 17:50:19 INFO - 'LANG': 'en_US.UTF-8', 17:50:19 INFO - 'LANGUAGE': 'en_US:en', 17:50:19 INFO - 'LOGNAME': 'cltbld', 17:50:19 INFO - 'MAIL': '/var/mail/cltbld', 17:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:19 INFO - 'MOZ_NO_REMOTE': '1', 17:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:19 INFO - 'NO_EM_RESTART': '1', 17:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:19 INFO - 'PWD': '/builds/slave/test', 17:50:19 INFO - 'SHELL': '/bin/bash', 17:50:19 INFO - 'SHLVL': '1', 17:50:19 INFO - 'TERM': 'linux', 17:50:19 INFO - 'TMOUT': '86400', 17:50:19 INFO - 'USER': 'cltbld', 17:50:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:19 INFO - '_': '/tools/buildbot/bin/python'} 17:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:19 INFO - Downloading/unpacking blobuploader==1.2.4 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50:21 INFO - Downloading blobuploader-1.2.4.tar.gz 17:50:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:50:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:50:21 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 17:50:21 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 17:50:21 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 17:50:21 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 17:50:21 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 17:50:21 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 17:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:50:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:50:22 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 17:50:22 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 17:50:22 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 17:50:22 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 17:50:22 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 17:50:22 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 17:50:22 INFO - Downloading docopt-0.6.1.tar.gz 17:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:50:22 INFO - Installing collected packages: blobuploader, requests, docopt 17:50:22 INFO - Running setup.py install for blobuploader 17:50:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:50:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:50:22 INFO - Running setup.py install for requests 17:50:22 INFO - Running setup.py install for docopt 17:50:22 INFO - Successfully installed blobuploader requests docopt 17:50:22 INFO - Cleaning up... 17:50:22 INFO - Return code: 0 17:50:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:50:22 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')]} 17:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:23 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 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:23 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 17:50:23 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 17:50:23 INFO - Using env: {'DISPLAY': ':0', 17:50:23 INFO - 'HOME': '/home/cltbld', 17:50:23 INFO - 'LANG': 'en_US.UTF-8', 17:50:23 INFO - 'LANGUAGE': 'en_US:en', 17:50:23 INFO - 'LOGNAME': 'cltbld', 17:50:23 INFO - 'MAIL': '/var/mail/cltbld', 17:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:23 INFO - 'MOZ_NO_REMOTE': '1', 17:50:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:23 INFO - 'NO_EM_RESTART': '1', 17:50:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:23 INFO - 'PWD': '/builds/slave/test', 17:50:23 INFO - 'SHELL': '/bin/bash', 17:50:23 INFO - 'SHLVL': '1', 17:50:23 INFO - 'TERM': 'linux', 17:50:23 INFO - 'TMOUT': '86400', 17:50:23 INFO - 'USER': 'cltbld', 17:50:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:23 INFO - '_': '/tools/buildbot/bin/python'} 17:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:50:23 INFO - Running setup.py (path:/tmp/pip-zRrU7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:50:23 INFO - Running setup.py (path:/tmp/pip-wy7OqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:50:23 INFO - Running setup.py (path:/tmp/pip-qbbqpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:50:23 INFO - Running setup.py (path:/tmp/pip-Rl_Hs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:50:23 INFO - Running setup.py (path:/tmp/pip-P5mfqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:50:23 INFO - Running setup.py (path:/tmp/pip-KkcUZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:50:23 INFO - Running setup.py (path:/tmp/pip-_faG1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:50:23 INFO - Running setup.py (path:/tmp/pip-J8sI1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:50:23 INFO - Running setup.py (path:/tmp/pip-goa0o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:50:23 INFO - Running setup.py (path:/tmp/pip-NreK7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:50:23 INFO - Running setup.py (path:/tmp/pip-TmV949-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:50:23 INFO - Running setup.py (path:/tmp/pip-fFiQHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:50:23 INFO - Running setup.py (path:/tmp/pip-MEYd3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:50:23 INFO - Running setup.py (path:/tmp/pip-H9uUqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:23 INFO - Running setup.py (path:/tmp/pip-apDu6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:50:24 INFO - Running setup.py (path:/tmp/pip-H4b6t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:50:24 INFO - Running setup.py (path:/tmp/pip-ELp9xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:50:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:50:24 INFO - Running setup.py install for manifestparser 17:50:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Running setup.py install for mozcrash 17:50:24 INFO - Running setup.py install for mozdebug 17:50:24 INFO - Running setup.py install for mozdevice 17:50:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Running setup.py install for mozfile 17:50:24 INFO - Running setup.py install for mozhttpd 17:50:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Running setup.py install for mozinfo 17:50:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Running setup.py install for mozInstall 17:50:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:50:24 INFO - Running setup.py install for mozleak 17:50:24 INFO - Running setup.py install for mozlog 17:50:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Running setup.py install for moznetwork 17:50:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Running setup.py install for mozprocess 17:50:25 INFO - Running setup.py install for mozprofile 17:50:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Running setup.py install for mozrunner 17:50:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Running setup.py install for mozscreenshot 17:50:25 INFO - Running setup.py install for moztest 17:50:25 INFO - Running setup.py install for mozversion 17:50:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:50:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:50:25 INFO - Cleaning up... 17:50:25 INFO - Return code: 0 17:50:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:25 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 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:25 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 17:50:25 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 17:50:25 INFO - Using env: {'DISPLAY': ':0', 17:50:25 INFO - 'HOME': '/home/cltbld', 17:50:25 INFO - 'LANG': 'en_US.UTF-8', 17:50:25 INFO - 'LANGUAGE': 'en_US:en', 17:50:25 INFO - 'LOGNAME': 'cltbld', 17:50:25 INFO - 'MAIL': '/var/mail/cltbld', 17:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:25 INFO - 'MOZ_NO_REMOTE': '1', 17:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:25 INFO - 'NO_EM_RESTART': '1', 17:50:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:25 INFO - 'PWD': '/builds/slave/test', 17:50:25 INFO - 'SHELL': '/bin/bash', 17:50:25 INFO - 'SHLVL': '1', 17:50:25 INFO - 'TERM': 'linux', 17:50:25 INFO - 'TMOUT': '86400', 17:50:25 INFO - 'USER': 'cltbld', 17:50:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:25 INFO - '_': '/tools/buildbot/bin/python'} 17:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:50:25 INFO - Running setup.py (path:/tmp/pip-3wF84f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:50:26 INFO - Running setup.py (path:/tmp/pip-XB581B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:50:26 INFO - Running setup.py (path:/tmp/pip-QQeqzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:50:26 INFO - Running setup.py (path:/tmp/pip-oyXxVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:50:26 INFO - Running setup.py (path:/tmp/pip-_Sc32b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:50:26 INFO - Running setup.py (path:/tmp/pip-WqvNtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:50:26 INFO - Running setup.py (path:/tmp/pip-ryiMyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:50:26 INFO - Running setup.py (path:/tmp/pip-z6me9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:50:26 INFO - Running setup.py (path:/tmp/pip-euP_LY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:50:26 INFO - Running setup.py (path:/tmp/pip-otkpY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:50:26 INFO - Running setup.py (path:/tmp/pip-RoBzfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:50:26 INFO - Running setup.py (path:/tmp/pip-ZBEBP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:50:26 INFO - Running setup.py (path:/tmp/pip-a3MWYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:50:26 INFO - Running setup.py (path:/tmp/pip-TepTql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:26 INFO - Running setup.py (path:/tmp/pip-61vNiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:50:26 INFO - Running setup.py (path:/tmp/pip-RyIsIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:50:26 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)) 17:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:50:26 INFO - Running setup.py (path:/tmp/pip-e4vFVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:50:26 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)) 17:50:26 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)) 17:50:26 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)) 17:50:26 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)) 17:50:26 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)) 17:50:26 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)) 17:50:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:28 INFO - Downloading blessings-1.6.tar.gz 17:50:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:50:28 INFO - Installing collected packages: blessings 17:50:28 INFO - Running setup.py install for blessings 17:50:29 INFO - Successfully installed blessings 17:50:29 INFO - Cleaning up... 17:50:29 INFO - Return code: 0 17:50:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:50:29 INFO - Reading from file tmpfile_stdout 17:50:29 INFO - Current package versions: 17:50:29 INFO - argparse == 1.2.1 17:50:29 INFO - blessings == 1.6 17:50:29 INFO - blobuploader == 1.2.4 17:50:29 INFO - docopt == 0.6.1 17:50:29 INFO - manifestparser == 1.1 17:50:29 INFO - mozInstall == 1.12 17:50:29 INFO - mozcrash == 0.17 17:50:29 INFO - mozdebug == 0.1 17:50:29 INFO - mozdevice == 0.48 17:50:29 INFO - mozfile == 1.2 17:50:29 INFO - mozhttpd == 0.7 17:50:29 INFO - mozinfo == 0.9 17:50:29 INFO - mozleak == 0.1 17:50:29 INFO - mozlog == 3.2 17:50:29 INFO - moznetwork == 0.27 17:50:29 INFO - mozprocess == 0.22 17:50:29 INFO - mozprofile == 0.28 17:50:29 INFO - mozrunner == 6.11 17:50:29 INFO - mozscreenshot == 0.1 17:50:29 INFO - mozsystemmonitor == 0.3 17:50:29 INFO - moztest == 0.7 17:50:29 INFO - mozversion == 1.4 17:50:29 INFO - psutil == 3.1.1 17:50:29 INFO - requests == 1.2.3 17:50:29 INFO - wsgiref == 0.1.2 17:50:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:29 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 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:29 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 17:50:29 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 17:50:29 INFO - Using env: {'DISPLAY': ':0', 17:50:29 INFO - 'HOME': '/home/cltbld', 17:50:29 INFO - 'LANG': 'en_US.UTF-8', 17:50:29 INFO - 'LANGUAGE': 'en_US:en', 17:50:29 INFO - 'LOGNAME': 'cltbld', 17:50:29 INFO - 'MAIL': '/var/mail/cltbld', 17:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:29 INFO - 'MOZ_NO_REMOTE': '1', 17:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:29 INFO - 'NO_EM_RESTART': '1', 17:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:29 INFO - 'PWD': '/builds/slave/test', 17:50:29 INFO - 'SHELL': '/bin/bash', 17:50:29 INFO - 'SHLVL': '1', 17:50:29 INFO - 'TERM': 'linux', 17:50:29 INFO - 'TMOUT': '86400', 17:50:29 INFO - 'USER': 'cltbld', 17:50:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:29 INFO - '_': '/tools/buildbot/bin/python'} 17:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 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)) 17:50:29 INFO - Cleaning up... 17:50:29 INFO - Return code: 0 17:50:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50: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', 'jsonschema'] in /builds/slave/test/build 17:50: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 jsonschema 17:50:29 INFO - Using env: {'DISPLAY': ':0', 17:50:29 INFO - 'HOME': '/home/cltbld', 17:50:29 INFO - 'LANG': 'en_US.UTF-8', 17:50:29 INFO - 'LANGUAGE': 'en_US:en', 17:50:29 INFO - 'LOGNAME': 'cltbld', 17:50:29 INFO - 'MAIL': '/var/mail/cltbld', 17:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:29 INFO - 'MOZ_NO_REMOTE': '1', 17:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:29 INFO - 'NO_EM_RESTART': '1', 17:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:29 INFO - 'PWD': '/builds/slave/test', 17:50:29 INFO - 'SHELL': '/bin/bash', 17:50:29 INFO - 'SHLVL': '1', 17:50:29 INFO - 'TERM': 'linux', 17:50:29 INFO - 'TMOUT': '86400', 17:50:29 INFO - 'USER': 'cltbld', 17:50:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:29 INFO - '_': '/tools/buildbot/bin/python'} 17:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:29 INFO - Downloading/unpacking jsonschema 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:50:31 INFO - Downloading/unpacking functools32 (from jsonschema) 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:50:31 INFO - warning: no files found matching '*.txt' 17:50:31 INFO - no previously-included directories found matching 'build' 17:50:31 INFO - no previously-included directories found matching 'dist' 17:50:31 INFO - no previously-included directories found matching '.git*' 17:50:31 INFO - Installing collected packages: jsonschema, functools32 17:50:31 INFO - Running setup.py install for functools32 17:50:31 INFO - warning: no files found matching '*.txt' 17:50:31 INFO - no previously-included directories found matching 'build' 17:50:31 INFO - no previously-included directories found matching 'dist' 17:50:31 INFO - no previously-included directories found matching '.git*' 17:50:31 INFO - Successfully installed jsonschema functools32 17:50:31 INFO - Cleaning up... 17:50:31 INFO - Return code: 0 17:50:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5acc3b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1232770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, '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': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', '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 17:50:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:50:31 INFO - Using env: {'DISPLAY': ':0', 17:50:31 INFO - 'HOME': '/home/cltbld', 17:50:31 INFO - 'LANG': 'en_US.UTF-8', 17:50:31 INFO - 'LANGUAGE': 'en_US:en', 17:50:31 INFO - 'LOGNAME': 'cltbld', 17:50:31 INFO - 'MAIL': '/var/mail/cltbld', 17:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:31 INFO - 'MOZ_NO_REMOTE': '1', 17:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:31 INFO - 'NO_EM_RESTART': '1', 17:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:31 INFO - 'PWD': '/builds/slave/test', 17:50:31 INFO - 'SHELL': '/bin/bash', 17:50:31 INFO - 'SHLVL': '1', 17:50:31 INFO - 'TERM': 'linux', 17:50:31 INFO - 'TMOUT': '86400', 17:50:31 INFO - 'USER': 'cltbld', 17:50:31 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:31 INFO - '_': '/tools/buildbot/bin/python'} 17:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:32 INFO - Downloading/unpacking flake8 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50: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 17:50:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:50:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:50:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 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 17:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:50:34 INFO - no previously-included directories found matching 'docs/_build' 17:50:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:50:34 INFO - Running setup.py install for pep8 17:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:50:34 INFO - no previously-included directories found matching 'docs/_build' 17:50:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:50:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:50:34 INFO - Cleaning up... 17:50:35 INFO - Return code: 0 17:50:35 INFO - Running post-action listener: _resource_record_post_action 17:50:35 INFO - Running post-action listener: _start_resource_monitoring 17:50:35 INFO - Starting resource monitoring. 17:50:35 INFO - ##### 17:50:35 INFO - ##### Finished create-virtualenv step (success) 17:50:35 INFO - ##### 17:50:35 INFO - ##### 17:50:35 INFO - ##### Running install step. 17:50:35 INFO - ##### 17:50:35 INFO - Running pre-action listener: _resource_record_pre_action 17:50:35 INFO - Running main action method: install 17:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:50:35 INFO - Reading from file tmpfile_stdout 17:50:35 INFO - Detecting whether we're running mozinstall >=1.0... 17:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:50:35 INFO - Reading from file tmpfile_stdout 17:50:35 INFO - Output received: 17:50:35 INFO - Usage: mozinstall [options] installer 17:50:35 INFO - Options: 17:50:35 INFO - -h, --help show this help message and exit 17:50:35 INFO - -d DEST, --destination=DEST 17:50:35 INFO - Directory to install application into. [default: 17:50:35 INFO - "/builds/slave/test"] 17:50:35 INFO - --app=APP Application being installed. [default: firefox] 17:50:35 INFO - mkdir: /builds/slave/test/build/application 17:50:35 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'] 17:50:35 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 17:50:51 INFO - Reading from file tmpfile_stdout 17:50:51 INFO - Output received: 17:50:51 INFO - /builds/slave/test/build/application/firefox/firefox 17:50:51 INFO - Running post-action listener: _resource_record_post_action 17:50:51 INFO - ##### 17:50:51 INFO - ##### Finished install step (success) 17:50:51 INFO - ##### 17:50:51 INFO - ##### 17:50:51 INFO - ##### Running run-tests step. 17:50:51 INFO - ##### 17:50:51 INFO - Running pre-action listener: _resource_record_pre_action 17:50:51 INFO - Running main action method: run_tests 17:50:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:50:51 INFO - Python 2.7.3 17:50:51 INFO - Return code: 0 17:50:51 INFO - grabbing minidump binary from tooltool 17:50:51 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')]} 17:50:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c19c0>, '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 0x12c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c2d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:50:51 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 17:50:51 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 17:50:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:50:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:50:51 INFO - Return code: 0 17:50:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:50:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:50:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:50:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:50:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:50:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:50:52 INFO - Return code: 0 17:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 17:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 17:50:52 INFO - Using env: {'DISPLAY': ':0', 17:50:52 INFO - 'HOME': '/home/cltbld', 17:50:52 INFO - 'LANG': 'en_US.UTF-8', 17:50:52 INFO - 'LANGUAGE': 'en_US:en', 17:50:52 INFO - 'LOGNAME': 'cltbld', 17:50:52 INFO - 'MAIL': '/var/mail/cltbld', 17:50:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:50:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:52 INFO - 'MOZ_NO_REMOTE': '1', 17:50:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:52 INFO - 'NO_EM_RESTART': '1', 17:50:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:52 INFO - 'PWD': '/builds/slave/test', 17:50:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:50:52 INFO - 'SHELL': '/bin/bash', 17:50:52 INFO - 'SHLVL': '1', 17:50:52 INFO - 'TERM': 'linux', 17:50:52 INFO - 'TMOUT': '86400', 17:50:52 INFO - 'USER': 'cltbld', 17:50:52 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465346999.820269-456021559', 17:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:52 INFO - '_': '/tools/buildbot/bin/python'} 17:50:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 17:50:52 INFO - mozversion application_buildid: 20160607155856 17:50:52 INFO - mozversion application_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 17:50:52 INFO - mozversion application_display_name: Nightly 17:50:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:50:52 INFO - mozversion application_name: Firefox 17:50:52 INFO - mozversion application_remotingname: firefox 17:50:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 17:50:52 INFO - mozversion application_vendor: Mozilla 17:50:52 INFO - mozversion application_version: 49.0a1 17:50:52 INFO - mozversion platform_buildid: 20160607155856 17:50:52 INFO - mozversion platform_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 17:50:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 17:50:52 INFO - mozversion platform_version: 49.0a1 17:50:52 INFO - using testdate: 1465347052 17:50:52 INFO - actual date: 1465347052 17:50:52 INFO - starting webserver on 'localhost:34673' 17:50:52 INFO - SUITE-START | Running 2 tests 17:50:52 INFO - TEST-START | dromaeo_css 17:50:52 INFO - operating with platform_type : linux_ 17:50:52 INFO - Initialising browser for dromaeo_css test... 17:50:52 INFO - TEST-INFO | started process 2506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpekPBEf/profile http://localhost:34673/getInfo.html) 17:50:57 INFO - PROCESS | 2506 | __metrics Screen width/height:1600/1200 17:50:57 INFO - PROCESS | 2506 | colorDepth:24 17:50:57 INFO - PROCESS | 2506 | Browser inner width/height: 1024/697 17:50:57 INFO - PROCESS | 2506 | __metrics 17:50:58 ERROR - PROCESS | 2506 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 17:50:58 INFO - TEST-INFO | 2506: exit 0 17:50:58 INFO - Browser initialized. 17:50:58 INFO - Running cycle 1/1 for dromaeo_css test... 17:50:58 INFO - TEST-INFO | started process 2624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpekPBEf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:10 INFO - PROCESS | 2624 | RSS: Main: 167718912 17:51:10 INFO - PROCESS | 2624 | 17:52:00 INFO - PROCESS | 2624 | 1465347120002 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] 17:54:11 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34673/tests/dromaeo/cssquery-ext.html) 17:54:12 INFO - PROCESS | 2624 | RSS: Main: 170262528 17:54:12 INFO - PROCESS | 2624 | 17:57:14 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-ext.html (next: http://localhost:34673/tests/dromaeo/cssquery-jquery.html) 17:57:15 INFO - PROCESS | 2624 | RSS: Main: 169283584 17:57:15 INFO - PROCESS | 2624 | 18:00:17 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34673/tests/dromaeo/cssquery-mootools.html) 18:00:17 INFO - PROCESS | 2624 | RSS: Main: 170356736 18:00:17 INFO - PROCESS | 2624 | 18:03:19 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34673/tests/dromaeo/cssquery-prototype.html) 18:03:20 INFO - PROCESS | 2624 | RSS: Main: 168001536 18:03:20 INFO - PROCESS | 2624 | 18:06:22 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34673/tests/dromaeo/cssquery-yui.html) 18:06:23 INFO - PROCESS | 2624 | RSS: Main: 170287104 18:06:23 INFO - PROCESS | 2624 | 18:09:25 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:34673/tests/dromaeo/cssquery-yui.html (next: http://localhost:34673/tests/dromaeo/cssquery-dojo.html) 18:09:26 INFO - PROCESS | 2624 | RSS: Main: 171089920 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | __start_tp_report 18:09:26 INFO - PROCESS | 2624 | _x_x_mozilla_page_load 18:09:26 INFO - PROCESS | 2624 | _x_x_mozilla_page_load_details 18:09:26 INFO - PROCESS | 2624 | |i|pagename|runs| 18:09:26 INFO - PROCESS | 2624 | |0;dojo.html;2586.1594981630974;2601.6877974643044;2631.329011102168;2638.287662331171;2655.8804853781567;4523.592876641102;4524.63802895768;4590.495045544987;4617.2150734375155;4656.022235330576;4500.009997800478;4637.976810115944;4639.396878405811;4668.9766551167195;4672.626189904808;4108.917821643566;4276.337167738998;4345;4374.671899607542;4406.5593440656;4310.8145260053425;4366.2359087159775;4384.0355121873135;4396.89007774805;4404.911901761979;4116.629503344683;4125.174965006996;4160.958390416087;4226.239276930151;4229.619334259932;4204.453421055274;4268.573142685737;4273.807678654437;4321.222180007598;4321.805518751664;4229.70392072555;4245.893852653678;4264.893377665552;4277.165952639237;4330.090680957002;4441.622462090726;4542.568455996675;4572.977135114336;4576.45082590088;4599.172149013176;4975.776090075955;4980.12847751642;4996;5011.949880501221;5029.547340739351;4864.075825593126;4992.276119962585;5015.6959190610705;5036.471170527078;5087.694738315713;4665.370175026328;4683.133620280225;4696.506866778939;4718.197906355927;4728.71627702339;5154.793808247703;5163.380394352626;5171.715555644476;5187.325647665854;5199.012187684328;4691.906161876744;4710.552497512731;4863.367762190893;4963;4978.77595508203;4874.658773885794;4882;5010.123228435083;5045.47022562633;5058.696478211319;4897.902041959141;4960.776765045582;4962.503749625081;4970.055689418999;5028.019536190481;4721.811127554928;4906.681065730716;4920.827771027997;4943.72809495474;4958.553730164303;4241.27898257297;4262.3180291153885;4279;4301.075268817157;4312;3713.665770080652;3729.9440508392395;3734.7945862977253;3757.5866654667966;3768.491253680718;4303.2469317869245;4367.978160109179;4382.9919118602265;4387.100644367897;4402.515723270438;4980.601551875841;5056.848294551096;5061.670991385548;5073.670211436245;5087.872803179876;4235.322348424237;4356.346548017759;4363.36731173978;4398.252297109499;4418.7127836690515;4469.642428605704;4540.659450541222;4543.61379282758;4547.658925580528;4583.175028494837;4698.553637404373;4735.692180008288;4765.404324459442;4770.379850619397;4808.374911261513;4842.539958703917;4959.280904268933;5022.4977502249485;5026.572741317029;5079.238114282739;4682.555157260055;4684.320773487757;4716.929246061243;4760.309755085424;4793.880152996203;4918.6065114791445;4932.728699921395;4963.6525443218625;4964.553190212754;4965.702057876539;4838.395200599925;4915;4928.0390323886495;5036.169032109661;5074.873128171826;4667.416572928384;4741.383620129361;4835.960268542281;4849.612031037579;4929.679570827885;4847.006363695505;4851.46633870281;4868.488808675038;4868.586170175433;4882.267659850909;4543.613792827515;4549.840755573539;4787.808487660454;4804.25534042224;4807.351007613927;4772.475027746876;4799.520047995312;4817.614590832795;4820.734859582757;4914.238293064581;4812.037592481448;4834.347363106076;4838.2016967201425;4869.123557759495;4884.194107972145 18:09:26 INFO - PROCESS | 2624 | |1;ext.html;558.4443478738655;564.5088772767691;566.7477972302327;579.4292621767561;616.183556787257;34681.30637387251;38471.80764096179;38506.614933850615;38607.80696096522;38643;39290;39986.200275994466;40131.39802902959;40164.39753403694;40319.798401008025;7269.89095163573;7398.334149926505;7403.889416587501;7417.703291868319;7466.104067511907;7551.848963020736;7591.658375373094;7594.658240379169;7627.923720762777;7651.273129052731;7560.206178351276;7587.317141457295;7604.011478507814;7613.162552119263;7645.388368930472;7537.321641052305;7567.243275672444;7645.655945482439;7653;7668.884966725504;7532.849343013164;7631.923680763232;7650.540967541965;7668.578228197447;7701.499402538817;36441.63558364409;37381.813090934644;37394.25211495782;37502.4374634378;37663.43504847402;3116.8597413116236;3253.4957081652383;3347.916302092467;3387.2548039431285;3721.5348081489815;28728.281792955135;29223.269418264714;29247.707522924713;29270.121896343142;29313.241205527524;3345.1804307944467;3350;3408.829558522064;3511.543499345069;3512.59605145409;255558;257912;259411;261194;262931;30806.53790193149;46274.5372546277;46602.766986164854;46977;47097.76451117791;31299.687003130133;33274.3345133096;33285.334293314;33539.161520962174;33846;12552;12562.555306139728;12604;12641.367931603567;12671.366431678563;10387.220958428148;10390.532426040847;10408.739781505372;10428.061474467335;10440;7886.132525422314;7894.763157105296;7903;7912;7968.880466793003;3118.8596513156963;3119.0175094845504;3124.42198193335;3135.294558724269;3135.8275294859;841.6843683618642;846.2341580869323;894.5348418822306;1039.6361273554196;1049.7270709615555;25431.87284063605;26368.472630547283;26473.544368039915;27696.723032769416;27783.72216277852;975.2685485885586;991.9156871665845;1002.1631937332409;1048.1614708233383;1050.4747626186906;14279.500217492337;14685.48600798967;15058.32237549313;15484.30320635574;15954;383566;386839;387025;389103;390776;49148;49535.752321238164;49593;49697;49949.750251249236;1196.86563405734;1217.3183017510223;1221.5052903574067;1230.5323976888726;1236.6970092327617;27468.313292167855;28722;28824.567631485126;28980.27549311284;29274.707252927197;42222;42966.78516607397;43080;43103.7844810774;43130.78434607807;352682;356859;357386;357951;360704;24513.877430612734;25101;25337.366565836;25431.745682542936;25480.108196213045;40641.39037914375;41423.7928810354;41546.79226603848;42286;42290.788546057076;35398;35577.644223557436;35588.64411355957;35715;35874.64125358713;34762.65237347594;35772.821135894155;35917.46123808093;36404.089897751706;36453;29651.2209267436;33698.49452258169;33969.83015085007;34638.480422793175;35046;30679.2330191747;32000.199995000312;32074.358512829145;32262.19344516406;32284.35431291314;24366.878165609058;24522.509549808547;25061.122860699787;25296.367590810376;25378.61932071057 18:09:26 INFO - PROCESS | 2624 | |2;jquery.html;358.3549610700738;362.9419292913134;364.84858783604795;373.1101323343824;377.79410221429316;15225.238738063093;16242.187890605466;17048.914755426205;17067.91466042671;17209.139543022844;15715.84284157158;15991.520254392386;16611.169441527905;16738;16838;4826.638002149844;5012.248162775594;5012.624053196007;5016.072026675059;5049.621278404116;3686.31803116423;4954.777035033432;5511.283533140706;5840.182374467557;5841.15303281024;5782.739776710058;5886.617369870966;5891.55813314001;5919.348871624118;5948.1375200595885;5641.971790141043;5893;5903.557233207526;5904.793332233394;5931.614445061057;5091.210862316347;5133.332666753298;5139.922901156486;5221.973890130525;5886.234789477383;20351.389458316273;21031.68452473214;21571.67642485364;22840.54318913629;23082.42293942648;1917.2906024770784;2007.3576455534235;2041.9688057531;2085.051301657742;2112.904919278636;13278.402471888789;13370.799438008438;13544.59366219015;13604.727905441934;14023.579292621238;1883.8587105967108;1905.7427247321573;1951.9121639526113;1958.7453631027875;1963.2932144427994;70080;73212;75628.62185689091;76103.61948190279;76522;15543.689126217412;16490.505284841474;16634.833651663328;16770.496885093467;17444.040577768345;28338.716612833607;28429.289267768472;28437.289067773057;28659;28725;8416.747497575083;8848.513331766686;8856.24721898633;8938.821223575493;8989.775255618531;6802.489813263926;6855.7600483983815;6879.965600171967;6899.310068993161;7060.646967651597;8179.877301840477;8291.419600627898;8397.992240931006;8431.747047588582;8463.788405289793;1521.7413039783041;1621.2218135295125;1624.16355576878;1630.4945466905297;3292.736581073508;860.6514361683528;875.2735229759285;902.0618556700957;919.6551293265026;924.8751418558543;14191.858081419261;14638.560843174722;15894.364225430852;16194.947328423616;16414.507564772837;842.650300125441;890.0298674444886;894.561664784251;898.6270697660528;937.4234845569925;8934.721645980364;9222.769430764285;9276.304277179239;9415.62337506492;9516.524173791282;381470;382141;391426;392118;396244;72891.27108728951;73409.26590734025;74259.62870185614;74877.6256118716;76228;908.4594666173616;908.6955869783565;911.9544022798701;917.5687426909342;924.9861252081224;14337.56987290383;14638.634034149232;15460;15713.109795404482;16045.315241899432;23735;24373.75626243786;33462;33961.49057764185;35119.99080262113;45627;47151;47162.29256561086;47200.29199561941;47707;7062;7132.8216794578475;7136.85726285461;7173.426125910019;7275.308845659654;9077.77305567366;9213.953930230575;9684.612615495302;9882.802343952937;10208.081272685495;8979.371443998858;9003.324750643596;9085.90914090877;9096.317776166681;9294.860577091215;9008.549572521479;9038.231750301298;9122.133397327243;9333.206677432494;9417.199538039344;9180.944191417806;9270.258379329773;9351.158395744416;9425.293103017164;9666.903330966601;7042.43660507148;7056.15326160864;7352.742654007084;7403.888941665771;7414.036175297177;8468.534230617375;9114.908850911406;9161.908380916106;9326.114019168168;9460.716178514656 18:09:26 INFO - PROCESS | 2624 | |3;mootools.html;1313.048040170876;1375.9724805503888;1395.4209003263645;1657.5027491752464;1683.5538582275692;6584.6707664616815;7072.611006394646;7280.126384833828;7457.291557302047;7462.365698915592;7545.773626791191;7649.661309270869;7666.501677390966;7678.692852285902;7835.4906931049445;2180.291405293278;2208.9949073171747;2209.5139069404704;2225.2100504320897;2226.955460890781;2187.824974002076;2200.966985495219;2205.724284464442;2213.015208232337;2217.66734989752;2144.02446886667;2150.193677370986;2152.6878602602615;2154.095279982403;2154.6121698094335;2144.356692992104;2146.1844499090325;2147.795959383856;2148.957020859582;2157.374361435182;2089.341857314941;2127.659574468093;2140.3578926322043;2154.515234072337;2167.0898222746328;7665.3101220890385;7674.769756907302;7688.5771282579435;7689.692412303501;7711.190325015905;4382.737035777832;4430.556944305576;4468.486124095705;4488.214562451558;4493.303537951622;2849.7292757188034;2854.9714502854913;2913.737763601286;2960.955585666217;2973.6431628204537;916.5921165081542;943.3915124744501;948.2650945517211;984.6996666016862;984.8916619171886;10403.47982600867;10456.372617642966;10479.528421220986;10521.368717876952;10564.841527377095;4217.62041416274;4229.873103806891;4313.698041137153;4321.546237645065;4351.216780979391;8832.690855820016;8937.776555586031;8942.86585701215;8998.965119011267;9152.633894644272;4392.462638076648;4431.556844315607;4472.485664148599;4499.302608095751;4522.208613492626;3619.2399596085115;4024.939625905614;4027.5166979962005;4029.879103626896;4137.338025915899;3633.454981752758;3641.1807343347537;3682.0978860179425;3686.8340924658455;3745.9438108428394;1195.6054502014315;1215.003696968495;1215.4469716279073;1217.257472941505;1223.2477026628737;846.5682501924063;849.0321034021221;855.4952577978897;860.1054902901038;891.9866202006975;5151.201563757623;5186.662866913714;5251.36983561975;5282.52457278839;5313.973430132824;398.4799836213769;416.09914535031925;416.6916481803504;420.2624394188241;432.09476147470997;3563.1092226943265;3621.420572708407;3679.3193259247123;3729.4778730977687;3743.513343265358;9022.458652480873;9359.532023398802;9360.765980850398;9421.199198068103;9460.337776355589;9078.228350590141;9224.30817688676;9274.953625231965;9323.627054917863;9379.296552758571;6938.202106757686;7089.149302083783;7195.172555156119;7203.315685009916;7207.495475316782;5013.974930125326;5080.847574572768;5235.63873392913;5257.894842103213;5281.762320695655;8654.264387527131;8656.610452529525;8676.08901065379;8751;8900.243479304332;8271.793205169919;8514.276286515715;8630.439021463584;8706;8720.345974051845;5944.256967879015;6376.52176086786;6501.382368674968;6703.396694297537;6704.128463299739;7267.745628902963;7280.053593033083;7324.560526368435;7356.889646655198;7385.261473852572;6572.112764776885;6672.466202703869;6703.798886033427;6736.225334086446;6742.325767423218;6456.935430645822;6515.185601799775;6552.868942621216;6562.868742625025;6580.21037475506;5880.558958078247;5904.261967254093;5906.822795316148;5923.437273459014;5941.435563621449;4534.183846907588;4659.836905708284;4667.32323813052;4674.252119660838;4730.574248317668;4639.30410438437;4643.141018911512;4711.1519926413575;4714;4760.547747963938 18:09:26 INFO - PROCESS | 2624 | |4;prototype.html;297.52990275364937;302.8243618701153;305.9724624783769;311.6104868913858;314.778081452576;4644.233701439267;4762.595179409755;4820.493848145947;4885.535874091965;4889.951100488998;4748.169070412681;4837.5646191842725;4840.830570930018;4850.5391987761195;4880.072786170634;3797.1076796952693;3804.543454785423;3806.809659517027;3830.5403351597847;3839.2705385976715;3747.906302342436;3816.503854498911;3842.846286148551;3842.903927401809;3858.054776579742;3781.7163712721517;3801.2397520495874;3805.923881522368;3813.6758375537975;3814.5231846019246;3785.5078839750795;3806.505154329947;3820.560635526894;3823.5220597425323;3831.578526362098;3375.4261775498226;3396.779209351409;3401.387750204987;3412.8805491807916;3421.9657803421896;4408.647308215335;4669.743164125972;4721.24460086388;4722.693024953402;4732.361131247272;4338.2191205582985;4341.218580655481;4364.519902810688;4372.2130016597;4381.518033016366;5045.754639185564;5054.3176671149295;5062.683702237445;5144.588432925357;5147.330846989905;3655.5796083450477;3695.519582454264;3703.2778608171416;3725.1990821973404;3734.589195188527;5281;5313.123334649778;5324.668832791802;5325.786968521294;5325.866853328659;3830.138218900725;3839.788811615388;3839.9232015359535;3848.249591329664;3865.014421322601;3911.393733971239;3915.647591716702;3921.470601468822;3923.7449565778134;3949.2496425679606;4240.194363071007;4365.541618130051;4392.253316936129;4393;4394.231009573312;5042.899142017139;5058.266551350107;5067.543921047123;5072.873178170502;5140.717260550706;1269.1116218646985;1316.4668309334734;1323.305264736002;1341.4097796969302;1344.2136350235025;862.0818827948215;868.5961028121953;888.657866721317;891.2246345679171;895.1764376773384;3954.268460334847;3975.3502296547185;3984.5456408410723;4009.1981603679383;4013;4525.411696479437;4564.452265728134;4585.610223131004;4624.5144259852905;4631.475845664121;3935.153941902505;3953.9439491464277;3957.8627541127466;3979.9403008954887;4038.8788336349958;2362.64560315954;2445.914392996272;2448.816338774599;2455.1529722245796;2473.8639374834556;4269.566347182138;4334.179644549296;4339;4369.650427965818;4392.846250381222;4603.286490593963;4637.25803871379;4663.673542852036;4666.160091183551;4683.5082316356975;4032.8185231664647;4234.348604044425;4261.722988005832;4327.5239723630375;4352.651787856964;4498.122866041091;4505.130370896041;4530.796114174936;4558.247889098262;4566.383538222298;4461.330800379969;4465.218586747371;4471.463424389094;4493.303537951622;4507.188706032945;4111.588841115865;4197.139586384723;4291.67812414064;4297.333913243452;4328.502222244356;3382.780119292238;3401.1837159081147;3407.9318413632086;3409.9147521312166;3426.1434641339665;4559.452865656142;4577.862664120082;4595.402597662416;4633.058008164772;5079.974600127123;4865.683730557503;5037.143685573388;5080.771365288645;5095.617828662791;5100.591952643706;5053.2818841594035;5060.772265247999;5070.721110338966;5074.923876141787;5096.541311282002;4833.951660483491;4972.825951091694;5016.194170098835;5016.799328026838;5067.695938243717;5129.846104616868;5147.948520514747;5150.48495150482;5151.43644863554;5168.328117344721;4919.950800491949;4931.876703082452;4933.55597996182;4959.876003099807;4987.376577927759 18:09:26 INFO - PROCESS | 2624 | |5;yui.html;667.252677001758;681.6796105830258;681.9863602727944;687.1342108942731;697.1181455458845;11070.28043177194;11323.20737548372;11730.648080557576;11774.175807693466;11777.175597708163;12762.936185319084;13026.02304827137;13175.93412032941;13246.735065298688;13267.867321326783;623.7193263031631;758.8292634157326;760.2435576601275;763.6601712238056;770.7803276066294;773.5668025905483;795.1134485049187;796.6693822063836;801.7594721583531;802.5104686141449;822.2599660305713;830.2030051150872;832.546262287055;835.8035861572525;837.9455335403179;824.2458150792048;827.230675471814;827.668932427028;828.5153185386556;830.1573902488979;604.552631053022;605.5125623872825;607.4623957797337;607.5625549604277;608.3247595169358;11215.439228038565;11259.831102533468;11262.887371126266;11281.210315277935;11290.20968532203;10508.369497830077;10841.891581084166;10934.398608076554;11079.335239885633;11107.944460277648;8882.955585222033;9022.005132219061;9097.63609455621;9203.079692030811;9432.151106400457;3109.54911537828;3187.7609179311644;3258.006308076036;3273.492608645664;3295.6374798772117;45401.091978160584;45575.54424455746;45751.313730294074;46061.76969115166;46186;8876.8668469973;9001.684941027164;9002.639894404281;9068.138526839939;9229;16112.838871611368;16144.434944776876;16188.514344569681;16251.349946002027;16259.10574918391;6026.246719160105;6215.751369945242;6218.098375735493;6309.463695585835;6342.460890824332;3401.829908504614;3449.3618680543677;3453.8445769940413;3466.6533346665133;3501.439769636846;2642.735726427342;2730.071775596307;2733.166384252822;2734.3164208947765;2734.6991830898587;517.76700484782;519.1019536202391;531.8271561742449;532.1937265043463;544.4881811097556;473.4697139204113;481.7831975610989;534.2547146730367;573.5698226330252;614.4285814192843;7335.686301840402;7517.924820751722;7704.30661240491;7786.610669466504;7930.405219608552;474.43305250226337;587.6532845621105;591.2845456996996;596.832886791699;600.3756093662629;3655.83013435698;3661.212839239576;3683.4658974448553;3747.6252374762853;3792.4311353297226;55582.72208638931;55656;55916.44083559112;56436.43563564394;56603;48072.519274807506;48982.51017489851;49059.26411103836;49769.502304976486;50075.74962125166;44682.55317446784;45133.54866451359;45162.77418612886;45598;46099;7126.358627723323;7211.0869087349965;7304.96347518259;7307.378872795872;7372.410207183305;29586.408271834873;29734.25664358408;30034.3993120132;30832.229194270323;30991;37296.6270337304;39024.6097539021;39461.40807887827;39725.40411893766;40468.595314046484;8601.22588966996;8734.868976965477;8818.5590720465;8838.204561589488;8874.600642970952;9687.66093186735;9795.412275263507;9836.901630983599;9931.900680993098;10100.24248181404;9019.098090190928;9078.863817042882;9095.590698418719;9221.677241296522;9317.301202409712;9202.677906273248;9260.953695231481;9368;9521.857172142561;9566.952165239129;8656.749245196168;9035.232005279624;9748.658796942073;10123.088921997056;10228.795424091626;8776.692815751418;8983.236424903957;9160.35877488596;9167;9241.815163696823;8940.284777217677;9055.773605659911;9183.403078799856;9221.077892210993;9265.258779297776 18:09:26 INFO - PROCESS | 2624 | __end_tp_report 18:09:26 INFO - PROCESS | 2624 | __start_cc_report 18:09:26 INFO - PROCESS | 2624 | _x_x_mozilla_cycle_collect,329 18:09:26 INFO - PROCESS | 2624 | __end_cc_report 18:09:26 INFO - PROCESS | 2624 | __startTimestamp1465348166028__endTimestamp 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | ------- Summary: start ------- 18:09:26 INFO - PROCESS | 2624 | Number of tests: 6 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#0] dojo.html Cycles:165 Average:4607.87 Median:4718.20 stddev:477.44 (10.1%) stddev-sans-first:451.80 18:09:26 INFO - PROCESS | 2624 | Values: 2586.2 2601.7 2631.3 2638.3 2655.9 4523.6 4524.6 4590.5 4617.2 4656.0 4500.0 4638.0 4639.4 4669.0 4672.6 4108.9 4276.3 4345.0 4374.7 4406.6 4310.8 4366.2 4384.0 4396.9 4404.9 4116.6 4125.2 4161.0 4226.2 4229.6 4204.5 4268.6 4273.8 4321.2 4321.8 4229.7 4245.9 4264.9 4277.2 4330.1 4441.6 4542.6 4573.0 4576.5 4599.2 4975.8 4980.1 4996.0 5011.9 5029.5 4864.1 4992.3 5015.7 5036.5 5087.7 4665.4 4683.1 4696.5 4718.2 4728.7 5154.8 5163.4 5171.7 5187.3 5199.0 4691.9 4710.6 4863.4 4963.0 4978.8 4874.7 4882.0 5010.1 5045.5 5058.7 4897.9 4960.8 4962.5 4970.1 5028.0 4721.8 4906.7 4920.8 4943.7 4958.6 4241.3 4262.3 4279.0 4301.1 4312.0 3713.7 3729.9 3734.8 3757.6 3768.5 4303.2 4368.0 4383.0 4387.1 4402.5 4980.6 5056.8 5061.7 5073.7 5087.9 4235.3 4356.3 4363.4 4398.3 4418.7 4469.6 4540.7 4543.6 4547.7 4583.2 4698.6 4735.7 4765.4 4770.4 4808.4 4842.5 4959.3 5022.5 5026.6 5079.2 4682.6 4684.3 4716.9 4760.3 4793.9 4918.6 4932.7 4963.7 4964.6 4965.7 4838.4 4915.0 4928.0 5036.2 5074.9 4667.4 4741.4 4836.0 4849.6 4929.7 4847.0 4851.5 4868.5 4868.6 4882.3 4543.6 4549.8 4787.8 4804.3 4807.4 4772.5 4799.5 4817.6 4820.7 4914.2 4812.0 4834.3 4838.2 4869.1 4884.2 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#1] ext.html Cycles:180 Average:47185.76 Median:196223.82 stddev:89615.90 (45.7%) stddev-sans-first:89798.91 18:09:26 INFO - PROCESS | 2624 | Values: 558.4 564.5 566.7 579.4 616.2 34681.3 38471.8 38506.6 38607.8 38643.0 39290.0 39986.2 40131.4 40164.4 40319.8 7269.9 7398.3 7403.9 7417.7 7466.1 7551.8 7591.7 7594.7 7627.9 7651.3 7560.2 7587.3 7604.0 7613.2 7645.4 7537.3 7567.2 7645.7 7653.0 7668.9 7532.8 7631.9 7650.5 7668.6 7701.5 36441.6 37381.8 37394.3 37502.4 37663.4 3116.9 3253.5 3347.9 3387.3 3721.5 28728.3 29223.3 29247.7 29270.1 29313.2 3345.2 3350.0 3408.8 3511.5 3512.6 255558.0 257912.0 259411.0 261194.0 262931.0 30806.5 46274.5 46602.8 46977.0 47097.8 31299.7 33274.3 33285.3 33539.2 33846.0 12552.0 12562.6 12604.0 12641.4 12671.4 10387.2 10390.5 10408.7 10428.1 10440.0 7886.1 7894.8 7903.0 7912.0 7968.9 3118.9 3119.0 3124.4 3135.3 3135.8 841.7 846.2 894.5 1039.6 1049.7 25431.9 26368.5 26473.5 27696.7 27783.7 975.3 991.9 1002.2 1048.2 1050.5 14279.5 14685.5 15058.3 15484.3 15954.0 383566.0 386839.0 387025.0 389103.0 390776.0 49148.0 49535.8 49593.0 49697.0 49949.8 1196.9 1217.3 1221.5 1230.5 1236.7 27468.3 28722.0 28824.6 28980.3 29274.7 42222.0 42966.8 43080.0 43103.8 43130.8 352682.0 356859.0 357386.0 357951.0 360704.0 24513.9 25101.0 25337.4 25431.7 25480.1 40641.4 41423.8 41546.8 42286.0 42290.8 35398.0 35577.6 35588.6 35715.0 35874.6 34762.7 35772.8 35917.5 36404.1 36453.0 29651.2 33698.5 33969.8 34638.5 35046.0 30679.2 32000.2 32074.4 32262.2 32284.4 24366.9 24522.5 25061.1 25296.4 25378.6 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#2] jquery.html Cycles:180 Average:24688.34 Median:5840.67 stddev:64101.17 (1097.5%) stddev-sans-first:64254.96 18:09:26 INFO - PROCESS | 2624 | Values: 358.4 362.9 364.8 373.1 377.8 15225.2 16242.2 17048.9 17067.9 17209.1 15715.8 15991.5 16611.2 16738.0 16838.0 4826.6 5012.2 5012.6 5016.1 5049.6 3686.3 4954.8 5511.3 5840.2 5841.2 5782.7 5886.6 5891.6 5919.3 5948.1 5642.0 5893.0 5903.6 5904.8 5931.6 5091.2 5133.3 5139.9 5222.0 5886.2 20351.4 21031.7 21571.7 22840.5 23082.4 1917.3 2007.4 2042.0 2085.1 2112.9 13278.4 13370.8 13544.6 13604.7 14023.6 1883.9 1905.7 1951.9 1958.7 1963.3 70080.0 73212.0 75628.6 76103.6 76522.0 15543.7 16490.5 16634.8 16770.5 17444.0 28338.7 28429.3 28437.3 28659.0 28725.0 8416.7 8848.5 8856.2 8938.8 8989.8 6802.5 6855.8 6880.0 6899.3 7060.6 8179.9 8291.4 8398.0 8431.7 8463.8 1521.7 1621.2 1624.2 1630.5 3292.7 860.7 875.3 902.1 919.7 924.9 14191.9 14638.6 15894.4 16194.9 16414.5 842.7 890.0 894.6 898.6 937.4 8934.7 9222.8 9276.3 9415.6 9516.5 381470.0 382141.0 391426.0 392118.0 396244.0 72891.3 73409.3 74259.6 74877.6 76228.0 908.5 908.7 912.0 917.6 925.0 14337.6 14638.6 15460.0 15713.1 16045.3 23735.0 24373.8 33462.0 33961.5 35120.0 45627.0 47151.0 47162.3 47200.3 47707.0 7062.0 7132.8 7136.9 7173.4 7275.3 9077.8 9214.0 9684.6 9882.8 10208.1 8979.4 9003.3 9085.9 9096.3 9294.9 9008.5 9038.2 9122.1 9333.2 9417.2 9180.9 9270.3 9351.2 9425.3 9666.9 7042.4 7056.2 7352.7 7403.9 7414.0 8468.5 9114.9 9161.9 9326.1 9460.7 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#3] mootools.html Cycles:180 Average:5023.91 Median:5047.41 stddev:2788.63 (55.2%) stddev-sans-first:2782.50 18:09:26 INFO - PROCESS | 2624 | Values: 1313.0 1376.0 1395.4 1657.5 1683.6 6584.7 7072.6 7280.1 7457.3 7462.4 7545.8 7649.7 7666.5 7678.7 7835.5 2180.3 2209.0 2209.5 2225.2 2227.0 2187.8 2201.0 2205.7 2213.0 2217.7 2144.0 2150.2 2152.7 2154.1 2154.6 2144.4 2146.2 2147.8 2149.0 2157.4 2089.3 2127.7 2140.4 2154.5 2167.1 7665.3 7674.8 7688.6 7689.7 7711.2 4382.7 4430.6 4468.5 4488.2 4493.3 2849.7 2855.0 2913.7 2961.0 2973.6 916.6 943.4 948.3 984.7 984.9 10403.5 10456.4 10479.5 10521.4 10564.8 4217.6 4229.9 4313.7 4321.5 4351.2 8832.7 8937.8 8942.9 8999.0 9152.6 4392.5 4431.6 4472.5 4499.3 4522.2 3619.2 4024.9 4027.5 4029.9 4137.3 3633.5 3641.2 3682.1 3686.8 3745.9 1195.6 1215.0 1215.4 1217.3 1223.2 846.6 849.0 855.5 860.1 892.0 5151.2 5186.7 5251.4 5282.5 5314.0 398.5 416.1 416.7 420.3 432.1 3563.1 3621.4 3679.3 3729.5 3743.5 9022.5 9359.5 9360.8 9421.2 9460.3 9078.2 9224.3 9275.0 9323.6 9379.3 6938.2 7089.1 7195.2 7203.3 7207.5 5014.0 5080.8 5235.6 5257.9 5281.8 8654.3 8656.6 8676.1 8751.0 8900.2 8271.8 8514.3 8630.4 8706.0 8720.3 5944.3 6376.5 6501.4 6703.4 6704.1 7267.7 7280.1 7324.6 7356.9 7385.3 6572.1 6672.5 6703.8 6736.2 6742.3 6456.9 6515.2 6552.9 6562.9 6580.2 5880.6 5904.3 5906.8 5923.4 5941.4 4534.2 4659.8 4667.3 4674.3 4730.6 4639.3 4643.1 4711.2 4714.0 4760.5 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#4] prototype.html Cycles:180 Average:4051.92 Median:4386.89 stddev:1151.52 (26.2%) stddev-sans-first:1119.74 18:09:26 INFO - PROCESS | 2624 | Values: 297.5 302.8 306.0 311.6 314.8 4644.2 4762.6 4820.5 4885.5 4890.0 4748.2 4837.6 4840.8 4850.5 4880.1 3797.1 3804.5 3806.8 3830.5 3839.3 3747.9 3816.5 3842.8 3842.9 3858.1 3781.7 3801.2 3805.9 3813.7 3814.5 3785.5 3806.5 3820.6 3823.5 3831.6 3375.4 3396.8 3401.4 3412.9 3422.0 4408.6 4669.7 4721.2 4722.7 4732.4 4338.2 4341.2 4364.5 4372.2 4381.5 5045.8 5054.3 5062.7 5144.6 5147.3 3655.6 3695.5 3703.3 3725.2 3734.6 5281.0 5313.1 5324.7 5325.8 5325.9 3830.1 3839.8 3839.9 3848.2 3865.0 3911.4 3915.6 3921.5 3923.7 3949.2 4240.2 4365.5 4392.3 4393.0 4394.2 5042.9 5058.3 5067.5 5072.9 5140.7 1269.1 1316.5 1323.3 1341.4 1344.2 862.1 868.6 888.7 891.2 895.2 3954.3 3975.4 3984.5 4009.2 4013.0 4525.4 4564.5 4585.6 4624.5 4631.5 3935.2 3953.9 3957.9 3979.9 4038.9 2362.6 2445.9 2448.8 2455.2 2473.9 4269.6 4334.2 4339.0 4369.7 4392.8 4603.3 4637.3 4663.7 4666.2 4683.5 4032.8 4234.3 4261.7 4327.5 4352.7 4498.1 4505.1 4530.8 4558.2 4566.4 4461.3 4465.2 4471.5 4493.3 4507.2 4111.6 4197.1 4291.7 4297.3 4328.5 3382.8 3401.2 3407.9 3409.9 3426.1 4559.5 4577.9 4595.4 4633.1 5080.0 4865.7 5037.1 5080.8 5095.6 5100.6 5053.3 5060.8 5070.7 5074.9 5096.5 4834.0 4972.8 5016.2 5016.8 5067.7 5129.8 5147.9 5150.5 5151.4 5168.3 4920.0 4931.9 4933.6 4959.9 4987.4 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - PROCESS | 2624 | [#5] yui.html Cycles:180 Average:12561.76 Median:598.60 stddev:15193.10 (2538.1%) stddev-sans-first:15209.46 18:09:26 INFO - PROCESS | 2624 | Values: 667.3 681.7 682.0 687.1 697.1 11070.3 11323.2 11730.6 11774.2 11777.2 12762.9 13026.0 13175.9 13246.7 13267.9 623.7 758.8 760.2 763.7 770.8 773.6 795.1 796.7 801.8 802.5 822.3 830.2 832.5 835.8 837.9 824.2 827.2 827.7 828.5 830.2 604.6 605.5 607.5 607.6 608.3 11215.4 11259.8 11262.9 11281.2 11290.2 10508.4 10841.9 10934.4 11079.3 11107.9 8883.0 9022.0 9097.6 9203.1 9432.2 3109.5 3187.8 3258.0 3273.5 3295.6 45401.1 45575.5 45751.3 46061.8 46186.0 8876.9 9001.7 9002.6 9068.1 9229.0 16112.8 16144.4 16188.5 16251.3 16259.1 6026.2 6215.8 6218.1 6309.5 6342.5 3401.8 3449.4 3453.8 3466.7 3501.4 2642.7 2730.1 2733.2 2734.3 2734.7 517.8 519.1 531.8 532.2 544.5 473.5 481.8 534.3 573.6 614.4 7335.7 7517.9 7704.3 7786.6 7930.4 474.4 587.7 591.3 596.8 600.4 3655.8 3661.2 3683.5 3747.6 3792.4 55582.7 55656.0 55916.4 56436.4 56603.0 48072.5 48982.5 49059.3 49769.5 50075.7 44682.6 45133.5 45162.8 45598.0 46099.0 7126.4 7211.1 7305.0 7307.4 7372.4 29586.4 29734.3 30034.4 30832.2 30991.0 37296.6 39024.6 39461.4 39725.4 40468.6 8601.2 8734.9 8818.6 8838.2 8874.6 9687.7 9795.4 9836.9 9931.9 10100.2 9019.1 9078.9 9095.6 9221.7 9317.3 9202.7 9261.0 9368.0 9521.9 9567.0 8656.7 9035.2 9748.7 10123.1 10228.8 8776.7 8983.2 9160.4 9167.0 9241.8 8940.3 9055.8 9183.4 9221.1 9265.3 18:09:26 INFO - PROCESS | 2624 | -------- Summary: end -------- 18:09:26 INFO - PROCESS | 2624 | 18:09:26 INFO - TEST-INFO | 2624: exit 0 18:09:26 INFO - TEST-OK | dromaeo_css | took 1114216ms 18:09:26 INFO - TEST-START | kraken 18:09:26 INFO - operating with platform_type : linux_ 18:09:26 INFO - Initialising browser for kraken test... 18:09:26 INFO - TEST-INFO | started process 2960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6XjYC/profile http://localhost:34673/getInfo.html) 18:09:31 INFO - PROCESS | 2960 | __metrics Screen width/height:1600/1200 18:09:31 INFO - PROCESS | 2960 | colorDepth:24 18:09:31 INFO - PROCESS | 2960 | Browser inner width/height: 1024/697 18:09:31 INFO - PROCESS | 2960 | __metrics 18:09:32 ERROR - PROCESS | 2960 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 18:09:32 INFO - TEST-INFO | 2960: exit 0 18:09:32 INFO - Browser initialized. 18:09:32 INFO - Running cycle 1/1 for kraken test... 18:09:32 INFO - TEST-INFO | started process 3079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6XjYC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:44 INFO - PROCESS | 3079 | RSS: Main: 167354368 18:09:44 INFO - PROCESS | 3079 | 18:09:47 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34673/tests/kraken/driver.html?testName=audio-beat-detection) 18:09:48 INFO - PROCESS | 3079 | RSS: Main: 172146688 18:09:48 INFO - PROCESS | 3079 | 18:09:50 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34673/tests/kraken/driver.html?testName=audio-dft) 18:09:51 INFO - PROCESS | 3079 | RSS: Main: 172716032 18:09:51 INFO - PROCESS | 3079 | 18:09:54 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34673/tests/kraken/driver.html?testName=audio-fft) 18:09:55 INFO - PROCESS | 3079 | RSS: Main: 167010304 18:09:55 INFO - PROCESS | 3079 | 18:09:57 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34673/tests/kraken/driver.html?testName=audio-oscillator) 18:09:57 INFO - PROCESS | 3079 | RSS: Main: 167469056 18:09:57 INFO - PROCESS | 3079 | 18:09:59 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34673/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:10:00 INFO - PROCESS | 3079 | RSS: Main: 167907328 18:10:00 INFO - PROCESS | 3079 | 18:10:03 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34673/tests/kraken/driver.html?testName=imaging-darkroom) 18:10:04 INFO - PROCESS | 3079 | RSS: Main: 168529920 18:10:04 INFO - PROCESS | 3079 | 18:10:07 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34673/tests/kraken/driver.html?testName=imaging-desaturate) 18:10:08 INFO - PROCESS | 3079 | RSS: Main: 165634048 18:10:08 INFO - PROCESS | 3079 | 18:10:11 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34673/tests/kraken/driver.html?testName=json-parse-financial) 18:10:12 INFO - PROCESS | 3079 | RSS: Main: 167124992 18:10:12 INFO - PROCESS | 3079 | 18:10:13 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34673/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:10:14 INFO - PROCESS | 3079 | RSS: Main: 167522304 18:10:14 INFO - PROCESS | 3079 | 18:10:16 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:10:16 INFO - PROCESS | 3079 | RSS: Main: 167612416 18:10:16 INFO - PROCESS | 3079 | 18:10:18 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:10:19 INFO - PROCESS | 3079 | RSS: Main: 168046592 18:10:19 INFO - PROCESS | 3079 | 18:10:22 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:10:23 INFO - PROCESS | 3079 | RSS: Main: 168267776 18:10:23 INFO - PROCESS | 3079 | 18:10:25 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:10:26 INFO - PROCESS | 3079 | RSS: Main: 168116224 18:10:26 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | Cycle 1(1): loaded http://localhost:34673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34673/tests/kraken/driver.html?testName=ai-astar) 18:10:28 INFO - PROCESS | 3079 | RSS: Main: 168878080 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | __start_tp_report 18:10:28 INFO - PROCESS | 3079 | _x_x_mozilla_page_load 18:10:28 INFO - PROCESS | 3079 | _x_x_mozilla_page_load_details 18:10:28 INFO - PROCESS | 3079 | |i|pagename|runs| 18:10:28 INFO - PROCESS | 3079 | |0;ai-astar;116;110;117;119;114;117;115;114;115;116 18:10:28 INFO - PROCESS | 3079 | |1;audio-beat-detection;160;135;161;189;138;162;160;142;186;137 18:10:28 INFO - PROCESS | 3079 | |2;audio-dft;163;166;166;165;167;182;169;165;165;164 18:10:28 INFO - PROCESS | 3079 | |3;audio-fft;78;79;81;154;80;78;77;77;156;78 18:10:28 INFO - PROCESS | 3079 | |4;audio-oscillator;85;87;130;87;88;87;129;87;88;129 18:10:28 INFO - PROCESS | 3079 | |5;imaging-gaussian-blur;121;119;119;118;119;119;119;120;119;119 18:10:28 INFO - PROCESS | 3079 | |6;imaging-darkroom;150;154;149;151;150;151;150;151;149;149 18:10:28 INFO - PROCESS | 3079 | |7;imaging-desaturate;97;96;96;98;97;97;95;96;97;96 18:10:28 INFO - PROCESS | 3079 | |8;json-parse-financial;64;64;64;64;63;63;64;66;64;64 18:10:28 INFO - PROCESS | 3079 | |9;json-stringify-tinderbox;66;65;64;66;66;67;65;66;66;66 18:10:28 INFO - PROCESS | 3079 | |10;stanford-crypto-aes;92;92;90;87;88;88;91;88;122;87 18:10:28 INFO - PROCESS | 3079 | |11;stanford-crypto-ccm;149;143;148;141;137;146;138;146;155;146 18:10:28 INFO - PROCESS | 3079 | |12;stanford-crypto-pbkdf2;174;174;175;174;175;175;173;172;174;172 18:10:28 INFO - PROCESS | 3079 | |13;stanford-crypto-sha256-iterative;68;71;69;68;68;69;71;71;68;68 18:10:28 INFO - PROCESS | 3079 | __end_tp_report 18:10:28 INFO - PROCESS | 3079 | __start_cc_report 18:10:28 INFO - PROCESS | 3079 | _x_x_mozilla_cycle_collect,845 18:10:28 INFO - PROCESS | 3079 | __end_cc_report 18:10:28 INFO - PROCESS | 3079 | __startTimestamp1465348228424__endTimestamp 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | ------- Summary: start ------- 18:10:28 INFO - PROCESS | 3079 | Number of tests: 14 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:2.41 (2.1%) stddev-sans-first:2.54 18:10:28 INFO - PROCESS | 3079 | Values: 116.0 110.0 117.0 119.0 114.0 117.0 115.0 114.0 115.0 116.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#1] audio-beat-detection Cycles:10 Average:157.00 Median:160.50 stddev:19.42 (12.1%) stddev-sans-first:20.57 18:10:28 INFO - PROCESS | 3079 | Values: 160.0 135.0 161.0 189.0 138.0 162.0 160.0 142.0 186.0 137.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#2] audio-dft Cycles:10 Average:167.20 Median:166.00 stddev:5.45 (3.3%) stddev-sans-first:5.57 18:10:28 INFO - PROCESS | 3079 | Values: 163.0 166.0 166.0 165.0 167.0 182.0 169.0 165.0 165.0 164.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#3] audio-fft Cycles:10 Average:93.80 Median:78.00 stddev:32.28 (41.4%) stddev-sans-first:33.73 18:10:28 INFO - PROCESS | 3079 | Values: 78.0 79.0 81.0 154.0 80.0 78.0 77.0 77.0 156.0 78.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#4] audio-oscillator Cycles:10 Average:99.70 Median:87.00 stddev:20.47 (23.5%) stddev-sans-first:21.01 18:10:28 INFO - PROCESS | 3079 | Values: 85.0 87.0 130.0 87.0 88.0 87.0 129.0 87.0 88.0 129.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#5] imaging-gaussian-blur Cycles:10 Average:119.20 Median:119.00 stddev:0.79 (0.7%) stddev-sans-first:0.50 18:10:28 INFO - PROCESS | 3079 | Values: 121.0 119.0 119.0 118.0 119.0 119.0 119.0 120.0 119.0 119.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.50 stddev:1.51 (1.0%) stddev-sans-first:1.59 18:10:28 INFO - PROCESS | 3079 | Values: 150.0 154.0 149.0 151.0 150.0 151.0 150.0 151.0 149.0 149.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 18:10:28 INFO - PROCESS | 3079 | Values: 97.0 96.0 96.0 98.0 97.0 97.0 95.0 96.0 97.0 96.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:10:28 INFO - PROCESS | 3079 | Values: 64.0 64.0 64.0 64.0 63.0 63.0 64.0 66.0 64.0 64.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 18:10:28 INFO - PROCESS | 3079 | Values: 66.0 65.0 64.0 66.0 66.0 67.0 65.0 66.0 66.0 66.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:89.00 stddev:10.54 (11.8%) stddev-sans-first:11.18 18:10:28 INFO - PROCESS | 3079 | Values: 92.0 92.0 90.0 87.0 88.0 88.0 91.0 88.0 122.0 87.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:146.00 stddev:5.38 (3.7%) stddev-sans-first:5.50 18:10:28 INFO - PROCESS | 3079 | Values: 149.0 143.0 148.0 141.0 137.0 146.0 138.0 146.0 155.0 146.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:174.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 18:10:28 INFO - PROCESS | 3079 | Values: 174.0 174.0 175.0 174.0 175.0 175.0 173.0 172.0 174.0 172.0 18:10:28 INFO - PROCESS | 3079 | 18:10:28 INFO - PROCESS | 3079 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.37 (2.0%) stddev-sans-first:1.39 18:10:28 INFO - PROCESS | 3079 | Values: 68.0 71.0 69.0 68.0 68.0 69.0 71.0 71.0 68.0 68.0 18:10:28 INFO - PROCESS | 3079 | -------- Summary: end -------- 18:10:28 INFO - PROCESS | 3079 | 18:10:29 INFO - TEST-INFO | 3079: exit 0 18:10:29 INFO - TEST-OK | kraken | took 62660ms 18:10:29 INFO - SUITE-END | took 1176s 18:10:29 INFO - Completed test suite (00:19:37) 18:10:29 INFO - javascript benchmark 18:10:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6141.498433309158, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.1594981630974, 2601.6877974643044, 2631.329011102168, 2638.287662331171, 2655.8804853781567, 4523.592876641102, 4524.63802895768, 4590.495045544987, 4617.2150734375155, 4656.022235330576, 4500.009997800478, 4637.976810115944, 4639.396878405811, 4668.9766551167195, 4672.626189904808, 4108.917821643566, 4276.337167738998, 4345.0, 4374.671899607542, 4406.5593440656, 4310.8145260053425, 4366.2359087159775, 4384.0355121873135, 4396.89007774805, 4404.911901761979, 4116.629503344683, 4125.174965006996, 4160.958390416087, 4226.239276930151, 4229.619334259932, 4204.453421055274, 4268.573142685737, 4273.807678654437, 4321.222180007598, 4321.805518751664, 4229.70392072555, 4245.893852653678, 4264.893377665552, 4277.165952639237, 4330.090680957002, 4441.622462090726, 4542.568455996675, 4572.977135114336, 4576.45082590088, 4599.172149013176, 4975.776090075955, 4980.12847751642, 4996.0, 5011.949880501221, 5029.547340739351, 4864.075825593126, 4992.276119962585, 5015.6959190610705, 5036.471170527078, 5087.694738315713, 4665.370175026328, 4683.133620280225, 4696.506866778939, 4718.197906355927, 4728.71627702339, 5154.793808247703, 5163.380394352626, 5171.715555644476, 5187.325647665854, 5199.012187684328, 4691.906161876744, 4710.552497512731, 4863.367762190893, 4963.0, 4978.77595508203, 4874.658773885794, 4882.0, 5010.123228435083, 5045.47022562633, 5058.696478211319, 4897.902041959141, 4960.776765045582, 4962.503749625081, 4970.055689418999, 5028.019536190481, 4721.811127554928, 4906.681065730716, 4920.827771027997, 4943.72809495474, 4958.553730164303, 4241.27898257297, 4262.3180291153885, 4279.0, 4301.075268817157, 4312.0, 3713.665770080652, 3729.9440508392395, 3734.7945862977253, 3757.5866654667966, 3768.491253680718, 4303.2469317869245, 4367.978160109179, 4382.9919118602265, 4387.100644367897, 4402.515723270438, 4980.601551875841, 5056.848294551096, 5061.670991385548, 5073.670211436245, 5087.872803179876, 4235.322348424237, 4356.346548017759, 4363.36731173978, 4398.252297109499, 4418.7127836690515, 4469.642428605704, 4540.659450541222, 4543.61379282758, 4547.658925580528, 4583.175028494837, 4698.553637404373, 4735.692180008288, 4765.404324459442, 4770.379850619397, 4808.374911261513, 4842.539958703917, 4959.280904268933, 5022.4977502249485, 5026.572741317029, 5079.238114282739, 4682.555157260055, 4684.320773487757, 4716.929246061243, 4760.309755085424, 4793.880152996203, 4918.6065114791445, 4932.728699921395, 4963.6525443218625, 4964.553190212754, 4965.702057876539, 4838.395200599925, 4915.0, 4928.0390323886495, 5036.169032109661, 5074.873128171826, 4667.416572928384, 4741.383620129361, 4835.960268542281, 4849.612031037579, 4929.679570827885, 4847.006363695505, 4851.46633870281, 4868.488808675038, 4868.586170175433, 4882.267659850909, 4543.613792827515, 4549.840755573539, 4787.808487660454, 4804.25534042224, 4807.351007613927, 4772.475027746876, 4799.520047995312, 4817.614590832795, 4820.734859582757, 4914.238293064581, 4812.037592481448, 4834.347363106076, 4838.2016967201425, 4869.123557759495, 4884.194107972145], "value": 4578.020463652703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.4443478738655, 564.5088772767691, 566.7477972302327, 579.4292621767561, 616.183556787257, 34681.30637387251, 38471.80764096179, 38506.614933850615, 38607.80696096522, 38643.0, 39290.0, 39986.200275994466, 40131.39802902959, 40164.39753403694, 40319.798401008025, 7269.89095163573, 7398.334149926505, 7403.889416587501, 7417.703291868319, 7466.104067511907, 7551.848963020736, 7591.658375373094, 7594.658240379169, 7627.923720762777, 7651.273129052731, 7560.206178351276, 7587.317141457295, 7604.011478507814, 7613.162552119263, 7645.388368930472, 7537.321641052305, 7567.243275672444, 7645.655945482439, 7653.0, 7668.884966725504, 7532.849343013164, 7631.923680763232, 7650.540967541965, 7668.578228197447, 7701.499402538817, 36441.63558364409, 37381.813090934644, 37394.25211495782, 37502.4374634378, 37663.43504847402, 3116.8597413116236, 3253.4957081652383, 3347.916302092467, 3387.2548039431285, 3721.5348081489815, 28728.281792955135, 29223.269418264714, 29247.707522924713, 29270.121896343142, 29313.241205527524, 3345.1804307944467, 3350.0, 3408.829558522064, 3511.543499345069, 3512.59605145409, 255558.0, 257912.0, 259411.0, 261194.0, 262931.0, 30806.53790193149, 46274.5372546277, 46602.766986164854, 46977.0, 47097.76451117791, 31299.687003130133, 33274.3345133096, 33285.334293314, 33539.161520962174, 33846.0, 12552.0, 12562.555306139728, 12604.0, 12641.367931603567, 12671.366431678563, 10387.220958428148, 10390.532426040847, 10408.739781505372, 10428.061474467335, 10440.0, 7886.132525422314, 7894.763157105296, 7903.0, 7912.0, 7968.880466793003, 3118.8596513156963, 3119.0175094845504, 3124.42198193335, 3135.294558724269, 3135.8275294859, 841.6843683618642, 846.2341580869323, 894.5348418822306, 1039.6361273554196, 1049.7270709615555, 25431.87284063605, 26368.472630547283, 26473.544368039915, 27696.723032769416, 27783.72216277852, 975.2685485885586, 991.9156871665845, 1002.1631937332409, 1048.1614708233383, 1050.4747626186906, 14279.500217492337, 14685.48600798967, 15058.32237549313, 15484.30320635574, 15954.0, 383566.0, 386839.0, 387025.0, 389103.0, 390776.0, 49148.0, 49535.752321238164, 49593.0, 49697.0, 49949.750251249236, 1196.86563405734, 1217.3183017510223, 1221.5052903574067, 1230.5323976888726, 1236.6970092327617, 27468.313292167855, 28722.0, 28824.567631485126, 28980.27549311284, 29274.707252927197, 42222.0, 42966.78516607397, 43080.0, 43103.7844810774, 43130.78434607807, 352682.0, 356859.0, 357386.0, 357951.0, 360704.0, 24513.877430612734, 25101.0, 25337.366565836, 25431.745682542936, 25480.108196213045, 40641.39037914375, 41423.7928810354, 41546.79226603848, 42286.0, 42290.788546057076, 35398.0, 35577.644223557436, 35588.64411355957, 35715.0, 35874.64125358713, 34762.65237347594, 35772.821135894155, 35917.46123808093, 36404.089897751706, 36453.0, 29651.2209267436, 33698.49452258169, 33969.83015085007, 34638.480422793175, 35046.0, 30679.2330191747, 32000.199995000312, 32074.358512829145, 32262.19344516406, 32284.35431291314, 24366.878165609058, 24522.509549808547, 25061.122860699787, 25296.367590810376, 25378.61932071057], "value": 16327.220538824695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.3549610700738, 362.9419292913134, 364.84858783604795, 373.1101323343824, 377.79410221429316, 15225.238738063093, 16242.187890605466, 17048.914755426205, 17067.91466042671, 17209.139543022844, 15715.84284157158, 15991.520254392386, 16611.169441527905, 16738.0, 16838.0, 4826.638002149844, 5012.248162775594, 5012.624053196007, 5016.072026675059, 5049.621278404116, 3686.31803116423, 4954.777035033432, 5511.283533140706, 5840.182374467557, 5841.15303281024, 5782.739776710058, 5886.617369870966, 5891.55813314001, 5919.348871624118, 5948.1375200595885, 5641.971790141043, 5893.0, 5903.557233207526, 5904.793332233394, 5931.614445061057, 5091.210862316347, 5133.332666753298, 5139.922901156486, 5221.973890130525, 5886.234789477383, 20351.389458316273, 21031.68452473214, 21571.67642485364, 22840.54318913629, 23082.42293942648, 1917.2906024770784, 2007.3576455534235, 2041.9688057531, 2085.051301657742, 2112.904919278636, 13278.402471888789, 13370.799438008438, 13544.59366219015, 13604.727905441934, 14023.579292621238, 1883.8587105967108, 1905.7427247321573, 1951.9121639526113, 1958.7453631027875, 1963.2932144427994, 70080.0, 73212.0, 75628.62185689091, 76103.61948190279, 76522.0, 15543.689126217412, 16490.505284841474, 16634.833651663328, 16770.496885093467, 17444.040577768345, 28338.716612833607, 28429.289267768472, 28437.289067773057, 28659.0, 28725.0, 8416.747497575083, 8848.513331766686, 8856.24721898633, 8938.821223575493, 8989.775255618531, 6802.489813263926, 6855.7600483983815, 6879.965600171967, 6899.310068993161, 7060.646967651597, 8179.877301840477, 8291.419600627898, 8397.992240931006, 8431.747047588582, 8463.788405289793, 1521.7413039783041, 1621.2218135295125, 1624.16355576878, 1630.4945466905297, 3292.736581073508, 860.6514361683528, 875.2735229759285, 902.0618556700957, 919.6551293265026, 924.8751418558543, 14191.858081419261, 14638.560843174722, 15894.364225430852, 16194.947328423616, 16414.507564772837, 842.650300125441, 890.0298674444886, 894.561664784251, 898.6270697660528, 937.4234845569925, 8934.721645980364, 9222.769430764285, 9276.304277179239, 9415.62337506492, 9516.524173791282, 381470.0, 382141.0, 391426.0, 392118.0, 396244.0, 72891.27108728951, 73409.26590734025, 74259.62870185614, 74877.6256118716, 76228.0, 908.4594666173616, 908.6955869783565, 911.9544022798701, 917.5687426909342, 924.9861252081224, 14337.56987290383, 14638.634034149232, 15460.0, 15713.109795404482, 16045.315241899432, 23735.0, 24373.75626243786, 33462.0, 33961.49057764185, 35119.99080262113, 45627.0, 47151.0, 47162.29256561086, 47200.29199561941, 47707.0, 7062.0, 7132.8216794578475, 7136.85726285461, 7173.426125910019, 7275.308845659654, 9077.77305567366, 9213.953930230575, 9684.612615495302, 9882.802343952937, 10208.081272685495, 8979.371443998858, 9003.324750643596, 9085.90914090877, 9096.317776166681, 9294.860577091215, 9008.549572521479, 9038.231750301298, 9122.133397327243, 9333.206677432494, 9417.199538039344, 9180.944191417806, 9270.258379329773, 9351.158395744416, 9425.293103017164, 9666.903330966601, 7042.43660507148, 7056.15326160864, 7352.742654007084, 7403.888941665771, 7414.036175297177, 8468.534230617375, 9114.908850911406, 9161.908380916106, 9326.114019168168, 9460.716178514656], "value": 8600.686409788816, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.048040170876, 1375.9724805503888, 1395.4209003263645, 1657.5027491752464, 1683.5538582275692, 6584.6707664616815, 7072.611006394646, 7280.126384833828, 7457.291557302047, 7462.365698915592, 7545.773626791191, 7649.661309270869, 7666.501677390966, 7678.692852285902, 7835.4906931049445, 2180.291405293278, 2208.9949073171747, 2209.5139069404704, 2225.2100504320897, 2226.955460890781, 2187.824974002076, 2200.966985495219, 2205.724284464442, 2213.015208232337, 2217.66734989752, 2144.02446886667, 2150.193677370986, 2152.6878602602615, 2154.095279982403, 2154.6121698094335, 2144.356692992104, 2146.1844499090325, 2147.795959383856, 2148.957020859582, 2157.374361435182, 2089.341857314941, 2127.659574468093, 2140.3578926322043, 2154.515234072337, 2167.0898222746328, 7665.3101220890385, 7674.769756907302, 7688.5771282579435, 7689.692412303501, 7711.190325015905, 4382.737035777832, 4430.556944305576, 4468.486124095705, 4488.214562451558, 4493.303537951622, 2849.7292757188034, 2854.9714502854913, 2913.737763601286, 2960.955585666217, 2973.6431628204537, 916.5921165081542, 943.3915124744501, 948.2650945517211, 984.6996666016862, 984.8916619171886, 10403.47982600867, 10456.372617642966, 10479.528421220986, 10521.368717876952, 10564.841527377095, 4217.62041416274, 4229.873103806891, 4313.698041137153, 4321.546237645065, 4351.216780979391, 8832.690855820016, 8937.776555586031, 8942.86585701215, 8998.965119011267, 9152.633894644272, 4392.462638076648, 4431.556844315607, 4472.485664148599, 4499.302608095751, 4522.208613492626, 3619.2399596085115, 4024.939625905614, 4027.5166979962005, 4029.879103626896, 4137.338025915899, 3633.454981752758, 3641.1807343347537, 3682.0978860179425, 3686.8340924658455, 3745.9438108428394, 1195.6054502014315, 1215.003696968495, 1215.4469716279073, 1217.257472941505, 1223.2477026628737, 846.5682501924063, 849.0321034021221, 855.4952577978897, 860.1054902901038, 891.9866202006975, 5151.201563757623, 5186.662866913714, 5251.36983561975, 5282.52457278839, 5313.973430132824, 398.4799836213769, 416.09914535031925, 416.6916481803504, 420.2624394188241, 432.09476147470997, 3563.1092226943265, 3621.420572708407, 3679.3193259247123, 3729.4778730977687, 3743.513343265358, 9022.458652480873, 9359.532023398802, 9360.765980850398, 9421.199198068103, 9460.337776355589, 9078.228350590141, 9224.30817688676, 9274.953625231965, 9323.627054917863, 9379.296552758571, 6938.202106757686, 7089.149302083783, 7195.172555156119, 7203.315685009916, 7207.495475316782, 5013.974930125326, 5080.847574572768, 5235.63873392913, 5257.894842103213, 5281.762320695655, 8654.264387527131, 8656.610452529525, 8676.08901065379, 8751.0, 8900.243479304332, 8271.793205169919, 8514.276286515715, 8630.439021463584, 8706.0, 8720.345974051845, 5944.256967879015, 6376.52176086786, 6501.382368674968, 6703.396694297537, 6704.128463299739, 7267.745628902963, 7280.053593033083, 7324.560526368435, 7356.889646655198, 7385.261473852572, 6572.112764776885, 6672.466202703869, 6703.798886033427, 6736.225334086446, 6742.325767423218, 6456.935430645822, 6515.185601799775, 6552.868942621216, 6562.868742625025, 6580.21037475506, 5880.558958078247, 5904.261967254093, 5906.822795316148, 5923.437273459014, 5941.435563621449, 4534.183846907588, 4659.836905708284, 4667.32323813052, 4674.252119660838, 4730.574248317668, 4639.30410438437, 4643.141018911512, 4711.1519926413575, 4714.0, 4760.547747963938], "value": 4011.3362536087443, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.52990275364937, 302.8243618701153, 305.9724624783769, 311.6104868913858, 314.778081452576, 4644.233701439267, 4762.595179409755, 4820.493848145947, 4885.535874091965, 4889.951100488998, 4748.169070412681, 4837.5646191842725, 4840.830570930018, 4850.5391987761195, 4880.072786170634, 3797.1076796952693, 3804.543454785423, 3806.809659517027, 3830.5403351597847, 3839.2705385976715, 3747.906302342436, 3816.503854498911, 3842.846286148551, 3842.903927401809, 3858.054776579742, 3781.7163712721517, 3801.2397520495874, 3805.923881522368, 3813.6758375537975, 3814.5231846019246, 3785.5078839750795, 3806.505154329947, 3820.560635526894, 3823.5220597425323, 3831.578526362098, 3375.4261775498226, 3396.779209351409, 3401.387750204987, 3412.8805491807916, 3421.9657803421896, 4408.647308215335, 4669.743164125972, 4721.24460086388, 4722.693024953402, 4732.361131247272, 4338.2191205582985, 4341.218580655481, 4364.519902810688, 4372.2130016597, 4381.518033016366, 5045.754639185564, 5054.3176671149295, 5062.683702237445, 5144.588432925357, 5147.330846989905, 3655.5796083450477, 3695.519582454264, 3703.2778608171416, 3725.1990821973404, 3734.589195188527, 5281.0, 5313.123334649778, 5324.668832791802, 5325.786968521294, 5325.866853328659, 3830.138218900725, 3839.788811615388, 3839.9232015359535, 3848.249591329664, 3865.014421322601, 3911.393733971239, 3915.647591716702, 3921.470601468822, 3923.7449565778134, 3949.2496425679606, 4240.194363071007, 4365.541618130051, 4392.253316936129, 4393.0, 4394.231009573312, 5042.899142017139, 5058.266551350107, 5067.543921047123, 5072.873178170502, 5140.717260550706, 1269.1116218646985, 1316.4668309334734, 1323.305264736002, 1341.4097796969302, 1344.2136350235025, 862.0818827948215, 868.5961028121953, 888.657866721317, 891.2246345679171, 895.1764376773384, 3954.268460334847, 3975.3502296547185, 3984.5456408410723, 4009.1981603679383, 4013.0, 4525.411696479437, 4564.452265728134, 4585.610223131004, 4624.5144259852905, 4631.475845664121, 3935.153941902505, 3953.9439491464277, 3957.8627541127466, 3979.9403008954887, 4038.8788336349958, 2362.64560315954, 2445.914392996272, 2448.816338774599, 2455.1529722245796, 2473.8639374834556, 4269.566347182138, 4334.179644549296, 4339.0, 4369.650427965818, 4392.846250381222, 4603.286490593963, 4637.25803871379, 4663.673542852036, 4666.160091183551, 4683.5082316356975, 4032.8185231664647, 4234.348604044425, 4261.722988005832, 4327.5239723630375, 4352.651787856964, 4498.122866041091, 4505.130370896041, 4530.796114174936, 4558.247889098262, 4566.383538222298, 4461.330800379969, 4465.218586747371, 4471.463424389094, 4493.303537951622, 4507.188706032945, 4111.588841115865, 4197.139586384723, 4291.67812414064, 4297.333913243452, 4328.502222244356, 3382.780119292238, 3401.1837159081147, 3407.9318413632086, 3409.9147521312166, 3426.1434641339665, 4559.452865656142, 4577.862664120082, 4595.402597662416, 4633.058008164772, 5079.974600127123, 4865.683730557503, 5037.143685573388, 5080.771365288645, 5095.617828662791, 5100.591952643706, 5053.2818841594035, 5060.772265247999, 5070.721110338966, 5074.923876141787, 5096.541311282002, 4833.951660483491, 4972.825951091694, 5016.194170098835, 5016.799328026838, 5067.695938243717, 5129.846104616868, 5147.948520514747, 5150.48495150482, 5151.43644863554, 5168.328117344721, 4919.950800491949, 4931.876703082452, 4933.55597996182, 4959.876003099807, 4987.376577927759], "value": 3695.6382216732222, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.252677001758, 681.6796105830258, 681.9863602727944, 687.1342108942731, 697.1181455458845, 11070.28043177194, 11323.20737548372, 11730.648080557576, 11774.175807693466, 11777.175597708163, 12762.936185319084, 13026.02304827137, 13175.93412032941, 13246.735065298688, 13267.867321326783, 623.7193263031631, 758.8292634157326, 760.2435576601275, 763.6601712238056, 770.7803276066294, 773.5668025905483, 795.1134485049187, 796.6693822063836, 801.7594721583531, 802.5104686141449, 822.2599660305713, 830.2030051150872, 832.546262287055, 835.8035861572525, 837.9455335403179, 824.2458150792048, 827.230675471814, 827.668932427028, 828.5153185386556, 830.1573902488979, 604.552631053022, 605.5125623872825, 607.4623957797337, 607.5625549604277, 608.3247595169358, 11215.439228038565, 11259.831102533468, 11262.887371126266, 11281.210315277935, 11290.20968532203, 10508.369497830077, 10841.891581084166, 10934.398608076554, 11079.335239885633, 11107.944460277648, 8882.955585222033, 9022.005132219061, 9097.63609455621, 9203.079692030811, 9432.151106400457, 3109.54911537828, 3187.7609179311644, 3258.006308076036, 3273.492608645664, 3295.6374798772117, 45401.091978160584, 45575.54424455746, 45751.313730294074, 46061.76969115166, 46186.0, 8876.8668469973, 9001.684941027164, 9002.639894404281, 9068.138526839939, 9229.0, 16112.838871611368, 16144.434944776876, 16188.514344569681, 16251.349946002027, 16259.10574918391, 6026.246719160105, 6215.751369945242, 6218.098375735493, 6309.463695585835, 6342.460890824332, 3401.829908504614, 3449.3618680543677, 3453.8445769940413, 3466.6533346665133, 3501.439769636846, 2642.735726427342, 2730.071775596307, 2733.166384252822, 2734.3164208947765, 2734.6991830898587, 517.76700484782, 519.1019536202391, 531.8271561742449, 532.1937265043463, 544.4881811097556, 473.4697139204113, 481.7831975610989, 534.2547146730367, 573.5698226330252, 614.4285814192843, 7335.686301840402, 7517.924820751722, 7704.30661240491, 7786.610669466504, 7930.405219608552, 474.43305250226337, 587.6532845621105, 591.2845456996996, 596.832886791699, 600.3756093662629, 3655.83013435698, 3661.212839239576, 3683.4658974448553, 3747.6252374762853, 3792.4311353297226, 55582.72208638931, 55656.0, 55916.44083559112, 56436.43563564394, 56603.0, 48072.519274807506, 48982.51017489851, 49059.26411103836, 49769.502304976486, 50075.74962125166, 44682.55317446784, 45133.54866451359, 45162.77418612886, 45598.0, 46099.0, 7126.358627723323, 7211.0869087349965, 7304.96347518259, 7307.378872795872, 7372.410207183305, 29586.408271834873, 29734.25664358408, 30034.3993120132, 30832.229194270323, 30991.0, 37296.6270337304, 39024.6097539021, 39461.40807887827, 39725.40411893766, 40468.595314046484, 8601.22588966996, 8734.868976965477, 8818.5590720465, 8838.204561589488, 8874.600642970952, 9687.66093186735, 9795.412275263507, 9836.901630983599, 9931.900680993098, 10100.24248181404, 9019.098090190928, 9078.863817042882, 9095.590698418719, 9221.677241296522, 9317.301202409712, 9202.677906273248, 9260.953695231481, 9368.0, 9521.857172142561, 9566.952165239129, 8656.749245196168, 9035.232005279624, 9748.658796942073, 10123.088921997056, 10228.795424091626, 8776.692815751418, 8983.236424903957, 9160.35877488596, 9167.0, 9241.815163696823, 8940.284777217677, 9055.773605659911, 9183.403078799856, 9221.077892210993, 9265.258779297776], "value": 5629.811738113228, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 117.0, 119.0, 114.0, 117.0, 115.0, 114.0, 115.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 135.0, 161.0, 189.0, 138.0, 162.0, 160.0, 142.0, 186.0, 137.0], "value": 157.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 166.0, 165.0, 167.0, 182.0, 169.0, 165.0, 165.0, 164.0], "value": 167.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 81.0, 154.0, 80.0, 78.0, 77.0, 77.0, 156.0, 78.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 130.0, 87.0, 88.0, 87.0, 129.0, 87.0, 88.0, 129.0], "value": 99.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 119.0, 118.0, 119.0, 119.0, 119.0, 120.0, 119.0, 119.0], "value": 119.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 149.0, 151.0, 150.0, 151.0, 150.0, 151.0, 149.0, 149.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 98.0, 97.0, 97.0, 95.0, 96.0, 97.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 63.0, 63.0, 64.0, 66.0, 64.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 90.0, 87.0, 88.0, 88.0, 91.0, 88.0, 122.0, 87.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 143.0, 148.0, 141.0, 137.0, 146.0, 138.0, 146.0, 155.0, 146.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 175.0, 174.0, 175.0, 175.0, 173.0, 172.0, 174.0, 172.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 69.0, 68.0, 68.0, 69.0, 71.0, 71.0, 68.0, 68.0], "value": 69.1, "unit": "score"}]}]} 18:10:29 INFO - Return code: 0 18:10:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:10:29 ERROR - # TBPL SUCCESS # 18:10:29 INFO - Running post-action listener: _resource_record_post_action 18:10:29 INFO - ##### 18:10:29 INFO - ##### Finished run-tests step (success) 18:10:29 INFO - ##### 18:10:29 INFO - Running post-run listener: _resource_record_post_run 18:10:29 INFO - Total resource usage - Wall time: 1194s; CPU: 26.0%; Read bytes: 4096; Write bytes: 525737984; Read time: 12; Write time: 591152 18:10:29 INFO - TinderboxPrint: CPU usage
25.8% 18:10:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:10:29 INFO - TinderboxPrint: I/O write bytes / time
525,737,984 / 591,152 18:10:29 INFO - TinderboxPrint: CPU idle
7,065.8 (74.1%) 18:10:29 INFO - TinderboxPrint: CPU user
2,420.0 (25.4%) 18:10:29 INFO - TinderboxPrint: Swap in / out
0 / 0 18:10:29 INFO - install - Wall time: 16s; CPU: 27.0%; Read bytes: 0; Write bytes: 239235072; Read time: 0; Write time: 376572 18:10:29 INFO - run-tests - Wall time: 1178s; CPU: 26.0%; Read bytes: 4096; Write bytes: 286228480; Read time: 12; Write time: 214540 18:10:30 INFO - Running post-run listener: _upload_blobber_files 18:10:30 INFO - Blob upload gear active. 18:10:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:10:30 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 18:10:30 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:10:30 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:10:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:10:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:10:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:10:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:10:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:10:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:10:44 INFO - (blobuploader) - INFO - Done attempting. 18:10:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:10:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:10:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:18 INFO - (blobuploader) - INFO - Done attempting. 18:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:11:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:31 INFO - (blobuploader) - INFO - Done attempting. 18:11:31 INFO - (blobuploader) - INFO - Iteration through files over. 18:11:31 INFO - Return code: 0 18:11:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:11:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:11:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/90a63ffb1a1822b07ae336b6b84d0b7b7188b5e2dbfb68edf42eabba02671de90cee768226561f4b7edb2878a8b447924e0366b02fcd1949896a20402959c86c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40a127c419689c2e950942313ca0df5556f07524cfe7197a8530d108a807edc73323931c0bc4d7b231be4ca48e0006ed32b13c9d072df5b5d3d5acb22c234c81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bbfa3646b13766969fec4238b12e1e786eb175c529d5903c6ebbe0916f2ae3ae04d596c5c7554635482889ae0437cac65c571c7ccd3d9d63df54df17172e09e4"} 18:11:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:11:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:11:31 INFO - Contents: 18:11:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/90a63ffb1a1822b07ae336b6b84d0b7b7188b5e2dbfb68edf42eabba02671de90cee768226561f4b7edb2878a8b447924e0366b02fcd1949896a20402959c86c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40a127c419689c2e950942313ca0df5556f07524cfe7197a8530d108a807edc73323931c0bc4d7b231be4ca48e0006ed32b13c9d072df5b5d3d5acb22c234c81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bbfa3646b13766969fec4238b12e1e786eb175c529d5903c6ebbe0916f2ae3ae04d596c5c7554635482889ae0437cac65c571c7ccd3d9d63df54df17172e09e4"} 18:11:31 INFO - Running post-run listener: copy_logs_to_upload_dir 18:11:31 INFO - Copying logs to upload dir... 18:11:31 INFO - mkdir: /builds/slave/test/build/upload/logs 18:11:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.821613 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-07 18:11:31.047418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 18:11:31.050684) ========= 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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 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/Autoland-Non-PGO/sha512/90a63ffb1a1822b07ae336b6b84d0b7b7188b5e2dbfb68edf42eabba02671de90cee768226561f4b7edb2878a8b447924e0366b02fcd1949896a20402959c86c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40a127c419689c2e950942313ca0df5556f07524cfe7197a8530d108a807edc73323931c0bc4d7b231be4ca48e0006ed32b13c9d072df5b5d3d5acb22c234c81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bbfa3646b13766969fec4238b12e1e786eb175c529d5903c6ebbe0916f2ae3ae04d596c5c7554635482889ae0437cac65c571c7ccd3d9d63df54df17172e09e4"} build_url:https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005513 build_url: 'https://queue.taskcluster.net/v1/task/ZFknCZIVQoORbXP5t92MGg/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/Autoland-Non-PGO/sha512/90a63ffb1a1822b07ae336b6b84d0b7b7188b5e2dbfb68edf42eabba02671de90cee768226561f4b7edb2878a8b447924e0366b02fcd1949896a20402959c86c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40a127c419689c2e950942313ca0df5556f07524cfe7197a8530d108a807edc73323931c0bc4d7b231be4ca48e0006ed32b13c9d072df5b5d3d5acb22c234c81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bbfa3646b13766969fec4238b12e1e786eb175c529d5903c6ebbe0916f2ae3ae04d596c5c7554635482889ae0437cac65c571c7ccd3d9d63df54df17172e09e4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 18:11:31.064315) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 18:11:31.064617) ========= 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=d2a8c569965ab8367867b893000001a1-1465346999.820269-456021559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002137 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 18:11:31.251539) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 18:11:31.251854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 18:11:31.274016) ========= ========= Total master_lag: 0.26 =========