builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-043 starttime: 1462946012.87 results: success (0) buildid: 20160510203104 builduid: 6c77b861037a47c3a16841a5cb75b2f8 revision: 4067fdc3fdc04df8033ee532caff07b995fef18a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.874551) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.875166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.875551) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.886769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.887375) ========= 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-1462945721.37182-1299472165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 22:53:33-- 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% 442M=0s 2016-05-10 22:53:33 (442 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082144 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.982532) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:32.983249) ========= 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-1462945721.37182-1299472165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009446 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.039236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.040018) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4067fdc3fdc04df8033ee532caff07b995fef18a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4067fdc3fdc04df8033ee532caff07b995fef18a --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-1462945721.37182-1299472165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 22:53:33,575 truncating revision to first 12 chars 2016-05-10 22:53:33,575 Setting DEBUG logging. 2016-05-10 22:53:33,575 attempt 1/10 2016-05-10 22:53:33,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4067fdc3fdc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 22:53:33,933 unpacking tar archive at: fx-team-4067fdc3fdc0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.690188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.690523) ========= script_repo_revision: 4067fdc3fdc04df8033ee532caff07b995fef18a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.690973) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.691301) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 22:53:33.697620) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-05-10 22:53:33.697927) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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-1462945721.37182-1299472165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:53:34 INFO - MultiFileLogger online at 20160510 22:53:34 in /builds/slave/test 22:53:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:53:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:53:34 INFO - {'append_to_log': False, 22:53:34 INFO - 'base_work_dir': '/builds/slave/test', 22:53:34 INFO - 'blob_upload_branch': 'Fx-Team', 22:53:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:53:34 INFO - 'branch': 'Fx-Team', 22:53:34 INFO - 'buildbot_json_path': 'buildprops.json', 22:53:34 INFO - 'config_files': ('talos/linux_config.py',), 22:53:34 INFO - 'default_actions': ('clobber', 22:53:34 INFO - 'read-buildbot-config', 22:53:34 INFO - 'download-and-extract', 22:53:34 INFO - 'populate-webroot', 22:53:34 INFO - 'create-virtualenv', 22:53:34 INFO - 'install', 22:53:34 INFO - 'run-tests'), 22:53:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:53:34 INFO - 'download_minidump_stackwalk': True, 22:53:34 INFO - 'download_symbols': 'ondemand', 22:53:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:53:34 INFO - 'tooltool.py': '/tools/tooltool.py', 22:53:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:53:34 INFO - '/tools/misc-python/virtualenv.py')}, 22:53:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:53:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:53:34 INFO - 'installer_path': 'installer.exe', 22:53:34 INFO - 'log_level': 'info', 22:53:34 INFO - 'log_name': 'talos', 22:53:34 INFO - 'log_to_console': True, 22:53:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:53:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:53:34 INFO - 'opt_config_files': (), 22:53:34 INFO - 'pip_index': False, 22:53:34 INFO - 'sps_profile': False, 22:53:34 INFO - 'sps_profile_interval': 0, 22:53:34 INFO - 'suite': 'dromaeojs-e10s', 22:53:34 INFO - 'system_bits': '32', 22:53:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:53:34 INFO - 'title': 'talos-linux64-ix-043', 22:53:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:53:34 INFO - 'use_talos_json': True, 22:53:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:53:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:53:34 INFO - 'work_dir': 'build'} 22:53:34 INFO - ##### 22:53:34 INFO - ##### Running clobber step. 22:53:34 INFO - ##### 22:53:34 INFO - Running pre-action listener: _resource_record_pre_action 22:53:34 INFO - Running main action method: clobber 22:53:34 INFO - rmtree: /builds/slave/test/build 22:53:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:53:34 INFO - Running post-action listener: _resource_record_post_action 22:53:34 INFO - ##### 22:53:34 INFO - ##### Finished clobber step (success) 22:53:34 INFO - ##### 22:53:34 INFO - ##### 22:53:34 INFO - ##### Running read-buildbot-config step. 22:53:34 INFO - ##### 22:53:34 INFO - Running pre-action listener: _resource_record_pre_action 22:53:34 INFO - Running main action method: read_buildbot_config 22:53:34 INFO - Using buildbot properties: 22:53:34 INFO - { 22:53:34 INFO - "project": "", 22:53:34 INFO - "product": "firefox", 22:53:34 INFO - "script_repo_revision": "production", 22:53:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 22:53:34 INFO - "repository": "", 22:53:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 22:53:34 INFO - "buildid": "20160510203104", 22:53:34 INFO - "pgo_build": "True", 22:53:34 INFO - "basedir": "/builds/slave/test", 22:53:34 INFO - "buildnumber": 451, 22:53:34 INFO - "slavename": "talos-linux64-ix-043", 22:53:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:53:34 INFO - "platform": "ubuntu64_hw", 22:53:34 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:53:34 INFO - "branch": "fx-team", 22:53:34 INFO - "repo_path": "integration/fx-team", 22:53:34 INFO - "revision": "4067fdc3fdc04df8033ee532caff07b995fef18a", 22:53:34 INFO - "stage_platform": "linux64-pgo", 22:53:34 INFO - "builduid": "6c77b861037a47c3a16841a5cb75b2f8", 22:53:34 INFO - "slavebuilddir": "test" 22:53:34 INFO - } 22:53:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:53:34 INFO - Running post-action listener: _resource_record_post_action 22:53:34 INFO - ##### 22:53:34 INFO - ##### Finished read-buildbot-config step (success) 22:53:34 INFO - ##### 22:53:34 INFO - ##### 22:53:34 INFO - ##### Running download-and-extract step. 22:53:34 INFO - ##### 22:53:34 INFO - Running pre-action listener: _resource_record_pre_action 22:53:34 INFO - Running main action method: download_and_extract 22:53:34 INFO - mkdir: /builds/slave/test/build/tests 22:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:34 INFO - https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:53:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:53:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:53:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:53:35 INFO - Downloaded 1361 bytes. 22:53:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:53:35 INFO - Using the following test package requirements: 22:53:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:53:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:53:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:53:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'jsshell-linux-x86_64.zip'], 22:53:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:53:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:53:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:53:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:53:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:53:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:53:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:53:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:35 INFO - https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:53:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:53:36 INFO - Downloaded 23902907 bytes. 22:53:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:53:37 INFO - Return code: 0 22:53:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:37 INFO - https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:53:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:53:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:53:39 INFO - Downloaded 23902907 bytes. 22:53:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:53:40 INFO - Return code: 0 22:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:40 INFO - https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:53:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:53:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:53:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:53:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:53:42 INFO - Downloaded 10840152 bytes. 22:53:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:53:42 INFO - Return code: 0 22:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:42 INFO - https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:53:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:53:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:53:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:53:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:53:44 INFO - Downloaded 58353621 bytes. 22:53:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:53:44 INFO - mkdir: /builds/slave/test/properties 22:53:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:53:44 INFO - Writing to file /builds/slave/test/properties/build_url 22:53:44 INFO - Contents: 22:53:44 INFO - build_url:https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:53:45 INFO - Running post-action listener: _resource_record_post_action 22:53:45 INFO - Running post-action listener: set_extra_try_arguments 22:53:45 INFO - ##### 22:53:45 INFO - ##### Finished download-and-extract step (success) 22:53:45 INFO - ##### 22:53:45 INFO - ##### 22:53:45 INFO - ##### Running populate-webroot step. 22:53:45 INFO - ##### 22:53:45 INFO - Running pre-action listener: _resource_record_pre_action 22:53:45 INFO - Running main action method: populate_webroot 22:53:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:53:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:53:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:53:45 INFO - '--tppagecycles', 22:53:45 INFO - '7'], 22:53:45 INFO - 'tests': ['tsvgm']}}, 22:53:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:53:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:53:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:53:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:53:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['damp', 'tps']}, 22:53:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'tests': ['damp', 'tps']}, 22:53:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['dromaeo_dom']}, 22:53:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:53:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'other-e10s': {'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['a11yr', 22:53:45 INFO - 'ts_paint', 22:53:45 INFO - 'tpaint', 22:53:45 INFO - 'sessionrestore', 22:53:45 INFO - 'sessionrestore_no_auto_restore', 22:53:45 INFO - 'tabpaint']}, 22:53:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['tsvgx', 22:53:45 INFO - 'tsvgr_opacity', 22:53:45 INFO - 'tart', 22:53:45 INFO - 'tscrollx', 22:53:45 INFO - 'cart']}, 22:53:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:53:45 INFO - 'tsvgr_opacity', 22:53:45 INFO - 'tart', 22:53:45 INFO - 'tscrollx', 22:53:45 INFO - 'cart']}, 22:53:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'talos_options': ['--disable-e10s'], 22:53:45 INFO - 'tests': ['tp5o']}, 22:53:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'tests': ['tp5o']}, 22:53:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'talos_options': ['--disable-e10s', 22:53:45 INFO - '--xperf_path', 22:53:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:45 INFO - 'tests': ['tp5n']}, 22:53:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:45 INFO - 'talos_options': ['--xperf_path', 22:53:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:45 INFO - 'tests': ['tp5n']}}, 22:53:45 INFO - 'talos.zip': {'path': '', 22:53:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:53:45 INFO - Running post-action listener: _resource_record_post_action 22:53:45 INFO - ##### 22:53:45 INFO - ##### Finished populate-webroot step (success) 22:53:45 INFO - ##### 22:53:45 INFO - ##### 22:53:45 INFO - ##### Running create-virtualenv step. 22:53:45 INFO - ##### 22:53:45 INFO - Running pre-action listener: _resource_record_pre_action 22:53:45 INFO - Running main action method: create_virtualenv 22:53:45 INFO - Creating virtualenv /builds/slave/test/build/venv 22:53:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:53:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:53:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:53:45 INFO - Using real prefix '/usr' 22:53:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:53:46 INFO - Installing distribute.............................................................................................................................................................................................done. 22:53:48 INFO - Installing pip.................done. 22:53:48 INFO - Return code: 0 22:53:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:48 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:53:48 INFO - Using env: {'DISPLAY': ':0', 22:53:48 INFO - 'HOME': '/home/cltbld', 22:53:48 INFO - 'LANG': 'en_US.UTF-8', 22:53:48 INFO - 'LANGUAGE': 'en_US:en', 22:53:48 INFO - 'LOGNAME': 'cltbld', 22:53:48 INFO - 'MAIL': '/var/mail/cltbld', 22:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:48 INFO - 'MOZ_NO_REMOTE': '1', 22:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:48 INFO - 'NO_EM_RESTART': '1', 22:53:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:48 INFO - 'PWD': '/builds/slave/test', 22:53:48 INFO - 'SHELL': '/bin/bash', 22:53:48 INFO - 'SHLVL': '1', 22:53:48 INFO - 'TERM': 'linux', 22:53:48 INFO - 'TMOUT': '86400', 22:53:48 INFO - 'USER': 'cltbld', 22:53:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:48 INFO - '_': '/tools/buildbot/bin/python'} 22:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:53:48 INFO - Cleaning up... 22:53:48 INFO - Return code: 0 22:53:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:53:48 INFO - Using env: {'DISPLAY': ':0', 22:53:48 INFO - 'HOME': '/home/cltbld', 22:53:48 INFO - 'LANG': 'en_US.UTF-8', 22:53:48 INFO - 'LANGUAGE': 'en_US:en', 22:53:48 INFO - 'LOGNAME': 'cltbld', 22:53:48 INFO - 'MAIL': '/var/mail/cltbld', 22:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:48 INFO - 'MOZ_NO_REMOTE': '1', 22:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:48 INFO - 'NO_EM_RESTART': '1', 22:53:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:48 INFO - 'PWD': '/builds/slave/test', 22:53:48 INFO - 'SHELL': '/bin/bash', 22:53:48 INFO - 'SHLVL': '1', 22:53:48 INFO - 'TERM': 'linux', 22:53:48 INFO - 'TMOUT': '86400', 22:53:48 INFO - 'USER': 'cltbld', 22:53:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:48 INFO - '_': '/tools/buildbot/bin/python'} 22:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:48 INFO - Downloading/unpacking psutil>=0.7.1 22:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:53:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:50 INFO - Installing collected packages: psutil 22:53:50 INFO - Running setup.py install for psutil 22:53:50 INFO - building 'psutil._psutil_linux' extension 22:53:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:53:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:53:50 INFO - building 'psutil._psutil_posix' extension 22:53:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:53:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:53:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:50 INFO - Successfully installed psutil 22:53:50 INFO - Cleaning up... 22:53:50 INFO - Return code: 0 22:53:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:53:50 INFO - Using env: {'DISPLAY': ':0', 22:53:50 INFO - 'HOME': '/home/cltbld', 22:53:50 INFO - 'LANG': 'en_US.UTF-8', 22:53:50 INFO - 'LANGUAGE': 'en_US:en', 22:53:50 INFO - 'LOGNAME': 'cltbld', 22:53:50 INFO - 'MAIL': '/var/mail/cltbld', 22:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:50 INFO - 'MOZ_NO_REMOTE': '1', 22:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:50 INFO - 'NO_EM_RESTART': '1', 22:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:50 INFO - 'PWD': '/builds/slave/test', 22:53:50 INFO - 'SHELL': '/bin/bash', 22:53:50 INFO - 'SHLVL': '1', 22:53:50 INFO - 'TERM': 'linux', 22:53:50 INFO - 'TMOUT': '86400', 22:53:50 INFO - 'USER': 'cltbld', 22:53:50 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:50 INFO - '_': '/tools/buildbot/bin/python'} 22:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:53:53 INFO - Installing collected packages: mozsystemmonitor 22:53:53 INFO - Running setup.py install for mozsystemmonitor 22:53:53 INFO - Successfully installed mozsystemmonitor 22:53:53 INFO - Cleaning up... 22:53:53 INFO - Return code: 0 22:53:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:53 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:53:53 INFO - Using env: {'DISPLAY': ':0', 22:53:53 INFO - 'HOME': '/home/cltbld', 22:53:53 INFO - 'LANG': 'en_US.UTF-8', 22:53:53 INFO - 'LANGUAGE': 'en_US:en', 22:53:53 INFO - 'LOGNAME': 'cltbld', 22:53:53 INFO - 'MAIL': '/var/mail/cltbld', 22:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:53 INFO - 'MOZ_NO_REMOTE': '1', 22:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:53 INFO - 'NO_EM_RESTART': '1', 22:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:53 INFO - 'PWD': '/builds/slave/test', 22:53:53 INFO - 'SHELL': '/bin/bash', 22:53:53 INFO - 'SHLVL': '1', 22:53:53 INFO - 'TERM': 'linux', 22:53:53 INFO - 'TMOUT': '86400', 22:53:53 INFO - 'USER': 'cltbld', 22:53:53 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:53 INFO - '_': '/tools/buildbot/bin/python'} 22:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:55 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:56 INFO - Downloading docopt-0.6.1.tar.gz 22:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:56 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:56 INFO - Running setup.py install for blobuploader 22:53:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:56 INFO - Running setup.py install for requests 22:53:56 INFO - Running setup.py install for docopt 22:53:56 INFO - Successfully installed blobuploader requests docopt 22:53:56 INFO - Cleaning up... 22:53:56 INFO - Return code: 0 22:53:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:56 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:53:56 INFO - Using env: {'DISPLAY': ':0', 22:53:56 INFO - 'HOME': '/home/cltbld', 22:53:56 INFO - 'LANG': 'en_US.UTF-8', 22:53:56 INFO - 'LANGUAGE': 'en_US:en', 22:53:56 INFO - 'LOGNAME': 'cltbld', 22:53:56 INFO - 'MAIL': '/var/mail/cltbld', 22:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:56 INFO - 'MOZ_NO_REMOTE': '1', 22:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:56 INFO - 'NO_EM_RESTART': '1', 22:53:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:56 INFO - 'PWD': '/builds/slave/test', 22:53:56 INFO - 'SHELL': '/bin/bash', 22:53:56 INFO - 'SHLVL': '1', 22:53:56 INFO - 'TERM': 'linux', 22:53:56 INFO - 'TMOUT': '86400', 22:53:56 INFO - 'USER': 'cltbld', 22:53:56 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:56 INFO - '_': '/tools/buildbot/bin/python'} 22:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:56 INFO - Running setup.py (path:/tmp/pip-MtvOzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:56 INFO - Running setup.py (path:/tmp/pip-juaWJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:56 INFO - Running setup.py (path:/tmp/pip-86_ta9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:57 INFO - Running setup.py (path:/tmp/pip-QExFQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:57 INFO - Running setup.py (path:/tmp/pip-xp1fgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:57 INFO - Running setup.py (path:/tmp/pip-MScvku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:57 INFO - Running setup.py (path:/tmp/pip-BuR6Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:57 INFO - Running setup.py (path:/tmp/pip-UrtTRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:57 INFO - Running setup.py (path:/tmp/pip-zes4TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:57 INFO - Running setup.py (path:/tmp/pip-bN5dX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:57 INFO - Running setup.py (path:/tmp/pip-uIDm5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:57 INFO - Running setup.py (path:/tmp/pip-NxTFb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:57 INFO - Running setup.py (path:/tmp/pip-IaQPE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:57 INFO - Running setup.py (path:/tmp/pip-akDaOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:57 INFO - Running setup.py (path:/tmp/pip-0DV61e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:57 INFO - Running setup.py (path:/tmp/pip-SptTmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:57 INFO - Running setup.py (path:/tmp/pip-DtkAVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:57 INFO - Running setup.py install for manifestparser 22:53:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozcrash 22:53:57 INFO - Running setup.py install for mozdebug 22:53:58 INFO - Running setup.py install for mozdevice 22:53:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozfile 22:53:58 INFO - Running setup.py install for mozhttpd 22:53:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozinfo 22:53:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozInstall 22:53:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozleak 22:53:58 INFO - Running setup.py install for mozlog 22:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for moznetwork 22:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozprocess 22:53:58 INFO - Running setup.py install for mozprofile 22:53:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Running setup.py install for mozrunner 22:53:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Running setup.py install for mozscreenshot 22:53:59 INFO - Running setup.py install for moztest 22:53:59 INFO - Running setup.py install for mozversion 22:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:59 INFO - Cleaning up... 22:53:59 INFO - Return code: 0 22:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:53:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:53:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:53:59 INFO - Using env: {'DISPLAY': ':0', 22:53:59 INFO - 'HOME': '/home/cltbld', 22:53:59 INFO - 'LANG': 'en_US.UTF-8', 22:53:59 INFO - 'LANGUAGE': 'en_US:en', 22:53:59 INFO - 'LOGNAME': 'cltbld', 22:53:59 INFO - 'MAIL': '/var/mail/cltbld', 22:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:59 INFO - 'MOZ_NO_REMOTE': '1', 22:53:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:53:59 INFO - 'NO_EM_RESTART': '1', 22:53:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:59 INFO - 'PWD': '/builds/slave/test', 22:53:59 INFO - 'SHELL': '/bin/bash', 22:53:59 INFO - 'SHLVL': '1', 22:53:59 INFO - 'TERM': 'linux', 22:53:59 INFO - 'TMOUT': '86400', 22:53:59 INFO - 'USER': 'cltbld', 22:53:59 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:59 INFO - '_': '/tools/buildbot/bin/python'} 22:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:59 INFO - Running setup.py (path:/tmp/pip-h8FVyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:59 INFO - Running setup.py (path:/tmp/pip-W_Wa_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:59 INFO - Running setup.py (path:/tmp/pip-vrSAVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:59 INFO - Running setup.py (path:/tmp/pip-0fnIhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:59 INFO - Running setup.py (path:/tmp/pip-2M6_Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:59 INFO - Running setup.py (path:/tmp/pip-WIuDpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:59 INFO - Running setup.py (path:/tmp/pip-3yYLyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 INFO - Running setup.py (path:/tmp/pip-seQGWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:00 INFO - Running setup.py (path:/tmp/pip-k8ftOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:00 INFO - Running setup.py (path:/tmp/pip-Xdx99X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:00 INFO - Running setup.py (path:/tmp/pip-Sw1i5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:00 INFO - Running setup.py (path:/tmp/pip-gPmbGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:00 INFO - Running setup.py (path:/tmp/pip-dhWj_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:00 INFO - Running setup.py (path:/tmp/pip-1RlHDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:00 INFO - Running setup.py (path:/tmp/pip-iUBca4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:00 INFO - Running setup.py (path:/tmp/pip-RyZVV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:00 INFO - Running setup.py (path:/tmp/pip-RUC4Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:54:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:02 INFO - Downloading blessings-1.6.tar.gz 22:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:54:02 INFO - Installing collected packages: blessings 22:54:02 INFO - Running setup.py install for blessings 22:54:02 INFO - Successfully installed blessings 22:54:02 INFO - Cleaning up... 22:54:02 INFO - Return code: 0 22:54:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:02 INFO - Reading from file tmpfile_stdout 22:54:02 INFO - Current package versions: 22:54:02 INFO - argparse == 1.2.1 22:54:02 INFO - blessings == 1.6 22:54:02 INFO - blobuploader == 1.2.4 22:54:02 INFO - docopt == 0.6.1 22:54:02 INFO - manifestparser == 1.1 22:54:02 INFO - mozInstall == 1.12 22:54:02 INFO - mozcrash == 0.17 22:54:02 INFO - mozdebug == 0.1 22:54:02 INFO - mozdevice == 0.48 22:54:02 INFO - mozfile == 1.2 22:54:02 INFO - mozhttpd == 0.7 22:54:02 INFO - mozinfo == 0.9 22:54:02 INFO - mozleak == 0.1 22:54:02 INFO - mozlog == 3.1 22:54:02 INFO - moznetwork == 0.27 22:54:02 INFO - mozprocess == 0.22 22:54:02 INFO - mozprofile == 0.28 22:54:02 INFO - mozrunner == 6.11 22:54:02 INFO - mozscreenshot == 0.1 22:54:02 INFO - mozsystemmonitor == 0.0 22:54:02 INFO - moztest == 0.7 22:54:02 INFO - mozversion == 1.4 22:54:02 INFO - psutil == 3.1.1 22:54:02 INFO - requests == 1.2.3 22:54:02 INFO - wsgiref == 0.1.2 22:54:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:02 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:54:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:54:02 INFO - Using env: {'DISPLAY': ':0', 22:54:02 INFO - 'HOME': '/home/cltbld', 22:54:02 INFO - 'LANG': 'en_US.UTF-8', 22:54:02 INFO - 'LANGUAGE': 'en_US:en', 22:54:02 INFO - 'LOGNAME': 'cltbld', 22:54:02 INFO - 'MAIL': '/var/mail/cltbld', 22:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:02 INFO - 'MOZ_NO_REMOTE': '1', 22:54:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:02 INFO - 'NO_EM_RESTART': '1', 22:54:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:02 INFO - 'PWD': '/builds/slave/test', 22:54:02 INFO - 'SHELL': '/bin/bash', 22:54:02 INFO - 'SHLVL': '1', 22:54:02 INFO - 'TERM': 'linux', 22:54:02 INFO - 'TMOUT': '86400', 22:54:02 INFO - 'USER': 'cltbld', 22:54:02 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:02 INFO - '_': '/tools/buildbot/bin/python'} 22:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:54:03 INFO - Cleaning up... 22:54:03 INFO - Return code: 0 22:54:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:03 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:54:03 INFO - Using env: {'DISPLAY': ':0', 22:54:03 INFO - 'HOME': '/home/cltbld', 22:54:03 INFO - 'LANG': 'en_US.UTF-8', 22:54:03 INFO - 'LANGUAGE': 'en_US:en', 22:54:03 INFO - 'LOGNAME': 'cltbld', 22:54:03 INFO - 'MAIL': '/var/mail/cltbld', 22:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:03 INFO - 'MOZ_NO_REMOTE': '1', 22:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:03 INFO - 'NO_EM_RESTART': '1', 22:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:03 INFO - 'PWD': '/builds/slave/test', 22:54:03 INFO - 'SHELL': '/bin/bash', 22:54:03 INFO - 'SHLVL': '1', 22:54:03 INFO - 'TERM': 'linux', 22:54:03 INFO - 'TMOUT': '86400', 22:54:03 INFO - 'USER': 'cltbld', 22:54:03 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:03 INFO - '_': '/tools/buildbot/bin/python'} 22:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:03 INFO - Downloading/unpacking jsonschema 22:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:54:05 INFO - Downloading/unpacking functools32 (from jsonschema) 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:05 INFO - Downloading functools32-3.2.3-2.tar.gz 22:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:54:05 INFO - warning: no files found matching '*.txt' 22:54:05 INFO - no previously-included directories found matching 'build' 22:54:05 INFO - no previously-included directories found matching 'dist' 22:54:05 INFO - no previously-included directories found matching '.git*' 22:54:05 INFO - Installing collected packages: jsonschema, functools32 22:54:05 INFO - Running setup.py install for functools32 22:54:05 INFO - warning: no files found matching '*.txt' 22:54:05 INFO - no previously-included directories found matching 'build' 22:54:05 INFO - no previously-included directories found matching 'dist' 22:54:05 INFO - no previously-included directories found matching '.git*' 22:54:05 INFO - Successfully installed jsonschema functools32 22:54:05 INFO - Cleaning up... 22:54:05 INFO - Return code: 0 22:54:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:05 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 0x7ffe5cdbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, '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-1462945721.37182-1299472165', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:54:05 INFO - Using env: {'DISPLAY': ':0', 22:54:05 INFO - 'HOME': '/home/cltbld', 22:54:05 INFO - 'LANG': 'en_US.UTF-8', 22:54:05 INFO - 'LANGUAGE': 'en_US:en', 22:54:05 INFO - 'LOGNAME': 'cltbld', 22:54:05 INFO - 'MAIL': '/var/mail/cltbld', 22:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:05 INFO - 'MOZ_NO_REMOTE': '1', 22:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:05 INFO - 'NO_EM_RESTART': '1', 22:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:05 INFO - 'PWD': '/builds/slave/test', 22:54:05 INFO - 'SHELL': '/bin/bash', 22:54:05 INFO - 'SHLVL': '1', 22:54:05 INFO - 'TERM': 'linux', 22:54:05 INFO - 'TMOUT': '86400', 22:54:05 INFO - 'USER': 'cltbld', 22:54:05 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:05 INFO - '_': '/tools/buildbot/bin/python'} 22:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:05 INFO - Downloading/unpacking flake8 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:54:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:54:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:54:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:08 INFO - no previously-included directories found matching 'docs/_build' 22:54:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:54:08 INFO - Running setup.py install for pep8 22:54:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:08 INFO - no previously-included directories found matching 'docs/_build' 22:54:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:54:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:54:08 INFO - Cleaning up... 22:54:08 INFO - Return code: 0 22:54:08 INFO - Running post-action listener: _resource_record_post_action 22:54:08 INFO - Running post-action listener: _start_resource_monitoring 22:54:08 INFO - Starting resource monitoring. 22:54:08 INFO - ##### 22:54:08 INFO - ##### Finished create-virtualenv step (success) 22:54:08 INFO - ##### 22:54:08 INFO - ##### 22:54:08 INFO - ##### Running install step. 22:54:08 INFO - ##### 22:54:08 INFO - Running pre-action listener: _resource_record_pre_action 22:54:08 INFO - Running main action method: install 22:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:08 INFO - Reading from file tmpfile_stdout 22:54:08 INFO - Detecting whether we're running mozinstall >=1.0... 22:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:54:08 INFO - Reading from file tmpfile_stdout 22:54:08 INFO - Output received: 22:54:08 INFO - Usage: mozinstall [options] installer 22:54:08 INFO - Options: 22:54:08 INFO - -h, --help show this help message and exit 22:54:08 INFO - -d DEST, --destination=DEST 22:54:08 INFO - Directory to install application into. [default: 22:54:08 INFO - "/builds/slave/test"] 22:54:08 INFO - --app=APP Application being installed. [default: firefox] 22:54:08 INFO - mkdir: /builds/slave/test/build/application 22:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:54:25 INFO - Reading from file tmpfile_stdout 22:54:25 INFO - Output received: 22:54:25 INFO - /builds/slave/test/build/application/firefox/firefox 22:54:25 INFO - Running post-action listener: _resource_record_post_action 22:54:25 INFO - ##### 22:54:25 INFO - ##### Finished install step (success) 22:54:25 INFO - ##### 22:54:25 INFO - ##### 22:54:25 INFO - ##### Running run-tests step. 22:54:25 INFO - ##### 22:54:25 INFO - Running pre-action listener: _resource_record_pre_action 22:54:25 INFO - Running main action method: run_tests 22:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:54:25 INFO - Python 2.7.3 22:54:25 INFO - Return code: 0 22:54:25 INFO - grabbing minidump binary from tooltool 22:54: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')]} 22:54:25 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 0x21739c0>, '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 0x21748c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2174d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:54:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:54:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:54:25 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 22:54:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:54:25 INFO - Return code: 0 22:54:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:54:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:54:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:54:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:54:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:54:26 INFO - Return code: 0 22:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/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 22:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/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 22:54:26 INFO - Using env: {'DISPLAY': ':0', 22:54:26 INFO - 'HOME': '/home/cltbld', 22:54:26 INFO - 'LANG': 'en_US.UTF-8', 22:54:26 INFO - 'LANGUAGE': 'en_US:en', 22:54:26 INFO - 'LOGNAME': 'cltbld', 22:54:26 INFO - 'MAIL': '/var/mail/cltbld', 22:54:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:54:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:26 INFO - 'MOZ_NO_REMOTE': '1', 22:54:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:54:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:26 INFO - 'NO_EM_RESTART': '1', 22:54:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:26 INFO - 'PWD': '/builds/slave/test', 22:54:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:54:26 INFO - 'SHELL': '/bin/bash', 22:54:26 INFO - 'SHLVL': '1', 22:54:26 INFO - 'TERM': 'linux', 22:54:26 INFO - 'TMOUT': '86400', 22:54:26 INFO - 'USER': 'cltbld', 22:54:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1462945721.37182-1299472165', 22:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:26 INFO - '_': '/tools/buildbot/bin/python'} 22:54:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/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 22:54:26 INFO - mozversion application_buildid: 20160510203104 22:54:26 INFO - mozversion application_changeset: 4067fdc3fdc04df8033ee532caff07b995fef18a 22:54:26 INFO - mozversion application_display_name: Nightly 22:54:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:54:26 INFO - mozversion application_name: Firefox 22:54:26 INFO - mozversion application_remotingname: firefox 22:54:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:54:26 INFO - mozversion application_vendor: Mozilla 22:54:26 INFO - mozversion application_version: 49.0a1 22:54:26 INFO - mozversion platform_buildid: 20160510203104 22:54:26 INFO - mozversion platform_changeset: 4067fdc3fdc04df8033ee532caff07b995fef18a 22:54:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:54:26 INFO - mozversion platform_version: 49.0a1 22:54:26 INFO - using testdate: 1462946066 22:54:26 INFO - actual date: 1462946066 22:54:26 INFO - starting webserver on 'localhost:51308' 22:54:26 INFO - SUITE-START | Running 2 tests 22:54:26 INFO - TEST-START | dromaeo_css 22:54:26 INFO - operating with platform_type : linux_ 22:54:26 INFO - Initialising browser for dromaeo_css test... 22:54:26 INFO - TEST-INFO | started process 6804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ5F_jd/profile http://localhost:51308/getInfo.html) 22:54:30 INFO - PROCESS | 6804 | 22:54:30 INFO - PROCESS | 6804 | (process:6871): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:54:30 INFO - PROCESS | 6804 | __metrics Screen width/height:1600/1200 22:54:30 INFO - PROCESS | 6804 | colorDepth:24 22:54:30 INFO - PROCESS | 6804 | Browser inner width/height: 1024/697 22:54:30 INFO - PROCESS | 6804 | __metrics 22:54:31 ERROR - PROCESS | 6804 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:54:32 INFO - TEST-INFO | 6804: exit 0 22:54:32 INFO - Browser initialized. 22:54:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:54:32 INFO - TEST-INFO | started process 6922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ5F_jd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:33 INFO - PROCESS | 6922 | 22:54:33 INFO - PROCESS | 6922 | (process:6983): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:54:43 INFO - PROCESS | 6922 | RSS: Main: 162353152 22:54:43 INFO - PROCESS | 6922 | 22:55:33 INFO - PROCESS | 6922 | 1462946133326 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:57:40 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51308/tests/dromaeo/cssquery-ext.html) 22:57:41 INFO - PROCESS | 6922 | RSS: Main: 165261312 22:57:41 INFO - PROCESS | 6922 | 23:00:43 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-ext.html (next: http://localhost:51308/tests/dromaeo/cssquery-jquery.html) 23:00:43 INFO - PROCESS | 6922 | RSS: Main: 165498880 23:00:43 INFO - PROCESS | 6922 | 23:03:45 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51308/tests/dromaeo/cssquery-mootools.html) 23:03:46 INFO - PROCESS | 6922 | RSS: Main: 166838272 23:03:46 INFO - PROCESS | 6922 | 23:06:48 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51308/tests/dromaeo/cssquery-prototype.html) 23:06:48 INFO - PROCESS | 6922 | RSS: Main: 163385344 23:06:48 INFO - PROCESS | 6922 | 23:09:51 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51308/tests/dromaeo/cssquery-yui.html) 23:09:51 INFO - PROCESS | 6922 | RSS: Main: 165085184 23:09:51 INFO - PROCESS | 6922 | 23:12:53 INFO - PROCESS | 6922 | Cycle 1(1): loaded http://localhost:51308/tests/dromaeo/cssquery-yui.html (next: http://localhost:51308/tests/dromaeo/cssquery-dojo.html) 23:12:54 INFO - PROCESS | 6922 | RSS: Main: 166391808 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | __start_tp_report 23:12:54 INFO - PROCESS | 6922 | _x_x_mozilla_page_load 23:12:54 INFO - PROCESS | 6922 | _x_x_mozilla_page_load_details 23:12:54 INFO - PROCESS | 6922 | |i|pagename|runs| 23:12:54 INFO - PROCESS | 6922 | |0;dojo.html;3098.395812816502;3127.49960006399;3156.384505021522;3158.4946408574633;3158.7315078218344;5238.397584277809;5245.895082098361;5335.0663633864115;5337.359516857973;5406.702631355274;5156.407013193484;5191.974040129803;5222.817201397952;5258.92111618326;5305.389880163782;4682.882927926812;4714.952850471503;4720.5515476029805;4728.929066064012;4795.760211989404;4753.263244197155;4757.429108506966;4773.997460533292;4779;4780.952190478103;507.7655533014698;4714.717116972993;4743.35964644772;4749.90500189996;4770.16522108633;4712.6229901607785;4717.504662010474;4736;4744.240921452585;4765.785539650689;4687.578117969365;4725.527447255247;4726.527347265281;4735.455422626407;4743.573078422958;5031.52200540948;5159.974200129013;5223.2165175223645;5239.842804715827;5282.2076688496645;5635.6055076144685;5677.091665333568;5684.0905455127395;5710.2862142232225;5723.198752174699;5431.83704488866;5712.257406537124;5713.371529131792;5725.055365864628;5725.513331366838;5230.843074707765;5274.156135018378;5275.103232450492;5294.523492885659;5319.5744340452675;5788;5797.159411885253;5826.883462330729;5829.4170582942215;5829.504492118217;5380;5394.974954758662;5544.1960915667005;5555;5559.499645031967;5473.7263136843;5484.91772623411;5499;5530.5299049580435;5558.082916318762;5435.510804027656;5467.043267428264;5468.070428027245;5496;5500.4224556420995;5432.728363581805;5469.453054694578;5474.206240095164;5483.232347471358;5527.695976721238;4173.248815213291;4242.745435273893;4246.214450326699;4264.275073237557;4278.0374415756205;3729.7202709796875;3786.8106594670157;3810.961890381116;3839.251345987506;3893.143508428141;5233.267342571967;5241.816536421284;5255.421903590602;5265.842024739264;5338.252644629755;5456.963176996437;5618.522425593788;5657.462541058593;5682.602217844711;5719.628224165416;5371.704556249444;5372.355317361941;5381.973090134525;5441.59188060897;5451.64564303319;4700.55344742249;4918.6556941014505;4918.704877707349;4920.852374428773;4976.701397916137;5752.395998420189;5831.212786273799;5831.562632802556;5863.941360586425;5895.793647222326;5707;5712.885742285048;5716.5998380113;5731.111677689964;5733.283339582552;5663.122216056518;5789.79735709265;5866.120081987736;5879.73541190656;5887.20522729426;5564.054110801093;5595.412481689526;5603.7758489659955;5604.635698679573;5623.943760562342;5652.349979752216;5662.320521537442;5676.489115979582;5680.715964201856;5713.9142912855505;5305.204219367126;5417.6478528895495;5441.2654291670115;5544.916826247691;5553.333599968029;5492.835214943558;5494;5514.641548299348;5516.641418307797;5533.087040638249;5344.812931547377;5355.955588660174;5374.408815030265;5382.623216374816;5413.1880217966045;5319.840404787863;5382.219578161066;5461.863453413696;5509.118541033416;5576.804811831566;5288.550473209821;5373.489518495737;5390.137577987503;5426.2674538936735;5440.42875498067 23:12:54 INFO - PROCESS | 6922 | |1;ext.html;530.8101761638607;566.0631654611617;595.3332267859997;609.3723464831224;645.7320212111973;37867;42070.57929420705;42539.57460425395;42552.361714574305;42609.57390426095;42312.365314520306;42400.36399454011;42592;42684;42824.78587607058;8678.652853885853;8850.911490885073;8863.335249856254;8867.24628406583;8893.95553022234;8698.739037828875;8797.208251257385;8812.206901378875;8812.691555795549;8819.294456443502;8799.120087991214;8808.603612837405;8835;8855.512946787923;8867.201951824334;8796.56017199144;8810.86783698245;8830.602622881986;8833.249173820233;8838.248748856355;8500.362472814502;8530.274926631244;8543.231109200202;8544.18830211129;8559.058503564604;30818.53772193419;39364.60635393638;39579;39728.40407393892;39877.20245595101;3060.831654259015;3072.5544796004456;3213.084270982782;3290.0952238134464;3314.2211580278617;29699.554506682416;30442;30465.543016854765;30492;30770.23074423135;4072.165206132736;4095.610916962884;4111.60939710727;4198.118395137025;4224.345226489899;276195;276747;278007;278410;279133;33988.66011339904;34105.147371315914;34310;34639.480407793904;35086;31205.375892482025;31327.21681957969;31413.685863141076;31498.685013149574;31537.842310788757;14470.782938255934;14708.926455367655;14716.19060951658;14757.557273281818;14764.671179593888;12383.133180677447;12505.874941250653;12538.686532836751;12540.871321581102;12558.874411255953;8329.08380078191;8361.623726932303;8366.121557236524;8372.497650141011;8437.873431898526;2945.9263518412213;2968.7773416993814;2976.3303256767053;2988.148377712385;3006.5039268520886;1053.2837670384213;1056.3081181825917;1082.951714673557;1128.4258459449022;1152.827075938616;31267.84366078155;31390.686093139233;31834.363312733614;31995.52006719901;32025.19937001547;1045.895410458948;1125.9943700281447;1139.4530625299903;1191.4995701805262;1252.3738130934532;15287.541373758804;15441.459548915735;15621.609459763597;15629.921850390676;15749.68500629981;464764;467972;472036;472037;475706;44454.77772611116;44529.55470445254;45072.54927450683;45078;45317;1224.7795396828478;1358.7010857611508;1401.9439222430988;1404.206623257856;1405.8383285922046;26446.471070578864;26770.732292677603;26916;26934.730652694005;26955.460890782466;49563.50436495589;50146.749266253435;50372.49627503678;50508.24237636512;50575.241371378725;392759;400808;400900;401026;402957;27308.180754577395;27656.170314890587;27697;27742.47416392118;27783.444331113667;46032.30951535796;46473.30290045584;46757.532424676676;47314.763426182646;47436.52563474459;38506.80214731465;40139.19373628252;40490.59509404868;40540;41387.7930610345;38823;40452.36109456279;40799.59200407958;40972;41576.376354355314;30843.69156308408;39070.21859562736;40065.39901901532;40773.184536308516;41587.376189356575;34364.65635343615;34934;35431.64568354283;35700.46449303314;35845.641543585276;26999;27480.725192748618;27560.72439275662;27739.44521109607;27799 23:12:54 INFO - PROCESS | 6922 | |2;jquery.html;381.4570064090753;384.20853042731954;385.5835697446758;395.24705436144154;403.1916008403151;17176.3129474821;19242.90378548109;19929.701054484194;19948.202071917105;20084.29704960323;16770.245338959747;17640.823591764078;17736.911315443438;18104.637907241846;18174;5065.113605119108;5070.974645126778;5213.530782229599;5252.606054545905;5262.4474430184855;5165.741712914358;5261.368635763713;5261.552768014723;5279.788808447657;5280.102382594949;5248.0553500369915;5252.818115923906;5260.263563101169;5264.236685680574;5311.681299122046;5529.281193444846;5676.631018983753;5685.147227915846;5688.687122208318;5690.772369105231;4980.9252861207115;5051.646384753069;5086.974565127188;5087.643864929457;5091.083604951107;21616.675749863763;22453.887730561408;22861.085556577716;23066.53866922669;23173.652395214085;2291.4958709083976;2329.208069256444;2428.9514209715885;2460.225029115823;2593.910557565808;10059.195264378905;10139.746506337327;10207.323774791443;12391.256524608552;14215;1907.6280125375558;1914.8659593828438;1929.353666521703;1930.6814375628146;1951.980480195194;82406;85327.57336213341;87353;87499;87688.56155719244;10082.899171008195;10105.44420056904;10266.897331026743;10313.690589282334;10434.217433692504;14159.646008849655;14165.858341416453;14180.787288190686;14192.503262385973;14229.50196743109;7170.784876453715;7454.589997550078;7523.022007139037;7539.924600754032;7637.427192960439;6679.9332006679315;6749.493787965922;6753.08833307507;7043.2752024643105;7127.536710113826;4285.785710714452;4378.759168245713;4455.331700244924;4539.931901021488;4784.210605250095;1932.4589115047809;1986.5232344237568;2017.5157962089286;2040.9387718368473;2052.8973551322374;905.180811365715;988.461288597716;1005.1606908231562;1026.707388394304;1088.1566785740956;17600.91199543994;17615.471535853943;18193.63612727738;18455.354062607745;19164.520886977658;964.6768332608655;1017.2472370445964;1017.7964407118458;1067.6636859389423;1071.9571217151226;10023.34848234877;10190.643327483502;10279.94860025695;10367.32612380208;10636.627718029986;465184;476210;479127;480727;483042;75301.62349188219;96596;97369;97487;98383;863.87905693203;884.6196135661598;973.2165606686543;1033.9224558158169;1040.1003132290364;16660.500184993984;16866.987980721195;17022.404215852384;17036.233369498153;17158.05630690324;37040.444393333586;38250.426243606926;38717.806410967765;39621.60378396294;39792.60207397889;53142.73428632832;54218.728906355216;54286.45713542814;54464;54478.18282725683;8025.117237104031;8065.9596702016115;8103.270705636521;8106.716264930935;8387.916120838958;9196.494192819191;10851.457427128455;11156.665300041012;11932.1050921183;11971.281723096643;5629.774809007594;7776.688932442639;10343.224258180517;10853.728656783327;10872.945635271773;10375;10506.579736810441;10682.092022178202;10778.622748204089;11007.559697612005;9793.657221997197;10874.401907895142;10917.945410273216;11017.83473247918;11202.88797112051;7012.474064445085;8188.2630563249595;8212.548309843016;8250.54621995772;8406.957965210135;10005.299629025898;10556.524956376828;10769.892301076889;10895;10899.128069754242 23:12:54 INFO - PROCESS | 6922 | |3;mootools.html;1345.340783016322;1453.4258967707756;1584.4296053420774;1762.9206685699141;1771.9911400443004;7442.851142977137;7925.881111783321;8054.91945080549;8494.660213591456;8550.35872309577;8186.590670466483;8463.111373305806;8509.276711479532;8543.957280213606;8569;2115.9365219043416;2135.455458857989;2136.134865379524;2144.2173606633546;2159.492519257973;2254.875981820999;2278.3734473019886;2326.2788535553946;2330.1727886600265;2331.9300420987397;2265.5129147233342;2282.5777231212196;2290.6220473621834;2293.140072472823;2295.6455691142223;2197.8571392859412;2225.054351900436;2229.320057382498;2231.9956019791157;2254.537819746948;2129.7444306683296;2137.7434707835005;2140.5397839464435;2151.4513798981316;2157.9784202158094;8381.329493640555;8449;8469.576521173916;8480.194381533744;8510.957445212796;5066.695998240118;5181.766820493049;5204.479552044803;5212.582993360522;5218.712970786605;3171.302313491028;3244.902652920416;3365.814880181589;3380.6957373836226;3386.72906167506;1042.6090216168936;1070.149231361062;1081.1350919264557;1100.5817789240118;1107.7673688525338;11900.583479578174;11913.821292680617;11927.224730392496;11972;12035.338056406894;4410.16206920684;4485.708428952108;4539.0921815637;4622.399088118523;4626.699264547794;10018.448985305879;10028.298019138736;10046.598136074475;10049.397036177706;10090.49547522621;5063.240513922941;5077.441481437039;5172.9741351293005;5174.534291913746;5206.088934436459;4169.624733773975;4500.932486012713;4649.558291962258;4684.250519916797;4750.738709371018;3849.1339448624394;3909.4526766252748;3935.0752573145287;3948.7038472114705;3971.92056158881;1305.8563558008611;1340.1021315718492;1345.9663508412368;1349.919004859692;1352.5671785028696;678.0405725897898;737.6606760889944;889.7597648634962;905.2395987370641;919.4575200631525;5294.364676238876;5327.946720532823;5385.676859388449;5444.237806707064;5475.616706830563;902.4585248850768;903.2548147778109;912.9315301352292;919.8022425178618;928.034843762493;3611.5485564304463;3643.216708407705;3661.9450708239397;3695.501107350527;3726.5900750917376;10197.490125493696;10486.37081775096;10543.31468454548;10555.050045495942;10616.203784716177;10411.11505522024;10513.684589462327;10520.210984176218;10557.524911379007;10571.894281057244;7372.189059203483;7792.961035194901;7823.726169584151;7827.882581761278;7962.442629016029;5534.169874518854;5571.721413929206;5649.6327738696855;5659.292588426447;5710.885782284414;9233.722988310361;9515.286353523652;9522.857157142787;9624.951875240858;9715.22278217755;9672.226221902372;9852.916179220138;9948.154406875206;10141.036601522843;10161;6708.463322934056;7050.647467626701;7266.38235749946;7375.114986201475;7553.244675532623;8409;8418.957905210435;8451.830963380575;8457;8464.322854171529;6882.5870447773295;7305.379042781211;7514.699412023458;7600.885986710315;7647.235276472308;7107.857842843217;7235.63821808897;7249.528780629243;7328.3404493595835;7386.15059268191;6675.132232809704;6735;6744.966275168593;6749.831254218684;6790.253072162157;4451.221036318696;4553.658475614408;4582.243929751687;4624.514425985223;4682.953170468251;4614.861554153381;4645.187092258773;4648.7443190624845;4707.693999889996;4728.62171026324 23:12:54 INFO - PROCESS | 6922 | |4;prototype.html;322.3906816117537;328.92270316475623;329.96535363786796;332.71885256957864;335.2774770369854;5291.312129423174;5434.972825135878;5485.341758988922;5492.5605951523885;5516.448355164482;5354.8219391733755;5359.919601205985;5365.007473617374;5395.838124856251;5414.702191379472;3976.985868603509;3990.7006974476885;4142.467287103776;4155.771432571208;4175.958240417603;4154.169166166779;4155.854545090922;4165.33354663254;4179.247735407625;4187.706860519765;4110.362893751473;4116.16830510423;4129.958700413003;4139.3997870308785;4139.813708383115;4013.2174226025936;4065.329220678599;4119.917601647965;4147.294959856831;4159.812808423628;3650.7261955353456;3659.9085022874374;3667.2665466906724;3680.2823449427374;3686.870959516404;5166.509181627739;5185.974070129663;5206.271122042917;5218.6868787872845;5245.4754524547625;4663.610853131232;4728.976355118202;4750.714957102585;4789.880252993668;4842.515748425165;5556.833295001156;5559.305086864142;5574;5619.185218143387;5650.265465489501;4015.9799201003807;4031.9798401007806;4050.4936882889638;4060.1473690525027;4060.2488539620263;5675.489205971441;5836.562257830679;5846.123081537761;5876.559258055663;5887.381824908366;4120.402541631506;4128.399108561647;4132.504099508018;4134.937975930364;4145.233131870553;4126.287590649907;4136.400221967858;4168.311833707354;4174.290370636999;4187.20443115807;4702.224133018014;4767.451743049524;4767.785449654774;4782.521747825259;4802.399700037496;5646.040173170571;5674.290713660793;5683.976884160808;5684.204211410406;5700.087985922232;1427.8577138289327;1430.2705620133806;1437.9862197150983;1450.9056911300731;1452.5860129862938;909.5770466733001;936.3866667332908;940.8118376324762;957.9079848972177;986.28001558861;5320.973395133;5405.97255044994;5410.161424979135;5416.945830541723;5448;5645.5201307888465;5755.899096829627;5761.193432919395;5772.336181339199;5776.768929242868;5146.794128234828;5344.85085706575;5345.438728933484;5458.8908221835345;5504.91742623861;3071.5239137933654;3116.5948426704836;3125;3128.1700205380107;3134.5298205268937;5757.8560535986935;6030.7889223876955;6032.215811944419;6043.244594425697;6047.425494578008;6010.729517171738;6026.920712354318;6029.487493563096;6060.909086363708;6066.393360663987;6010.459058684652;6079.817605471843;6086.904357215655;6107.755689772448;6156.907646385308;5511.877926998215;5756.136579513107;5851;5853.0635098384055;5855.9121613176685;5772.94227057724;5774.509166720875;5792.82621521355;5817.360090389969;5849.912251316318;5820.563457740602;6023.488003519575;6101.328853825987;6108.389161084034;6141.1402403664415;4175.248455277958;4209.179210054132;4213.41012258278;4218.578142185879;4224.176285624274;5717.342505611908;5719.48524632785;5724.169995350734;5738.340090889601;5751.252337196138;5450.1007333789485;5641.351244606923;5676.9432305678065;5690.658560486384;5768.913466298093;5415.241866138665;5662.094064949588;5693.373728889902;5724.883647688661;5744.472432129849;5280.023195708806;5424.24060631504;5554.527865131509;5591.924441557046;5623.2127502148915;5626.324841019104;5697.561755216579;5701.201831743476;5708.457696518824;5735.283089613798;5507.311586051744;5530.9259027864555;5557.332480409507;5572.4148964358155;5587.161925711176 23:12:54 INFO - PROCESS | 6922 | |5;yui.html;730.3901242462543;748.1994266135234;761.7257787196605;787.3583029830692;799.6321692021671;13309.401076951537;13415.19508829471;14013.719725605482;14291.785623215661;14497.637559061015;14652.120872747642;15317.387304507807;15317.540473785777;15325.69348613027;15448.768268475982;532.2601583798513;535.0209117315324;536.1314670234226;536.1582315764232;537.1432565058725;509.95155460231217;534.9465053494639;535.8633548445141;541.4125673644081;542.4521233554119;565.2905603467663;565.6747370262095;569.0099227344411;569.977200911965;572.6277919352433;561.831450564831;563.6251892491488;564.9632773869686;566.0970751651109;567.0162268464211;400.93785463253244;401.84930651005874;402.3481959226043;403.73151854017044;404.0887798015486;12493.93753031229;12502.187357821713;12503.437345319482;12510.562130325396;12568.685782855411;12920.289384083871;13440.529581464602;13446.260455674934;13539.255340956244;13840.377183026789;10136.189104871593;10228.641997530125;10295.485225738683;10300.433476158809;10304.690859274233;3633.691136253395;3650.945235821465;3705.3886108792017;3731.057116249404;3735.8505659773577;58685.41314586842;58809.41190588082;59325.40674593242;59354.70322648445;59795.40204597942;10412.125817352799;10490.058555432921;10576.471176441148;10642.308249963882;10663.733406664895;19017.80982190188;19018.334358297394;19043.61912761737;19083.713744293847;19087.42737717871;7067.681954312068;7097.9290207097265;7137.714491420389;7219;7304.013958115693;4417.182821178092;4470.955290447119;4563.06457176278;4584.564466375689;4606.631469482433;2930.8827646893888;2985.7611391088662;3000.9849950750545;3002.7147420995407;3019;580.7183515994739;586.7594286342579;586.865021045162;590.6367583935848;616.4636766013597;556.4296595989143;573.916782066598;574.0528128587863;575.7524264566276;598.1506261109236;8817.867731984026;8915.910840891638;9031.593578288992;9105.499197544199;9269.351145419756;568.2357229526281;582.7610679621334;629.4335098411375;629.7449532939168;633.8795628830508;4293.549177336397;4422.336649502536;4466.57567531084;4543.27355604868;4618.538146185355;54346.184807227924;55889.16166257509;58729;64693.353066469674;67387.66306168537;59505.70247148736;59608;60114.699426502586;60151.699241504386;62445;55228.723856380464;55783.44216557783;56240.71879640576;56429;56618.43381566214;8660.220580147818;8685.869711954452;8791.824163516821;8872.955635221782;8878.73363799087;34375.82812085924;34816.825915871275;35004.299914002084;35045;35460.46809297914;48089;49214.50785492243;49282.75358623184;50044.49955500398;50048.49951500584;10755.193360497837;10943.101023904455;11078.224524283223;11159;11431.142664300378;12579.371031448574;12581.370931453574;12587.87412125867;12612.936935315265;12764.553240636533;11647.30116193031;11762.882371176522;12074.698132546757;12116.878831211576;12117.212381195193;10303.845442318521;10539.736506587396;11855.58505452305;11883.643490694947;12091.214071085351;11203.551857925591;11356.54573817038;11527.308361498337;11634.883651163718;12060.396980151132;9768.316217864965;10120.59517619287;10130.594776208869;10375.118114960313;10480.68557943263;10549.156067514426;10680.519376627915;10768;10772.838407423738;10791.298565593212 23:12:54 INFO - PROCESS | 6922 | __end_tp_report 23:12:54 INFO - PROCESS | 6922 | __start_cc_report 23:12:54 INFO - PROCESS | 6922 | _x_x_mozilla_cycle_collect,293 23:12:54 INFO - PROCESS | 6922 | __end_cc_report 23:12:54 INFO - PROCESS | 6922 | __startTimestamp1462947174072__endTimestamp 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | ------- Summary: start ------- 23:12:54 INFO - PROCESS | 6922 | Number of tests: 6 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#0] dojo.html Cycles:165 Average:5186.81 Median:5390.14 stddev:694.75 (12.9%) stddev-sans-first:677.28 23:12:54 INFO - PROCESS | 6922 | Values: 3098.4 3127.5 3156.4 3158.5 3158.7 5238.4 5245.9 5335.1 5337.4 5406.7 5156.4 5192.0 5222.8 5258.9 5305.4 4682.9 4715.0 4720.6 4728.9 4795.8 4753.3 4757.4 4774.0 4779.0 4781.0 507.8 4714.7 4743.4 4749.9 4770.2 4712.6 4717.5 4736.0 4744.2 4765.8 4687.6 4725.5 4726.5 4735.5 4743.6 5031.5 5160.0 5223.2 5239.8 5282.2 5635.6 5677.1 5684.1 5710.3 5723.2 5431.8 5712.3 5713.4 5725.1 5725.5 5230.8 5274.2 5275.1 5294.5 5319.6 5788.0 5797.2 5826.9 5829.4 5829.5 5380.0 5395.0 5544.2 5555.0 5559.5 5473.7 5484.9 5499.0 5530.5 5558.1 5435.5 5467.0 5468.1 5496.0 5500.4 5432.7 5469.5 5474.2 5483.2 5527.7 4173.2 4242.7 4246.2 4264.3 4278.0 3729.7 3786.8 3811.0 3839.3 3893.1 5233.3 5241.8 5255.4 5265.8 5338.3 5457.0 5618.5 5657.5 5682.6 5719.6 5371.7 5372.4 5382.0 5441.6 5451.6 4700.6 4918.7 4918.7 4920.9 4976.7 5752.4 5831.2 5831.6 5863.9 5895.8 5707.0 5712.9 5716.6 5731.1 5733.3 5663.1 5789.8 5866.1 5879.7 5887.2 5564.1 5595.4 5603.8 5604.6 5623.9 5652.3 5662.3 5676.5 5680.7 5713.9 5305.2 5417.6 5441.3 5544.9 5553.3 5492.8 5494.0 5514.6 5516.6 5533.1 5344.8 5356.0 5374.4 5382.6 5413.2 5319.8 5382.2 5461.9 5509.1 5576.8 5288.6 5373.5 5390.1 5426.3 5440.4 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#1] ext.html Cycles:180 Average:52375.54 Median:215914.61 stddev:103658.90 (48.0%) stddev-sans-first:103876.60 23:12:54 INFO - PROCESS | 6922 | Values: 530.8 566.1 595.3 609.4 645.7 37867.0 42070.6 42539.6 42552.4 42609.6 42312.4 42400.4 42592.0 42684.0 42824.8 8678.7 8850.9 8863.3 8867.2 8894.0 8698.7 8797.2 8812.2 8812.7 8819.3 8799.1 8808.6 8835.0 8855.5 8867.2 8796.6 8810.9 8830.6 8833.2 8838.2 8500.4 8530.3 8543.2 8544.2 8559.1 30818.5 39364.6 39579.0 39728.4 39877.2 3060.8 3072.6 3213.1 3290.1 3314.2 29699.6 30442.0 30465.5 30492.0 30770.2 4072.2 4095.6 4111.6 4198.1 4224.3 276195.0 276747.0 278007.0 278410.0 279133.0 33988.7 34105.1 34310.0 34639.5 35086.0 31205.4 31327.2 31413.7 31498.7 31537.8 14470.8 14708.9 14716.2 14757.6 14764.7 12383.1 12505.9 12538.7 12540.9 12558.9 8329.1 8361.6 8366.1 8372.5 8437.9 2945.9 2968.8 2976.3 2988.1 3006.5 1053.3 1056.3 1083.0 1128.4 1152.8 31267.8 31390.7 31834.4 31995.5 32025.2 1045.9 1126.0 1139.5 1191.5 1252.4 15287.5 15441.5 15621.6 15629.9 15749.7 464764.0 467972.0 472036.0 472037.0 475706.0 44454.8 44529.6 45072.5 45078.0 45317.0 1224.8 1358.7 1401.9 1404.2 1405.8 26446.5 26770.7 26916.0 26934.7 26955.5 49563.5 50146.7 50372.5 50508.2 50575.2 392759.0 400808.0 400900.0 401026.0 402957.0 27308.2 27656.2 27697.0 27742.5 27783.4 46032.3 46473.3 46757.5 47314.8 47436.5 38506.8 40139.2 40490.6 40540.0 41387.8 38823.0 40452.4 40799.6 40972.0 41576.4 30843.7 39070.2 40065.4 40773.2 41587.4 34364.7 34934.0 35431.6 35700.5 35845.6 26999.0 27480.7 27560.7 27739.4 27799.0 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#2] jquery.html Cycles:180 Average:28136.36 Median:18710.95 stddev:78893.75 (421.6%) stddev-sans-first:79087.55 23:12:54 INFO - PROCESS | 6922 | Values: 381.5 384.2 385.6 395.2 403.2 17176.3 19242.9 19929.7 19948.2 20084.3 16770.2 17640.8 17736.9 18104.6 18174.0 5065.1 5071.0 5213.5 5252.6 5262.4 5165.7 5261.4 5261.6 5279.8 5280.1 5248.1 5252.8 5260.3 5264.2 5311.7 5529.3 5676.6 5685.1 5688.7 5690.8 4980.9 5051.6 5087.0 5087.6 5091.1 21616.7 22453.9 22861.1 23066.5 23173.7 2291.5 2329.2 2429.0 2460.2 2593.9 10059.2 10139.7 10207.3 12391.3 14215.0 1907.6 1914.9 1929.4 1930.7 1952.0 82406.0 85327.6 87353.0 87499.0 87688.6 10082.9 10105.4 10266.9 10313.7 10434.2 14159.6 14165.9 14180.8 14192.5 14229.5 7170.8 7454.6 7523.0 7539.9 7637.4 6679.9 6749.5 6753.1 7043.3 7127.5 4285.8 4378.8 4455.3 4539.9 4784.2 1932.5 1986.5 2017.5 2040.9 2052.9 905.2 988.5 1005.2 1026.7 1088.2 17600.9 17615.5 18193.6 18455.4 19164.5 964.7 1017.2 1017.8 1067.7 1072.0 10023.3 10190.6 10279.9 10367.3 10636.6 465184.0 476210.0 479127.0 480727.0 483042.0 75301.6 96596.0 97369.0 97487.0 98383.0 863.9 884.6 973.2 1033.9 1040.1 16660.5 16867.0 17022.4 17036.2 17158.1 37040.4 38250.4 38717.8 39621.6 39792.6 53142.7 54218.7 54286.5 54464.0 54478.2 8025.1 8066.0 8103.3 8106.7 8387.9 9196.5 10851.5 11156.7 11932.1 11971.3 5629.8 7776.7 10343.2 10853.7 10872.9 10375.0 10506.6 10682.1 10778.6 11007.6 9793.7 10874.4 10917.9 11017.8 11202.9 7012.5 8188.3 8212.5 8250.5 8407.0 10005.3 10556.5 10769.9 10895.0 10899.1 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#3] mootools.html Cycles:180 Average:5546.02 Median:4649.15 stddev:3175.29 (68.3%) stddev-sans-first:3168.50 23:12:54 INFO - PROCESS | 6922 | Values: 1345.3 1453.4 1584.4 1762.9 1772.0 7442.9 7925.9 8054.9 8494.7 8550.4 8186.6 8463.1 8509.3 8544.0 8569.0 2115.9 2135.5 2136.1 2144.2 2159.5 2254.9 2278.4 2326.3 2330.2 2331.9 2265.5 2282.6 2290.6 2293.1 2295.6 2197.9 2225.1 2229.3 2232.0 2254.5 2129.7 2137.7 2140.5 2151.5 2158.0 8381.3 8449.0 8469.6 8480.2 8511.0 5066.7 5181.8 5204.5 5212.6 5218.7 3171.3 3244.9 3365.8 3380.7 3386.7 1042.6 1070.1 1081.1 1100.6 1107.8 11900.6 11913.8 11927.2 11972.0 12035.3 4410.2 4485.7 4539.1 4622.4 4626.7 10018.4 10028.3 10046.6 10049.4 10090.5 5063.2 5077.4 5173.0 5174.5 5206.1 4169.6 4500.9 4649.6 4684.3 4750.7 3849.1 3909.5 3935.1 3948.7 3971.9 1305.9 1340.1 1346.0 1349.9 1352.6 678.0 737.7 889.8 905.2 919.5 5294.4 5327.9 5385.7 5444.2 5475.6 902.5 903.3 912.9 919.8 928.0 3611.5 3643.2 3661.9 3695.5 3726.6 10197.5 10486.4 10543.3 10555.1 10616.2 10411.1 10513.7 10520.2 10557.5 10571.9 7372.2 7793.0 7823.7 7827.9 7962.4 5534.2 5571.7 5649.6 5659.3 5710.9 9233.7 9515.3 9522.9 9625.0 9715.2 9672.2 9852.9 9948.2 10141.0 10161.0 6708.5 7050.6 7266.4 7375.1 7553.2 8409.0 8419.0 8451.8 8457.0 8464.3 6882.6 7305.4 7514.7 7600.9 7647.2 7107.9 7235.6 7249.5 7328.3 7386.2 6675.1 6735.0 6745.0 6749.8 6790.3 4451.2 4553.7 4582.2 4624.5 4683.0 4614.9 4645.2 4648.7 4707.7 4728.6 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#4] prototype.html Cycles:180 Average:4754.17 Median:5429.61 stddev:1408.97 (25.9%) stddev-sans-first:1373.09 23:12:54 INFO - PROCESS | 6922 | Values: 322.4 328.9 330.0 332.7 335.3 5291.3 5435.0 5485.3 5492.6 5516.4 5354.8 5359.9 5365.0 5395.8 5414.7 3977.0 3990.7 4142.5 4155.8 4176.0 4154.2 4155.9 4165.3 4179.2 4187.7 4110.4 4116.2 4130.0 4139.4 4139.8 4013.2 4065.3 4119.9 4147.3 4159.8 3650.7 3659.9 3667.3 3680.3 3686.9 5166.5 5186.0 5206.3 5218.7 5245.5 4663.6 4729.0 4750.7 4789.9 4842.5 5556.8 5559.3 5574.0 5619.2 5650.3 4016.0 4032.0 4050.5 4060.1 4060.2 5675.5 5836.6 5846.1 5876.6 5887.4 4120.4 4128.4 4132.5 4134.9 4145.2 4126.3 4136.4 4168.3 4174.3 4187.2 4702.2 4767.5 4767.8 4782.5 4802.4 5646.0 5674.3 5684.0 5684.2 5700.1 1427.9 1430.3 1438.0 1450.9 1452.6 909.6 936.4 940.8 957.9 986.3 5321.0 5406.0 5410.2 5416.9 5448.0 5645.5 5755.9 5761.2 5772.3 5776.8 5146.8 5344.9 5345.4 5458.9 5504.9 3071.5 3116.6 3125.0 3128.2 3134.5 5757.9 6030.8 6032.2 6043.2 6047.4 6010.7 6026.9 6029.5 6060.9 6066.4 6010.5 6079.8 6086.9 6107.8 6156.9 5511.9 5756.1 5851.0 5853.1 5855.9 5772.9 5774.5 5792.8 5817.4 5849.9 5820.6 6023.5 6101.3 6108.4 6141.1 4175.2 4209.2 4213.4 4218.6 4224.2 5717.3 5719.5 5724.2 5738.3 5751.3 5450.1 5641.4 5676.9 5690.7 5768.9 5415.2 5662.1 5693.4 5724.9 5744.5 5280.0 5424.2 5554.5 5591.9 5623.2 5626.3 5697.6 5701.2 5708.5 5735.3 5507.3 5530.9 5557.3 5572.4 5587.2 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - PROCESS | 6922 | [#5] yui.html Cycles:180 Average:15006.97 Median:4419.76 stddev:18319.27 (414.5%) stddev-sans-first:18339.29 23:12:54 INFO - PROCESS | 6922 | Values: 730.4 748.2 761.7 787.4 799.6 13309.4 13415.2 14013.7 14291.8 14497.6 14652.1 15317.4 15317.5 15325.7 15448.8 532.3 535.0 536.1 536.2 537.1 510.0 534.9 535.9 541.4 542.5 565.3 565.7 569.0 570.0 572.6 561.8 563.6 565.0 566.1 567.0 400.9 401.8 402.3 403.7 404.1 12493.9 12502.2 12503.4 12510.6 12568.7 12920.3 13440.5 13446.3 13539.3 13840.4 10136.2 10228.6 10295.5 10300.4 10304.7 3633.7 3650.9 3705.4 3731.1 3735.9 58685.4 58809.4 59325.4 59354.7 59795.4 10412.1 10490.1 10576.5 10642.3 10663.7 19017.8 19018.3 19043.6 19083.7 19087.4 7067.7 7097.9 7137.7 7219.0 7304.0 4417.2 4471.0 4563.1 4584.6 4606.6 2930.9 2985.8 3001.0 3002.7 3019.0 580.7 586.8 586.9 590.6 616.5 556.4 573.9 574.1 575.8 598.2 8817.9 8915.9 9031.6 9105.5 9269.4 568.2 582.8 629.4 629.7 633.9 4293.5 4422.3 4466.6 4543.3 4618.5 54346.2 55889.2 58729.0 64693.4 67387.7 59505.7 59608.0 60114.7 60151.7 62445.0 55228.7 55783.4 56240.7 56429.0 56618.4 8660.2 8685.9 8791.8 8873.0 8878.7 34375.8 34816.8 35004.3 35045.0 35460.5 48089.0 49214.5 49282.8 50044.5 50048.5 10755.2 10943.1 11078.2 11159.0 11431.1 12579.4 12581.4 12587.9 12612.9 12764.6 11647.3 11762.9 12074.7 12116.9 12117.2 10303.8 10539.7 11855.6 11883.6 12091.2 11203.6 11356.5 11527.3 11634.9 12060.4 9768.3 10120.6 10130.6 10375.1 10480.7 10549.2 10680.5 10768.0 10772.8 10791.3 23:12:54 INFO - PROCESS | 6922 | -------- Summary: end -------- 23:12:54 INFO - PROCESS | 6922 | 23:12:54 INFO - TEST-INFO | 6922: exit 0 23:12:54 INFO - TEST-OK | dromaeo_css | took 1108057ms 23:12:54 INFO - TEST-START | kraken 23:12:54 INFO - operating with platform_type : linux_ 23:12:54 INFO - Initialising browser for kraken test... 23:12:54 INFO - TEST-INFO | started process 7156 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprrtj96/profile http://localhost:51308/getInfo.html) 23:12:58 INFO - PROCESS | 7156 | 23:12:58 INFO - PROCESS | 7156 | (process:7226): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:12:58 INFO - PROCESS | 7156 | __metrics Screen width/height:1600/1200 23:12:58 INFO - PROCESS | 7156 | colorDepth:24 23:12:58 INFO - PROCESS | 7156 | Browser inner width/height: 1024/697 23:12:58 INFO - PROCESS | 7156 | __metrics 23:12:59 ERROR - PROCESS | 7156 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:13:00 INFO - TEST-INFO | 7156: exit 0 23:13:00 INFO - Browser initialized. 23:13:00 INFO - Running cycle 1/1 for kraken test... 23:13:00 INFO - TEST-INFO | started process 7277 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprrtj96/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:01 INFO - PROCESS | 7277 | 23:13:01 INFO - PROCESS | 7277 | (process:7338): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:13:11 INFO - PROCESS | 7277 | RSS: Main: 162246656 23:13:11 INFO - PROCESS | 7277 | 23:13:14 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51308/tests/kraken/driver.html?testName=audio-beat-detection) 23:13:15 INFO - PROCESS | 7277 | RSS: Main: 167030784 23:13:15 INFO - PROCESS | 7277 | 23:13:17 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51308/tests/kraken/driver.html?testName=audio-dft) 23:13:18 INFO - PROCESS | 7277 | RSS: Main: 166821888 23:13:18 INFO - PROCESS | 7277 | 23:13:21 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51308/tests/kraken/driver.html?testName=audio-fft) 23:13:21 INFO - PROCESS | 7277 | RSS: Main: 161902592 23:13:21 INFO - PROCESS | 7277 | 23:13:24 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51308/tests/kraken/driver.html?testName=audio-oscillator) 23:13:24 INFO - PROCESS | 7277 | RSS: Main: 161755136 23:13:24 INFO - PROCESS | 7277 | 23:13:26 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51308/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:13:27 INFO - PROCESS | 7277 | RSS: Main: 162144256 23:13:27 INFO - PROCESS | 7277 | 23:13:30 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51308/tests/kraken/driver.html?testName=imaging-darkroom) 23:13:31 INFO - PROCESS | 7277 | RSS: Main: 162754560 23:13:31 INFO - PROCESS | 7277 | 23:13:34 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51308/tests/kraken/driver.html?testName=imaging-desaturate) 23:13:35 INFO - PROCESS | 7277 | RSS: Main: 161038336 23:13:35 INFO - PROCESS | 7277 | 23:13:38 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51308/tests/kraken/driver.html?testName=json-parse-financial) 23:13:38 INFO - PROCESS | 7277 | RSS: Main: 162009088 23:13:38 INFO - PROCESS | 7277 | 23:13:40 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51308/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:13:41 INFO - PROCESS | 7277 | RSS: Main: 162140160 23:13:41 INFO - PROCESS | 7277 | 23:13:42 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:13:43 INFO - PROCESS | 7277 | RSS: Main: 162590720 23:13:43 INFO - PROCESS | 7277 | 23:13:45 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:13:46 INFO - PROCESS | 7277 | RSS: Main: 162988032 23:13:46 INFO - PROCESS | 7277 | 23:13:48 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:13:49 INFO - PROCESS | 7277 | RSS: Main: 163110912 23:13:49 INFO - PROCESS | 7277 | 23:13:52 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:13:53 INFO - PROCESS | 7277 | RSS: Main: 162893824 23:13:53 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:51308/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51308/tests/kraken/driver.html?testName=ai-astar) 23:13:54 INFO - PROCESS | 7277 | RSS: Main: 164372480 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | __start_tp_report 23:13:54 INFO - PROCESS | 7277 | _x_x_mozilla_page_load 23:13:54 INFO - PROCESS | 7277 | _x_x_mozilla_page_load_details 23:13:54 INFO - PROCESS | 7277 | |i|pagename|runs| 23:13:54 INFO - PROCESS | 7277 | |0;ai-astar;109;112;105;112;114;114;106;112;114;111 23:13:54 INFO - PROCESS | 7277 | |1;audio-beat-detection;154;130;155;209;136;158;154;132;184;131 23:13:54 INFO - PROCESS | 7277 | |2;audio-dft;165;163;165;164;163;163;168;179;165;162 23:13:54 INFO - PROCESS | 7277 | |3;audio-fft;78;78;77;161;77;77;76;78;167;78 23:13:54 INFO - PROCESS | 7277 | |4;audio-oscillator;94;93;174;92;93;93;139;95;95;139 23:13:54 INFO - PROCESS | 7277 | |5;imaging-gaussian-blur;118;121;118;117;118;119;118;118;118;118 23:13:54 INFO - PROCESS | 7277 | |6;imaging-darkroom;149;150;148;147;150;150;150;148;148;148 23:13:54 INFO - PROCESS | 7277 | |7;imaging-desaturate;99;96;96;96;97;96;97;96;96;95 23:13:54 INFO - PROCESS | 7277 | |8;json-parse-financial;58;58;58;57;57;58;57;110;55;55 23:13:54 INFO - PROCESS | 7277 | |9;json-stringify-tinderbox;60;59;60;60;60;59;60;59;66;59 23:13:54 INFO - PROCESS | 7277 | |10;stanford-crypto-aes;82;81;86;80;83;85;87;83;84;81 23:13:54 INFO - PROCESS | 7277 | |11;stanford-crypto-ccm;145;136;131;139;131;145;150;137;194;132 23:13:54 INFO - PROCESS | 7277 | |12;stanford-crypto-pbkdf2;170;169;169;172;170;175;180;171;170;169 23:13:54 INFO - PROCESS | 7277 | |13;stanford-crypto-sha256-iterative;67;68;67;67;66;65;68;67;67;65 23:13:54 INFO - PROCESS | 7277 | __end_tp_report 23:13:54 INFO - PROCESS | 7277 | __start_cc_report 23:13:54 INFO - PROCESS | 7277 | _x_x_mozilla_cycle_collect,742 23:13:54 INFO - PROCESS | 7277 | __end_cc_report 23:13:54 INFO - PROCESS | 7277 | __startTimestamp1462947234905__endTimestamp 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | ------- Summary: start ------- 23:13:54 INFO - PROCESS | 7277 | Number of tests: 14 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.25 (2.9%) stddev-sans-first:3.37 23:13:54 INFO - PROCESS | 7277 | Values: 109.0 112.0 105.0 112.0 114.0 114.0 106.0 112.0 114.0 111.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#1] audio-beat-detection Cycles:10 Average:154.30 Median:154.50 stddev:25.50 (16.5%) stddev-sans-first:27.05 23:13:54 INFO - PROCESS | 7277 | Values: 154.0 130.0 155.0 209.0 136.0 158.0 154.0 132.0 184.0 131.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#2] audio-dft Cycles:10 Average:165.70 Median:165.00 stddev:4.97 (3.0%) stddev-sans-first:5.26 23:13:54 INFO - PROCESS | 7277 | Values: 165.0 163.0 165.0 164.0 163.0 163.0 168.0 179.0 165.0 162.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#3] audio-fft Cycles:10 Average:94.70 Median:77.50 stddev:36.56 (47.2%) stddev-sans-first:38.27 23:13:54 INFO - PROCESS | 7277 | Values: 78.0 78.0 77.0 161.0 77.0 77.0 76.0 78.0 167.0 78.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#4] audio-oscillator Cycles:10 Average:110.70 Median:93.00 stddev:29.19 (31.4%) stddev-sans-first:30.33 23:13:54 INFO - PROCESS | 7277 | Values: 94.0 93.0 174.0 92.0 93.0 93.0 139.0 95.0 95.0 139.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 23:13:54 INFO - PROCESS | 7277 | Values: 118.0 121.0 118.0 117.0 118.0 119.0 118.0 118.0 118.0 118.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.50 stddev:1.14 (0.8%) stddev-sans-first:1.20 23:13:54 INFO - PROCESS | 7277 | Values: 149.0 150.0 148.0 147.0 150.0 150.0 150.0 148.0 148.0 148.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.07 (1.1%) stddev-sans-first:0.60 23:13:54 INFO - PROCESS | 7277 | Values: 99.0 96.0 96.0 96.0 97.0 96.0 97.0 96.0 96.0 95.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:57.50 stddev:16.80 (29.2%) stddev-sans-first:17.75 23:13:54 INFO - PROCESS | 7277 | Values: 58.0 58.0 58.0 57.0 57.0 58.0 57.0 110.0 55.0 55.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:2.10 (3.5%) stddev-sans-first:2.22 23:13:54 INFO - PROCESS | 7277 | Values: 60.0 59.0 60.0 60.0 60.0 59.0 60.0 59.0 66.0 59.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:2.30 (2.8%) stddev-sans-first:2.40 23:13:54 INFO - PROCESS | 7277 | Values: 82.0 81.0 86.0 80.0 83.0 85.0 87.0 83.0 84.0 81.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:142.00 stddev:18.73 (13.2%) stddev-sans-first:19.86 23:13:54 INFO - PROCESS | 7277 | Values: 145.0 136.0 131.0 139.0 131.0 145.0 150.0 137.0 194.0 132.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:170.50 stddev:3.50 (2.1%) stddev-sans-first:3.67 23:13:54 INFO - PROCESS | 7277 | Values: 170.0 169.0 169.0 172.0 170.0 175.0 180.0 171.0 170.0 169.0 23:13:54 INFO - PROCESS | 7277 | 23:13:54 INFO - PROCESS | 7277 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 23:13:54 INFO - PROCESS | 7277 | Values: 67.0 68.0 67.0 67.0 66.0 65.0 68.0 67.0 67.0 65.0 23:13:54 INFO - PROCESS | 7277 | -------- Summary: end -------- 23:13:54 INFO - PROCESS | 7277 | 23:13:55 INFO - TEST-INFO | 7277: exit 0 23:13:55 INFO - TEST-OK | kraken | took 61111ms 23:13:55 INFO - SUITE-END | took 1169s 23:13:56 INFO - Completed test suite (00:19:29) 23:13:56 INFO - javascript benchmark 23:13:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.395812816502, 3127.49960006399, 3156.384505021522, 3158.4946408574633, 3158.7315078218344, 5238.397584277809, 5245.895082098361, 5335.0663633864115, 5337.359516857973, 5406.702631355274, 5156.407013193484, 5191.974040129803, 5222.817201397952, 5258.92111618326, 5305.389880163782, 4682.882927926812, 4714.952850471503, 4720.5515476029805, 4728.929066064012, 4795.760211989404, 4753.263244197155, 4757.429108506966, 4773.997460533292, 4779.0, 4780.952190478103, 507.7655533014698, 4714.717116972993, 4743.35964644772, 4749.90500189996, 4770.16522108633, 4712.6229901607785, 4717.504662010474, 4736.0, 4744.240921452585, 4765.785539650689, 4687.578117969365, 4725.527447255247, 4726.527347265281, 4735.455422626407, 4743.573078422958, 5031.52200540948, 5159.974200129013, 5223.2165175223645, 5239.842804715827, 5282.2076688496645, 5635.6055076144685, 5677.091665333568, 5684.0905455127395, 5710.2862142232225, 5723.198752174699, 5431.83704488866, 5712.257406537124, 5713.371529131792, 5725.055365864628, 5725.513331366838, 5230.843074707765, 5274.156135018378, 5275.103232450492, 5294.523492885659, 5319.5744340452675, 5788.0, 5797.159411885253, 5826.883462330729, 5829.4170582942215, 5829.504492118217, 5380.0, 5394.974954758662, 5544.1960915667005, 5555.0, 5559.499645031967, 5473.7263136843, 5484.91772623411, 5499.0, 5530.5299049580435, 5558.082916318762, 5435.510804027656, 5467.043267428264, 5468.070428027245, 5496.0, 5500.4224556420995, 5432.728363581805, 5469.453054694578, 5474.206240095164, 5483.232347471358, 5527.695976721238, 4173.248815213291, 4242.745435273893, 4246.214450326699, 4264.275073237557, 4278.0374415756205, 3729.7202709796875, 3786.8106594670157, 3810.961890381116, 3839.251345987506, 3893.143508428141, 5233.267342571967, 5241.816536421284, 5255.421903590602, 5265.842024739264, 5338.252644629755, 5456.963176996437, 5618.522425593788, 5657.462541058593, 5682.602217844711, 5719.628224165416, 5371.704556249444, 5372.355317361941, 5381.973090134525, 5441.59188060897, 5451.64564303319, 4700.55344742249, 4918.6556941014505, 4918.704877707349, 4920.852374428773, 4976.701397916137, 5752.395998420189, 5831.212786273799, 5831.562632802556, 5863.941360586425, 5895.793647222326, 5707.0, 5712.885742285048, 5716.5998380113, 5731.111677689964, 5733.283339582552, 5663.122216056518, 5789.79735709265, 5866.120081987736, 5879.73541190656, 5887.20522729426, 5564.054110801093, 5595.412481689526, 5603.7758489659955, 5604.635698679573, 5623.943760562342, 5652.349979752216, 5662.320521537442, 5676.489115979582, 5680.715964201856, 5713.9142912855505, 5305.204219367126, 5417.6478528895495, 5441.2654291670115, 5544.916826247691, 5553.333599968029, 5492.835214943558, 5494.0, 5514.641548299348, 5516.641418307797, 5533.087040638249, 5344.812931547377, 5355.955588660174, 5374.408815030265, 5382.623216374816, 5413.1880217966045, 5319.840404787863, 5382.219578161066, 5461.863453413696, 5509.118541033416, 5576.804811831566, 5288.550473209821, 5373.489518495737, 5390.137577987503, 5426.2674538936735, 5440.42875498067], "value": 5142.636673942006, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.8101761638607, 566.0631654611617, 595.3332267859997, 609.3723464831224, 645.7320212111973, 37867.0, 42070.57929420705, 42539.57460425395, 42552.361714574305, 42609.57390426095, 42312.365314520306, 42400.36399454011, 42592.0, 42684.0, 42824.78587607058, 8678.652853885853, 8850.911490885073, 8863.335249856254, 8867.24628406583, 8893.95553022234, 8698.739037828875, 8797.208251257385, 8812.206901378875, 8812.691555795549, 8819.294456443502, 8799.120087991214, 8808.603612837405, 8835.0, 8855.512946787923, 8867.201951824334, 8796.56017199144, 8810.86783698245, 8830.602622881986, 8833.249173820233, 8838.248748856355, 8500.362472814502, 8530.274926631244, 8543.231109200202, 8544.18830211129, 8559.058503564604, 30818.53772193419, 39364.60635393638, 39579.0, 39728.40407393892, 39877.20245595101, 3060.831654259015, 3072.5544796004456, 3213.084270982782, 3290.0952238134464, 3314.2211580278617, 29699.554506682416, 30442.0, 30465.543016854765, 30492.0, 30770.23074423135, 4072.165206132736, 4095.610916962884, 4111.60939710727, 4198.118395137025, 4224.345226489899, 276195.0, 276747.0, 278007.0, 278410.0, 279133.0, 33988.66011339904, 34105.147371315914, 34310.0, 34639.480407793904, 35086.0, 31205.375892482025, 31327.21681957969, 31413.685863141076, 31498.685013149574, 31537.842310788757, 14470.782938255934, 14708.926455367655, 14716.19060951658, 14757.557273281818, 14764.671179593888, 12383.133180677447, 12505.874941250653, 12538.686532836751, 12540.871321581102, 12558.874411255953, 8329.08380078191, 8361.623726932303, 8366.121557236524, 8372.497650141011, 8437.873431898526, 2945.9263518412213, 2968.7773416993814, 2976.3303256767053, 2988.148377712385, 3006.5039268520886, 1053.2837670384213, 1056.3081181825917, 1082.951714673557, 1128.4258459449022, 1152.827075938616, 31267.84366078155, 31390.686093139233, 31834.363312733614, 31995.52006719901, 32025.19937001547, 1045.895410458948, 1125.9943700281447, 1139.4530625299903, 1191.4995701805262, 1252.3738130934532, 15287.541373758804, 15441.459548915735, 15621.609459763597, 15629.921850390676, 15749.68500629981, 464764.0, 467972.0, 472036.0, 472037.0, 475706.0, 44454.77772611116, 44529.55470445254, 45072.54927450683, 45078.0, 45317.0, 1224.7795396828478, 1358.7010857611508, 1401.9439222430988, 1404.206623257856, 1405.8383285922046, 26446.471070578864, 26770.732292677603, 26916.0, 26934.730652694005, 26955.460890782466, 49563.50436495589, 50146.749266253435, 50372.49627503678, 50508.24237636512, 50575.241371378725, 392759.0, 400808.0, 400900.0, 401026.0, 402957.0, 27308.180754577395, 27656.170314890587, 27697.0, 27742.47416392118, 27783.444331113667, 46032.30951535796, 46473.30290045584, 46757.532424676676, 47314.763426182646, 47436.52563474459, 38506.80214731465, 40139.19373628252, 40490.59509404868, 40540.0, 41387.7930610345, 38823.0, 40452.36109456279, 40799.59200407958, 40972.0, 41576.376354355314, 30843.69156308408, 39070.21859562736, 40065.39901901532, 40773.184536308516, 41587.376189356575, 34364.65635343615, 34934.0, 35431.64568354283, 35700.46449303314, 35845.641543585276, 26999.0, 27480.725192748618, 27560.72439275662, 27739.44521109607, 27799.0], "value": 17703.34735232176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4570064090753, 384.20853042731954, 385.5835697446758, 395.24705436144154, 403.1916008403151, 17176.3129474821, 19242.90378548109, 19929.701054484194, 19948.202071917105, 20084.29704960323, 16770.245338959747, 17640.823591764078, 17736.911315443438, 18104.637907241846, 18174.0, 5065.113605119108, 5070.974645126778, 5213.530782229599, 5252.606054545905, 5262.4474430184855, 5165.741712914358, 5261.368635763713, 5261.552768014723, 5279.788808447657, 5280.102382594949, 5248.0553500369915, 5252.818115923906, 5260.263563101169, 5264.236685680574, 5311.681299122046, 5529.281193444846, 5676.631018983753, 5685.147227915846, 5688.687122208318, 5690.772369105231, 4980.9252861207115, 5051.646384753069, 5086.974565127188, 5087.643864929457, 5091.083604951107, 21616.675749863763, 22453.887730561408, 22861.085556577716, 23066.53866922669, 23173.652395214085, 2291.4958709083976, 2329.208069256444, 2428.9514209715885, 2460.225029115823, 2593.910557565808, 10059.195264378905, 10139.746506337327, 10207.323774791443, 12391.256524608552, 14215.0, 1907.6280125375558, 1914.8659593828438, 1929.353666521703, 1930.6814375628146, 1951.980480195194, 82406.0, 85327.57336213341, 87353.0, 87499.0, 87688.56155719244, 10082.899171008195, 10105.44420056904, 10266.897331026743, 10313.690589282334, 10434.217433692504, 14159.646008849655, 14165.858341416453, 14180.787288190686, 14192.503262385973, 14229.50196743109, 7170.784876453715, 7454.589997550078, 7523.022007139037, 7539.924600754032, 7637.427192960439, 6679.9332006679315, 6749.493787965922, 6753.08833307507, 7043.2752024643105, 7127.536710113826, 4285.785710714452, 4378.759168245713, 4455.331700244924, 4539.931901021488, 4784.210605250095, 1932.4589115047809, 1986.5232344237568, 2017.5157962089286, 2040.9387718368473, 2052.8973551322374, 905.180811365715, 988.461288597716, 1005.1606908231562, 1026.707388394304, 1088.1566785740956, 17600.91199543994, 17615.471535853943, 18193.63612727738, 18455.354062607745, 19164.520886977658, 964.6768332608655, 1017.2472370445964, 1017.7964407118458, 1067.6636859389423, 1071.9571217151226, 10023.34848234877, 10190.643327483502, 10279.94860025695, 10367.32612380208, 10636.627718029986, 465184.0, 476210.0, 479127.0, 480727.0, 483042.0, 75301.62349188219, 96596.0, 97369.0, 97487.0, 98383.0, 863.87905693203, 884.6196135661598, 973.2165606686543, 1033.9224558158169, 1040.1003132290364, 16660.500184993984, 16866.987980721195, 17022.404215852384, 17036.233369498153, 17158.05630690324, 37040.444393333586, 38250.426243606926, 38717.806410967765, 39621.60378396294, 39792.60207397889, 53142.73428632832, 54218.728906355216, 54286.45713542814, 54464.0, 54478.18282725683, 8025.117237104031, 8065.9596702016115, 8103.270705636521, 8106.716264930935, 8387.916120838958, 9196.494192819191, 10851.457427128455, 11156.665300041012, 11932.1050921183, 11971.281723096643, 5629.774809007594, 7776.688932442639, 10343.224258180517, 10853.728656783327, 10872.945635271773, 10375.0, 10506.579736810441, 10682.092022178202, 10778.622748204089, 11007.559697612005, 9793.657221997197, 10874.401907895142, 10917.945410273216, 11017.83473247918, 11202.88797112051, 7012.474064445085, 8188.2630563249595, 8212.548309843016, 8250.54621995772, 8406.957965210135, 10005.299629025898, 10556.524956376828, 10769.892301076889, 10895.0, 10899.128069754242], "value": 8769.43928540833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.340783016322, 1453.4258967707756, 1584.4296053420774, 1762.9206685699141, 1771.9911400443004, 7442.851142977137, 7925.881111783321, 8054.91945080549, 8494.660213591456, 8550.35872309577, 8186.590670466483, 8463.111373305806, 8509.276711479532, 8543.957280213606, 8569.0, 2115.9365219043416, 2135.455458857989, 2136.134865379524, 2144.2173606633546, 2159.492519257973, 2254.875981820999, 2278.3734473019886, 2326.2788535553946, 2330.1727886600265, 2331.9300420987397, 2265.5129147233342, 2282.5777231212196, 2290.6220473621834, 2293.140072472823, 2295.6455691142223, 2197.8571392859412, 2225.054351900436, 2229.320057382498, 2231.9956019791157, 2254.537819746948, 2129.7444306683296, 2137.7434707835005, 2140.5397839464435, 2151.4513798981316, 2157.9784202158094, 8381.329493640555, 8449.0, 8469.576521173916, 8480.194381533744, 8510.957445212796, 5066.695998240118, 5181.766820493049, 5204.479552044803, 5212.582993360522, 5218.712970786605, 3171.302313491028, 3244.902652920416, 3365.814880181589, 3380.6957373836226, 3386.72906167506, 1042.6090216168936, 1070.149231361062, 1081.1350919264557, 1100.5817789240118, 1107.7673688525338, 11900.583479578174, 11913.821292680617, 11927.224730392496, 11972.0, 12035.338056406894, 4410.16206920684, 4485.708428952108, 4539.0921815637, 4622.399088118523, 4626.699264547794, 10018.448985305879, 10028.298019138736, 10046.598136074475, 10049.397036177706, 10090.49547522621, 5063.240513922941, 5077.441481437039, 5172.9741351293005, 5174.534291913746, 5206.088934436459, 4169.624733773975, 4500.932486012713, 4649.558291962258, 4684.250519916797, 4750.738709371018, 3849.1339448624394, 3909.4526766252748, 3935.0752573145287, 3948.7038472114705, 3971.92056158881, 1305.8563558008611, 1340.1021315718492, 1345.9663508412368, 1349.919004859692, 1352.5671785028696, 678.0405725897898, 737.6606760889944, 889.7597648634962, 905.2395987370641, 919.4575200631525, 5294.364676238876, 5327.946720532823, 5385.676859388449, 5444.237806707064, 5475.616706830563, 902.4585248850768, 903.2548147778109, 912.9315301352292, 919.8022425178618, 928.034843762493, 3611.5485564304463, 3643.216708407705, 3661.9450708239397, 3695.501107350527, 3726.5900750917376, 10197.490125493696, 10486.37081775096, 10543.31468454548, 10555.050045495942, 10616.203784716177, 10411.11505522024, 10513.684589462327, 10520.210984176218, 10557.524911379007, 10571.894281057244, 7372.189059203483, 7792.961035194901, 7823.726169584151, 7827.882581761278, 7962.442629016029, 5534.169874518854, 5571.721413929206, 5649.6327738696855, 5659.292588426447, 5710.885782284414, 9233.722988310361, 9515.286353523652, 9522.857157142787, 9624.951875240858, 9715.22278217755, 9672.226221902372, 9852.916179220138, 9948.154406875206, 10141.036601522843, 10161.0, 6708.463322934056, 7050.647467626701, 7266.38235749946, 7375.114986201475, 7553.244675532623, 8409.0, 8418.957905210435, 8451.830963380575, 8457.0, 8464.322854171529, 6882.5870447773295, 7305.379042781211, 7514.699412023458, 7600.885986710315, 7647.235276472308, 7107.857842843217, 7235.63821808897, 7249.528780629243, 7328.3404493595835, 7386.15059268191, 6675.132232809704, 6735.0, 6744.966275168593, 6749.831254218684, 6790.253072162157, 4451.221036318696, 4553.658475614408, 4582.243929751687, 4624.514425985223, 4682.953170468251, 4614.861554153381, 4645.187092258773, 4648.7443190624845, 4707.693999889996, 4728.62171026324], "value": 4435.601566082869, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3906816117537, 328.92270316475623, 329.96535363786796, 332.71885256957864, 335.2774770369854, 5291.312129423174, 5434.972825135878, 5485.341758988922, 5492.5605951523885, 5516.448355164482, 5354.8219391733755, 5359.919601205985, 5365.007473617374, 5395.838124856251, 5414.702191379472, 3976.985868603509, 3990.7006974476885, 4142.467287103776, 4155.771432571208, 4175.958240417603, 4154.169166166779, 4155.854545090922, 4165.33354663254, 4179.247735407625, 4187.706860519765, 4110.362893751473, 4116.16830510423, 4129.958700413003, 4139.3997870308785, 4139.813708383115, 4013.2174226025936, 4065.329220678599, 4119.917601647965, 4147.294959856831, 4159.812808423628, 3650.7261955353456, 3659.9085022874374, 3667.2665466906724, 3680.2823449427374, 3686.870959516404, 5166.509181627739, 5185.974070129663, 5206.271122042917, 5218.6868787872845, 5245.4754524547625, 4663.610853131232, 4728.976355118202, 4750.714957102585, 4789.880252993668, 4842.515748425165, 5556.833295001156, 5559.305086864142, 5574.0, 5619.185218143387, 5650.265465489501, 4015.9799201003807, 4031.9798401007806, 4050.4936882889638, 4060.1473690525027, 4060.2488539620263, 5675.489205971441, 5836.562257830679, 5846.123081537761, 5876.559258055663, 5887.381824908366, 4120.402541631506, 4128.399108561647, 4132.504099508018, 4134.937975930364, 4145.233131870553, 4126.287590649907, 4136.400221967858, 4168.311833707354, 4174.290370636999, 4187.20443115807, 4702.224133018014, 4767.451743049524, 4767.785449654774, 4782.521747825259, 4802.399700037496, 5646.040173170571, 5674.290713660793, 5683.976884160808, 5684.204211410406, 5700.087985922232, 1427.8577138289327, 1430.2705620133806, 1437.9862197150983, 1450.9056911300731, 1452.5860129862938, 909.5770466733001, 936.3866667332908, 940.8118376324762, 957.9079848972177, 986.28001558861, 5320.973395133, 5405.97255044994, 5410.161424979135, 5416.945830541723, 5448.0, 5645.5201307888465, 5755.899096829627, 5761.193432919395, 5772.336181339199, 5776.768929242868, 5146.794128234828, 5344.85085706575, 5345.438728933484, 5458.8908221835345, 5504.91742623861, 3071.5239137933654, 3116.5948426704836, 3125.0, 3128.1700205380107, 3134.5298205268937, 5757.8560535986935, 6030.7889223876955, 6032.215811944419, 6043.244594425697, 6047.425494578008, 6010.729517171738, 6026.920712354318, 6029.487493563096, 6060.909086363708, 6066.393360663987, 6010.459058684652, 6079.817605471843, 6086.904357215655, 6107.755689772448, 6156.907646385308, 5511.877926998215, 5756.136579513107, 5851.0, 5853.0635098384055, 5855.9121613176685, 5772.94227057724, 5774.509166720875, 5792.82621521355, 5817.360090389969, 5849.912251316318, 5820.563457740602, 6023.488003519575, 6101.328853825987, 6108.389161084034, 6141.1402403664415, 4175.248455277958, 4209.179210054132, 4213.41012258278, 4218.578142185879, 4224.176285624274, 5717.342505611908, 5719.48524632785, 5724.169995350734, 5738.340090889601, 5751.252337196138, 5450.1007333789485, 5641.351244606923, 5676.9432305678065, 5690.658560486384, 5768.913466298093, 5415.241866138665, 5662.094064949588, 5693.373728889902, 5724.883647688661, 5744.472432129849, 5280.023195708806, 5424.24060631504, 5554.527865131509, 5591.924441557046, 5623.2127502148915, 5626.324841019104, 5697.561755216579, 5701.201831743476, 5708.457696518824, 5735.283089613798, 5507.311586051744, 5530.9259027864555, 5557.332480409507, 5572.4148964358155, 5587.161925711176], "value": 4302.253218866912, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.3901242462543, 748.1994266135234, 761.7257787196605, 787.3583029830692, 799.6321692021671, 13309.401076951537, 13415.19508829471, 14013.719725605482, 14291.785623215661, 14497.637559061015, 14652.120872747642, 15317.387304507807, 15317.540473785777, 15325.69348613027, 15448.768268475982, 532.2601583798513, 535.0209117315324, 536.1314670234226, 536.1582315764232, 537.1432565058725, 509.95155460231217, 534.9465053494639, 535.8633548445141, 541.4125673644081, 542.4521233554119, 565.2905603467663, 565.6747370262095, 569.0099227344411, 569.977200911965, 572.6277919352433, 561.831450564831, 563.6251892491488, 564.9632773869686, 566.0970751651109, 567.0162268464211, 400.93785463253244, 401.84930651005874, 402.3481959226043, 403.73151854017044, 404.0887798015486, 12493.93753031229, 12502.187357821713, 12503.437345319482, 12510.562130325396, 12568.685782855411, 12920.289384083871, 13440.529581464602, 13446.260455674934, 13539.255340956244, 13840.377183026789, 10136.189104871593, 10228.641997530125, 10295.485225738683, 10300.433476158809, 10304.690859274233, 3633.691136253395, 3650.945235821465, 3705.3886108792017, 3731.057116249404, 3735.8505659773577, 58685.41314586842, 58809.41190588082, 59325.40674593242, 59354.70322648445, 59795.40204597942, 10412.125817352799, 10490.058555432921, 10576.471176441148, 10642.308249963882, 10663.733406664895, 19017.80982190188, 19018.334358297394, 19043.61912761737, 19083.713744293847, 19087.42737717871, 7067.681954312068, 7097.9290207097265, 7137.714491420389, 7219.0, 7304.013958115693, 4417.182821178092, 4470.955290447119, 4563.06457176278, 4584.564466375689, 4606.631469482433, 2930.8827646893888, 2985.7611391088662, 3000.9849950750545, 3002.7147420995407, 3019.0, 580.7183515994739, 586.7594286342579, 586.865021045162, 590.6367583935848, 616.4636766013597, 556.4296595989143, 573.916782066598, 574.0528128587863, 575.7524264566276, 598.1506261109236, 8817.867731984026, 8915.910840891638, 9031.593578288992, 9105.499197544199, 9269.351145419756, 568.2357229526281, 582.7610679621334, 629.4335098411375, 629.7449532939168, 633.8795628830508, 4293.549177336397, 4422.336649502536, 4466.57567531084, 4543.27355604868, 4618.538146185355, 54346.184807227924, 55889.16166257509, 58729.0, 64693.353066469674, 67387.66306168537, 59505.70247148736, 59608.0, 60114.699426502586, 60151.699241504386, 62445.0, 55228.723856380464, 55783.44216557783, 56240.71879640576, 56429.0, 56618.43381566214, 8660.220580147818, 8685.869711954452, 8791.824163516821, 8872.955635221782, 8878.73363799087, 34375.82812085924, 34816.825915871275, 35004.299914002084, 35045.0, 35460.46809297914, 48089.0, 49214.50785492243, 49282.75358623184, 50044.49955500398, 50048.49951500584, 10755.193360497837, 10943.101023904455, 11078.224524283223, 11159.0, 11431.142664300378, 12579.371031448574, 12581.370931453574, 12587.87412125867, 12612.936935315265, 12764.553240636533, 11647.30116193031, 11762.882371176522, 12074.698132546757, 12116.878831211576, 12117.212381195193, 10303.845442318521, 10539.736506587396, 11855.58505452305, 11883.643490694947, 12091.214071085351, 11203.551857925591, 11356.54573817038, 11527.308361498337, 11634.883651163718, 12060.396980151132, 9768.316217864965, 10120.59517619287, 10130.594776208869, 10375.118114960313, 10480.68557943263, 10549.156067514426, 10680.519376627915, 10768.0, 10772.838407423738, 10791.298565593212], "value": 6164.775640715831, "unit": "score"}], "name": "dromaeo_css", "value": 6742.226797766464, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 105.0, 112.0, 114.0, 114.0, 106.0, 112.0, 114.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 155.0, 209.0, 136.0, 158.0, 154.0, 132.0, 184.0, 131.0], "value": 154.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 165.0, 164.0, 163.0, 163.0, 168.0, 179.0, 165.0, 162.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 161.0, 77.0, 77.0, 76.0, 78.0, 167.0, 78.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 174.0, 92.0, 93.0, 93.0, 139.0, 95.0, 95.0, 139.0], "value": 110.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 121.0, 118.0, 117.0, 118.0, 119.0, 118.0, 118.0, 118.0, 118.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 148.0, 147.0, 150.0, 150.0, 150.0, 148.0, 148.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 95.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 57.0, 110.0, 55.0, 55.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 66.0, 59.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 86.0, 80.0, 83.0, 85.0, 87.0, 83.0, 84.0, 81.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 136.0, 131.0, 139.0, 131.0, 145.0, 150.0, 137.0, 194.0, 132.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 169.0, 172.0, 170.0, 175.0, 180.0, 171.0, 170.0, 169.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 65.0, 68.0, 67.0, 67.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1587.7000000000003, "alertThreshold": 2.0}]} 23:13:56 INFO - Return code: 0 23:13:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:13:56 ERROR - # TBPL SUCCESS # 23:13:56 INFO - Running post-action listener: _resource_record_post_action 23:13:56 INFO - ##### 23:13:56 INFO - ##### Finished run-tests step (success) 23:13:56 INFO - ##### 23:13:56 INFO - Running post-run listener: _resource_record_post_run 23:13:57 INFO - Total resource usage - Wall time: 1187s; CPU: 26.0%; Read bytes: 12288; Write bytes: 549257216; Read time: 380; Write time: 453524 23:13:57 INFO - install - Wall time: 17s; CPU: 27.0%; Read bytes: 4096; Write bytes: 254423040; Read time: 244; Write time: 285600 23:13:57 INFO - run-tests - Wall time: 1171s; CPU: 26.0%; Read bytes: 8192; Write bytes: 294236160; Read time: 136; Write time: 167924 23:13:57 INFO - Running post-run listener: _upload_blobber_files 23:13:57 INFO - Blob upload gear active. 23:13:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:13:57 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 23:13:57 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:13:57 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:13:57 INFO - (blobuploader) - INFO - Open directory for files ... 23:13:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:13:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:13:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:58 INFO - (blobuploader) - INFO - Done attempting. 23:13:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:13:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:13:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:59 INFO - (blobuploader) - INFO - Done attempting. 23:13:59 INFO - (blobuploader) - INFO - Iteration through files over. 23:13:59 INFO - Return code: 0 23:13:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:13:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:13:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1964f45790041babc02689d7e0793ee09ceddc67ce9c8ff6905de5d3ca558f6b162000a65b3d3763c34bd6585a876d8139de5e9fd63ffb0dd14042a51307dede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4cbeb0a8dcbf2abb70a520c660f8d35dcd554560c0f093919c33faf2d77f6af3542a9e75235db24b66e800e1de7f2650028bb41ec4fc910a8d496fcc9ab33d6"} 23:13:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:13:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:13:59 INFO - Contents: 23:13:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1964f45790041babc02689d7e0793ee09ceddc67ce9c8ff6905de5d3ca558f6b162000a65b3d3763c34bd6585a876d8139de5e9fd63ffb0dd14042a51307dede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4cbeb0a8dcbf2abb70a520c660f8d35dcd554560c0f093919c33faf2d77f6af3542a9e75235db24b66e800e1de7f2650028bb41ec4fc910a8d496fcc9ab33d6"} 23:13:59 INFO - Running post-run listener: copy_logs_to_upload_dir 23:13:59 INFO - Copying logs to upload dir... 23:13:59 INFO - mkdir: /builds/slave/test/build/upload/logs 23:13:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1224.947865 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-05-10 23:13:58.658048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 23:13:58.661879) ========= 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-1462945721.37182-1299472165 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/Fx-Team/sha512/1964f45790041babc02689d7e0793ee09ceddc67ce9c8ff6905de5d3ca558f6b162000a65b3d3763c34bd6585a876d8139de5e9fd63ffb0dd14042a51307dede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4cbeb0a8dcbf2abb70a520c660f8d35dcd554560c0f093919c33faf2d77f6af3542a9e75235db24b66e800e1de7f2650028bb41ec4fc910a8d496fcc9ab33d6"} build_url:https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005429 build_url: 'https://queue.taskcluster.net/v1/task/SehbInjwQ9SDXJJ2rBG3qA/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/Fx-Team/sha512/1964f45790041babc02689d7e0793ee09ceddc67ce9c8ff6905de5d3ca558f6b162000a65b3d3763c34bd6585a876d8139de5e9fd63ffb0dd14042a51307dede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4cbeb0a8dcbf2abb70a520c660f8d35dcd554560c0f093919c33faf2d77f6af3542a9e75235db24b66e800e1de7f2650028bb41ec4fc910a8d496fcc9ab33d6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 23:13:58.676191) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 23:13:58.676531) ========= 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-1462945721.37182-1299472165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002185 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 23:13:58.758288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 23:13:58.758648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 23:13:58.758964) ========= ========= Total master_lag: 0.17 =========