builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1464831077.89 results: success (0) buildid: 20160601171646 builduid: 53752fd2ea174b3482276bb52bef4de6 revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.886178) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.886606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.886949) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.930818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.931089) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 18:31:18-- 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% 225M=0s 2016-06-01 18:31:18 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115379 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.078538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.078930) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017052 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.134001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.134379) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --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=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 18:31:18,503 truncating revision to first 12 chars 2016-06-01 18:31:18,503 Setting DEBUG logging. 2016-06-01 18:31:18,503 attempt 1/10 2016-06-01 18:31:18,503 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/279597150b92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 18:31:18,869 unpacking tar archive at: larch-279597150b92/testing/mozharness/ program finished with exit code 0 elapsedTime=0.705451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.857230) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.857536) ========= script_repo_revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.857913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.858202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.867021) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-06-01 18:31:18.867287) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:31:19 INFO - MultiFileLogger online at 20160601 18:31:19 in /builds/slave/test 18:31:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 18:31:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:31:19 INFO - {'append_to_log': False, 18:31:19 INFO - 'base_work_dir': '/builds/slave/test', 18:31:19 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 18:31:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:31:19 INFO - 'branch': 'Larch-Non-PGO', 18:31:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:31:19 INFO - 'config_files': ('talos/linux_config.py',), 18:31:19 INFO - 'default_actions': ('clobber', 18:31:19 INFO - 'read-buildbot-config', 18:31:19 INFO - 'download-and-extract', 18:31:19 INFO - 'populate-webroot', 18:31:19 INFO - 'create-virtualenv', 18:31:19 INFO - 'install', 18:31:19 INFO - 'run-tests'), 18:31:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:31:19 INFO - 'download_minidump_stackwalk': True, 18:31:19 INFO - 'download_symbols': 'ondemand', 18:31:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:31:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:31:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:31:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:31:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:31:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:31:19 INFO - 'installer_path': 'installer.exe', 18:31:19 INFO - 'log_level': 'info', 18:31:19 INFO - 'log_name': 'talos', 18:31:19 INFO - 'log_to_console': True, 18:31:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:31:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:31:19 INFO - 'opt_config_files': (), 18:31:19 INFO - 'pip_index': False, 18:31:19 INFO - 'sps_profile': False, 18:31:19 INFO - 'sps_profile_interval': 0, 18:31:19 INFO - 'suite': 'dromaeojs', 18:31:19 INFO - 'system_bits': '32', 18:31:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:31:19 INFO - 'title': 'talos-linux64-ix-022', 18:31:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:31:19 INFO - 'use_talos_json': True, 18:31:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:31:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:31:19 INFO - 'work_dir': 'build'} 18:31:19 INFO - ##### 18:31:19 INFO - ##### Running clobber step. 18:31:19 INFO - ##### 18:31:19 INFO - Running pre-action listener: _resource_record_pre_action 18:31:19 INFO - Running main action method: clobber 18:31:19 INFO - rmtree: /builds/slave/test/build 18:31:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:31:20 INFO - Running post-action listener: _resource_record_post_action 18:31:20 INFO - ##### 18:31:20 INFO - ##### Finished clobber step (success) 18:31:20 INFO - ##### 18:31:20 INFO - ##### 18:31:20 INFO - ##### Running read-buildbot-config step. 18:31:20 INFO - ##### 18:31:20 INFO - Running pre-action listener: _resource_record_pre_action 18:31:20 INFO - Running main action method: read_buildbot_config 18:31:20 INFO - Using buildbot properties: 18:31:20 INFO - { 18:31:20 INFO - "project": "", 18:31:20 INFO - "product": "firefox", 18:31:20 INFO - "script_repo_revision": "production", 18:31:20 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 18:31:20 INFO - "repository": "", 18:31:20 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 18:31:20 INFO - "buildid": "20160601171646", 18:31:20 INFO - "pgo_build": "False", 18:31:20 INFO - "basedir": "/builds/slave/test", 18:31:20 INFO - "buildnumber": 4, 18:31:20 INFO - "slavename": "talos-linux64-ix-022", 18:31:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:31:20 INFO - "platform": "ubuntu64_hw", 18:31:20 INFO - "scheduler": "tests-larch-linux64-talos", 18:31:20 INFO - "branch": "larch", 18:31:20 INFO - "repo_path": "projects/larch", 18:31:20 INFO - "revision": "279597150b922a9b762de1dc88fbf083a31a4f6f", 18:31:20 INFO - "stage_platform": "linux64", 18:31:20 INFO - "builduid": "53752fd2ea174b3482276bb52bef4de6", 18:31:20 INFO - "slavebuilddir": "test" 18:31:20 INFO - } 18:31:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:31:20 INFO - Running post-action listener: _resource_record_post_action 18:31:20 INFO - ##### 18:31:20 INFO - ##### Finished read-buildbot-config step (success) 18:31:20 INFO - ##### 18:31:20 INFO - ##### 18:31:20 INFO - ##### Running download-and-extract step. 18:31:20 INFO - ##### 18:31:20 INFO - Running pre-action listener: _resource_record_pre_action 18:31:20 INFO - Running main action method: download_and_extract 18:31:20 INFO - mkdir: /builds/slave/test/build/tests 18:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:20 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:31:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:22 INFO - Downloaded 1361 bytes. 18:31:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:22 INFO - Using the following test package requirements: 18:31:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:31:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:31:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:31:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'jsshell-linux-x86_64.zip'], 18:31:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:31:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:31:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:31:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:31:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:31:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:31:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:22 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:31:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:24 INFO - Downloaded 22850307 bytes. 18:31:24 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'] 18:31:24 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 18:31:25 INFO - Return code: 0 18:31:25 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 18:31: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')]} 18:31:25 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:31:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:30 INFO - Downloaded 22850307 bytes. 18:31:30 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'] 18:31:30 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 18:31:31 INFO - Return code: 0 18:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:31 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:31:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:31:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:33 INFO - Downloaded 10834063 bytes. 18:31:33 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'] 18:31:33 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 18:31:34 INFO - Return code: 0 18:31: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')]} 18:31:34 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:31:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:35 INFO - Downloaded 55177110 bytes. 18:31:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:35 INFO - mkdir: /builds/slave/test/properties 18:31:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:35 INFO - Contents: 18:31:35 INFO - build_url:https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:36 INFO - Running post-action listener: _resource_record_post_action 18:31:36 INFO - Running post-action listener: set_extra_try_arguments 18:31:36 INFO - ##### 18:31:36 INFO - ##### Finished download-and-extract step (success) 18:31:36 INFO - ##### 18:31:36 INFO - ##### 18:31:36 INFO - ##### Running populate-webroot step. 18:31:36 INFO - ##### 18:31:36 INFO - Running pre-action listener: _resource_record_pre_action 18:31:36 INFO - Running main action method: populate_webroot 18:31:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:36 INFO - '--tppagecycles', 18:31:36 INFO - '7'], 18:31:36 INFO - 'tests': ['tsvgm']}}, 18:31:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['damp', 'tps']}, 18:31:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'tests': ['damp', 'tps']}, 18:31:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['dromaeo_dom']}, 18:31:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['a11yr', 18:31:36 INFO - 'ts_paint', 18:31:36 INFO - 'tpaint', 18:31:36 INFO - 'sessionrestore', 18:31:36 INFO - 'sessionrestore_no_auto_restore', 18:31:36 INFO - 'tabpaint']}, 18:31:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['tsvgx', 18:31:36 INFO - 'tsvgr_opacity', 18:31:36 INFO - 'tart', 18:31:36 INFO - 'tscrollx', 18:31:36 INFO - 'cart']}, 18:31:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:36 INFO - 'tsvgr_opacity', 18:31:36 INFO - 'tart', 18:31:36 INFO - 'tscrollx', 18:31:36 INFO - 'cart']}, 18:31:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'talos_options': ['--disable-e10s'], 18:31:36 INFO - 'tests': ['tp5o']}, 18:31:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'tests': ['tp5o']}, 18:31:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'talos_options': ['--disable-e10s', 18:31:36 INFO - '--xperf_path', 18:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:36 INFO - 'tests': ['tp5n']}, 18:31:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:36 INFO - 'talos_options': ['--xperf_path', 18:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:36 INFO - 'tests': ['tp5n']}}, 18:31:36 INFO - 'talos.zip': {'path': '', 18:31:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:36 INFO - Running post-action listener: _resource_record_post_action 18:31:36 INFO - ##### 18:31:36 INFO - ##### Finished populate-webroot step (success) 18:31:36 INFO - ##### 18:31:36 INFO - ##### 18:31:36 INFO - ##### Running create-virtualenv step. 18:31:36 INFO - ##### 18:31:36 INFO - Running pre-action listener: _resource_record_pre_action 18:31:36 INFO - Running main action method: create_virtualenv 18:31:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:36 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 18:31:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:36 INFO - Using real prefix '/usr' 18:31:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:38 INFO - Installing pip.................done. 18:31:38 INFO - Return code: 0 18:31:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:38 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:38 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 18:31:38 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 18:31:38 INFO - Using env: {'DISPLAY': ':0', 18:31:38 INFO - 'HOME': '/home/cltbld', 18:31:38 INFO - 'LANG': 'en_US.UTF-8', 18:31:38 INFO - 'LANGUAGE': 'en_US:en', 18:31:38 INFO - 'LOGNAME': 'cltbld', 18:31:38 INFO - 'MAIL': '/var/mail/cltbld', 18:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:38 INFO - 'MOZ_NO_REMOTE': '1', 18:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:38 INFO - 'NO_EM_RESTART': '1', 18:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:38 INFO - 'PWD': '/builds/slave/test', 18:31:38 INFO - 'SHELL': '/bin/bash', 18:31:38 INFO - 'SHLVL': '1', 18:31:38 INFO - 'TERM': 'linux', 18:31:38 INFO - 'TMOUT': '86400', 18:31:38 INFO - 'USER': 'cltbld', 18:31:38 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:38 INFO - '_': '/tools/buildbot/bin/python'} 18:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:38 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 18:31:38 INFO - Cleaning up... 18:31:38 INFO - Return code: 0 18:31:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:31:38 INFO - Using env: {'DISPLAY': ':0', 18:31:38 INFO - 'HOME': '/home/cltbld', 18:31:38 INFO - 'LANG': 'en_US.UTF-8', 18:31:38 INFO - 'LANGUAGE': 'en_US:en', 18:31:38 INFO - 'LOGNAME': 'cltbld', 18:31:38 INFO - 'MAIL': '/var/mail/cltbld', 18:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:38 INFO - 'MOZ_NO_REMOTE': '1', 18:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:38 INFO - 'NO_EM_RESTART': '1', 18:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:38 INFO - 'PWD': '/builds/slave/test', 18:31:38 INFO - 'SHELL': '/bin/bash', 18:31:38 INFO - 'SHLVL': '1', 18:31:38 INFO - 'TERM': 'linux', 18:31:38 INFO - 'TMOUT': '86400', 18:31:38 INFO - 'USER': 'cltbld', 18:31:38 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:38 INFO - '_': '/tools/buildbot/bin/python'} 18:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:39 INFO - Downloading/unpacking psutil>=3.1.1 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:41 INFO - Installing collected packages: psutil 18:31:41 INFO - Running setup.py install for psutil 18:31:41 INFO - building 'psutil._psutil_linux' extension 18:31:41 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 18:31:41 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 18:31:41 INFO - building 'psutil._psutil_posix' extension 18:31:41 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 18:31:41 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 18:31:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:41 INFO - Successfully installed psutil 18:31:41 INFO - Cleaning up... 18:31:41 INFO - Return code: 0 18:31:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31:41 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')]} 18:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:41 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')]} 18:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:31:41 INFO - Using env: {'DISPLAY': ':0', 18:31:41 INFO - 'HOME': '/home/cltbld', 18:31:41 INFO - 'LANG': 'en_US.UTF-8', 18:31:41 INFO - 'LANGUAGE': 'en_US:en', 18:31:41 INFO - 'LOGNAME': 'cltbld', 18:31:41 INFO - 'MAIL': '/var/mail/cltbld', 18:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:41 INFO - 'MOZ_NO_REMOTE': '1', 18:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:41 INFO - 'NO_EM_RESTART': '1', 18:31:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:41 INFO - 'PWD': '/builds/slave/test', 18:31:41 INFO - 'SHELL': '/bin/bash', 18:31:41 INFO - 'SHLVL': '1', 18:31:41 INFO - 'TERM': 'linux', 18:31:41 INFO - 'TMOUT': '86400', 18:31:41 INFO - 'USER': 'cltbld', 18:31:41 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:41 INFO - '_': '/tools/buildbot/bin/python'} 18:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:31:43 INFO - Installing collected packages: mozsystemmonitor 18:31:43 INFO - Running setup.py install for mozsystemmonitor 18:31:43 INFO - Successfully installed mozsystemmonitor 18:31:43 INFO - Cleaning up... 18:31:43 INFO - Return code: 0 18:31:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31:43 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')]} 18:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:43 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')]} 18:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:43 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:43 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 18:31:43 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 18:31:43 INFO - Using env: {'DISPLAY': ':0', 18:31:43 INFO - 'HOME': '/home/cltbld', 18:31:43 INFO - 'LANG': 'en_US.UTF-8', 18:31:43 INFO - 'LANGUAGE': 'en_US:en', 18:31:43 INFO - 'LOGNAME': 'cltbld', 18:31:43 INFO - 'MAIL': '/var/mail/cltbld', 18:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:43 INFO - 'MOZ_NO_REMOTE': '1', 18:31:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:43 INFO - 'NO_EM_RESTART': '1', 18:31:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:43 INFO - 'PWD': '/builds/slave/test', 18:31:43 INFO - 'SHELL': '/bin/bash', 18:31:43 INFO - 'SHLVL': '1', 18:31:43 INFO - 'TERM': 'linux', 18:31:43 INFO - 'TMOUT': '86400', 18:31:43 INFO - 'USER': 'cltbld', 18:31:43 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:43 INFO - '_': '/tools/buildbot/bin/python'} 18:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:44 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:46 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 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 18:31:46 INFO - Downloading docopt-0.6.1.tar.gz 18:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:46 INFO - Running setup.py install for blobuploader 18:31:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:46 INFO - Running setup.py install for requests 18:31:47 INFO - Running setup.py install for docopt 18:31:47 INFO - Successfully installed blobuploader requests docopt 18:31:47 INFO - Cleaning up... 18:31:47 INFO - Return code: 0 18:31:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:47 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')]} 18:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:47 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')]} 18:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:47 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:47 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 18:31:47 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 18:31:47 INFO - Using env: {'DISPLAY': ':0', 18:31:47 INFO - 'HOME': '/home/cltbld', 18:31:47 INFO - 'LANG': 'en_US.UTF-8', 18:31:47 INFO - 'LANGUAGE': 'en_US:en', 18:31:47 INFO - 'LOGNAME': 'cltbld', 18:31:47 INFO - 'MAIL': '/var/mail/cltbld', 18:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:47 INFO - 'MOZ_NO_REMOTE': '1', 18:31:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:47 INFO - 'NO_EM_RESTART': '1', 18:31:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:47 INFO - 'PWD': '/builds/slave/test', 18:31:47 INFO - 'SHELL': '/bin/bash', 18:31:47 INFO - 'SHLVL': '1', 18:31:47 INFO - 'TERM': 'linux', 18:31:47 INFO - 'TMOUT': '86400', 18:31:47 INFO - 'USER': 'cltbld', 18:31:47 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:47 INFO - '_': '/tools/buildbot/bin/python'} 18:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:47 INFO - Running setup.py (path:/tmp/pip-MnPCuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:47 INFO - Running setup.py (path:/tmp/pip-hKk18A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:47 INFO - Running setup.py (path:/tmp/pip-G1qX4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:47 INFO - Running setup.py (path:/tmp/pip-rqkgw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:47 INFO - Running setup.py (path:/tmp/pip-x3Q9zU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:47 INFO - Running setup.py (path:/tmp/pip-uLCrRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:48 INFO - Running setup.py (path:/tmp/pip-EbMiNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:48 INFO - Running setup.py (path:/tmp/pip-yG38Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:48 INFO - Running setup.py (path:/tmp/pip-wLSQmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:48 INFO - Running setup.py (path:/tmp/pip-vykexZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:48 INFO - Running setup.py (path:/tmp/pip-Zl9TNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:48 INFO - Running setup.py (path:/tmp/pip-2jq44U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:48 INFO - Running setup.py (path:/tmp/pip-nlb12R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:48 INFO - Running setup.py (path:/tmp/pip-W0QTmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:48 INFO - Running setup.py (path:/tmp/pip-hGCLDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:48 INFO - Running setup.py (path:/tmp/pip-Z07bJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:48 INFO - Running setup.py (path:/tmp/pip-L6uLJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:48 INFO - Running setup.py install for manifestparser 18:31:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:48 INFO - Running setup.py install for mozcrash 18:31:49 INFO - Running setup.py install for mozdebug 18:31:49 INFO - Running setup.py install for mozdevice 18:31:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozfile 18:31:49 INFO - Running setup.py install for mozhttpd 18:31:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozinfo 18:31:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozInstall 18:31:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozleak 18:31:49 INFO - Running setup.py install for mozlog 18:31:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for moznetwork 18:31:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozprocess 18:31:50 INFO - Running setup.py install for mozprofile 18:31:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for mozrunner 18:31:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for mozscreenshot 18:31:50 INFO - Running setup.py install for moztest 18:31:50 INFO - Running setup.py install for mozversion 18:31:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:50 INFO - Cleaning up... 18:31:50 INFO - Return code: 0 18:31:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:50 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:50 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 18:31:50 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 18:31:50 INFO - Using env: {'DISPLAY': ':0', 18:31:50 INFO - 'HOME': '/home/cltbld', 18:31:50 INFO - 'LANG': 'en_US.UTF-8', 18:31:50 INFO - 'LANGUAGE': 'en_US:en', 18:31:50 INFO - 'LOGNAME': 'cltbld', 18:31:50 INFO - 'MAIL': '/var/mail/cltbld', 18:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:50 INFO - 'MOZ_NO_REMOTE': '1', 18:31:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:50 INFO - 'NO_EM_RESTART': '1', 18:31:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:50 INFO - 'PWD': '/builds/slave/test', 18:31:50 INFO - 'SHELL': '/bin/bash', 18:31:50 INFO - 'SHLVL': '1', 18:31:50 INFO - 'TERM': 'linux', 18:31:50 INFO - 'TMOUT': '86400', 18:31:50 INFO - 'USER': 'cltbld', 18:31:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:50 INFO - '_': '/tools/buildbot/bin/python'} 18:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:50 INFO - Running setup.py (path:/tmp/pip-noQWah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:50 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)) 18:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:50 INFO - Running setup.py (path:/tmp/pip-dmo8Gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:50 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)) 18:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:50 INFO - Running setup.py (path:/tmp/pip-iT3HeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:51 INFO - Running setup.py (path:/tmp/pip-Ft5YIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:51 INFO - Running setup.py (path:/tmp/pip-HC_Irh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:51 INFO - Running setup.py (path:/tmp/pip-twHLH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:51 INFO - Running setup.py (path:/tmp/pip-RfApQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:51 INFO - Running setup.py (path:/tmp/pip-_iUQMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:51 INFO - Running setup.py (path:/tmp/pip-y3bqVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:51 INFO - Running setup.py (path:/tmp/pip-hl9Spa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:51 INFO - Running setup.py (path:/tmp/pip-fQjkhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:51 INFO - Running setup.py (path:/tmp/pip-iGls6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:51 INFO - Running setup.py (path:/tmp/pip-KgHiF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:51 INFO - Running setup.py (path:/tmp/pip-qOFVxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:51 INFO - Running setup.py (path:/tmp/pip-HmkU8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:51 INFO - Running setup.py (path:/tmp/pip-kjYQdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:51 INFO - Running setup.py (path:/tmp/pip-43AwsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:52 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)) 18:31:52 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)) 18:31:52 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)) 18:31:52 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)) 18:31:52 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)) 18:31:52 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)) 18:31:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:52 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 18:31:52 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 18:31:52 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 18:31:52 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 18:31:52 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 18:31:52 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 18:31:53 INFO - Downloading blessings-1.6.tar.gz 18:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:54 INFO - Installing collected packages: blessings 18:31:54 INFO - Running setup.py install for blessings 18:31:54 INFO - Successfully installed blessings 18:31:54 INFO - Cleaning up... 18:31:54 INFO - Return code: 0 18:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:54 INFO - Reading from file tmpfile_stdout 18:31:54 INFO - Current package versions: 18:31:54 INFO - argparse == 1.2.1 18:31:54 INFO - blessings == 1.6 18:31:54 INFO - blobuploader == 1.2.4 18:31:54 INFO - docopt == 0.6.1 18:31:54 INFO - manifestparser == 1.1 18:31:54 INFO - mozInstall == 1.12 18:31:54 INFO - mozcrash == 0.17 18:31:54 INFO - mozdebug == 0.1 18:31:54 INFO - mozdevice == 0.48 18:31:54 INFO - mozfile == 1.2 18:31:54 INFO - mozhttpd == 0.7 18:31:54 INFO - mozinfo == 0.9 18:31:54 INFO - mozleak == 0.1 18:31:54 INFO - mozlog == 3.2 18:31:54 INFO - moznetwork == 0.27 18:31:54 INFO - mozprocess == 0.22 18:31:54 INFO - mozprofile == 0.28 18:31:54 INFO - mozrunner == 6.11 18:31:54 INFO - mozscreenshot == 0.1 18:31:54 INFO - mozsystemmonitor == 0.3 18:31:54 INFO - moztest == 0.7 18:31:54 INFO - mozversion == 1.4 18:31:54 INFO - psutil == 3.1.1 18:31:54 INFO - requests == 1.2.3 18:31:54 INFO - wsgiref == 0.1.2 18:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:54 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')]} 18:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:54 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')]} 18:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:54 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:54 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 18:31:54 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 18:31:54 INFO - Using env: {'DISPLAY': ':0', 18:31:54 INFO - 'HOME': '/home/cltbld', 18:31:54 INFO - 'LANG': 'en_US.UTF-8', 18:31:54 INFO - 'LANGUAGE': 'en_US:en', 18:31:54 INFO - 'LOGNAME': 'cltbld', 18:31:54 INFO - 'MAIL': '/var/mail/cltbld', 18:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:54 INFO - 'MOZ_NO_REMOTE': '1', 18:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:54 INFO - 'NO_EM_RESTART': '1', 18:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:54 INFO - 'PWD': '/builds/slave/test', 18:31:54 INFO - 'SHELL': '/bin/bash', 18:31:54 INFO - 'SHLVL': '1', 18:31:54 INFO - 'TERM': 'linux', 18:31:54 INFO - 'TMOUT': '86400', 18:31:54 INFO - 'USER': 'cltbld', 18:31:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:54 INFO - '_': '/tools/buildbot/bin/python'} 18:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 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)) 18:31:54 INFO - Cleaning up... 18:31:54 INFO - Return code: 0 18:31:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31:54 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')]} 18:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:54 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')]} 18:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:54 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:54 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 18:31:54 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 18:31:54 INFO - Using env: {'DISPLAY': ':0', 18:31:54 INFO - 'HOME': '/home/cltbld', 18:31:54 INFO - 'LANG': 'en_US.UTF-8', 18:31:54 INFO - 'LANGUAGE': 'en_US:en', 18:31:54 INFO - 'LOGNAME': 'cltbld', 18:31:54 INFO - 'MAIL': '/var/mail/cltbld', 18:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:54 INFO - 'MOZ_NO_REMOTE': '1', 18:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:54 INFO - 'NO_EM_RESTART': '1', 18:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:54 INFO - 'PWD': '/builds/slave/test', 18:31:54 INFO - 'SHELL': '/bin/bash', 18:31:54 INFO - 'SHLVL': '1', 18:31:54 INFO - 'TERM': 'linux', 18:31:54 INFO - 'TMOUT': '86400', 18:31:54 INFO - 'USER': 'cltbld', 18:31:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:54 INFO - '_': '/tools/buildbot/bin/python'} 18:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:54 INFO - Downloading/unpacking jsonschema 18:31:54 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 18:31:54 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 18:31:54 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 18:31:54 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 18:31:54 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 18:31:54 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 18:31:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:56 INFO - Downloading/unpacking functools32 (from jsonschema) 18:31:56 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 18:31:56 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 18:31:56 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 18:31:56 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 18:31:56 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 18:31:56 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 18:31:56 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:56 INFO - warning: no files found matching '*.txt' 18:31:56 INFO - no previously-included directories found matching 'build' 18:31:56 INFO - no previously-included directories found matching 'dist' 18:31:56 INFO - no previously-included directories found matching '.git*' 18:31:56 INFO - Installing collected packages: jsonschema, functools32 18:31:57 INFO - Running setup.py install for functools32 18:31:57 INFO - warning: no files found matching '*.txt' 18:31:57 INFO - no previously-included directories found matching 'build' 18:31:57 INFO - no previously-included directories found matching 'dist' 18:31:57 INFO - no previously-included directories found matching '.git*' 18:31:57 INFO - Successfully installed jsonschema functools32 18:31:57 INFO - Cleaning up... 18:31:57 INFO - Return code: 0 18:31:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:31:57 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')]} 18:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:57 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')]} 18:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:57 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 0x7f365e656f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', '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 18:31:57 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 18:31:57 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 18:31:57 INFO - Using env: {'DISPLAY': ':0', 18:31:57 INFO - 'HOME': '/home/cltbld', 18:31:57 INFO - 'LANG': 'en_US.UTF-8', 18:31:57 INFO - 'LANGUAGE': 'en_US:en', 18:31:57 INFO - 'LOGNAME': 'cltbld', 18:31:57 INFO - 'MAIL': '/var/mail/cltbld', 18:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:57 INFO - 'MOZ_NO_REMOTE': '1', 18:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:57 INFO - 'NO_EM_RESTART': '1', 18:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:57 INFO - 'PWD': '/builds/slave/test', 18:31:57 INFO - 'SHELL': '/bin/bash', 18:31:57 INFO - 'SHLVL': '1', 18:31:57 INFO - 'TERM': 'linux', 18:31:57 INFO - 'TMOUT': '86400', 18:31:57 INFO - 'USER': 'cltbld', 18:31:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:57 INFO - '_': '/tools/buildbot/bin/python'} 18:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:57 INFO - Downloading/unpacking flake8 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:31:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:31:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:31:59 INFO - no previously-included directories found matching 'docs/_build' 18:31:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:32:00 INFO - Running setup.py install for pep8 18:32:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:00 INFO - no previously-included directories found matching 'docs/_build' 18:32:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:32:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:32:00 INFO - Cleaning up... 18:32:00 INFO - Return code: 0 18:32:00 INFO - Running post-action listener: _resource_record_post_action 18:32:00 INFO - Running post-action listener: _start_resource_monitoring 18:32:00 INFO - Starting resource monitoring. 18:32:00 INFO - ##### 18:32:00 INFO - ##### Finished create-virtualenv step (success) 18:32:00 INFO - ##### 18:32:00 INFO - ##### 18:32:00 INFO - ##### Running install step. 18:32:00 INFO - ##### 18:32:00 INFO - Running pre-action listener: _resource_record_pre_action 18:32:00 INFO - Running main action method: install 18:32:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:00 INFO - Reading from file tmpfile_stdout 18:32:00 INFO - Detecting whether we're running mozinstall >=1.0... 18:32:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:32:00 INFO - Reading from file tmpfile_stdout 18:32:00 INFO - Output received: 18:32:00 INFO - Usage: mozinstall [options] installer 18:32:00 INFO - Options: 18:32:00 INFO - -h, --help show this help message and exit 18:32:00 INFO - -d DEST, --destination=DEST 18:32:00 INFO - Directory to install application into. [default: 18:32:00 INFO - "/builds/slave/test"] 18:32:00 INFO - --app=APP Application being installed. [default: firefox] 18:32:00 INFO - mkdir: /builds/slave/test/build/application 18:32:00 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'] 18:32:00 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 18:32:16 INFO - Reading from file tmpfile_stdout 18:32:16 INFO - Output received: 18:32:16 INFO - /builds/slave/test/build/application/firefox/firefox 18:32:16 INFO - Running post-action listener: _resource_record_post_action 18:32:16 INFO - ##### 18:32:16 INFO - ##### Finished install step (success) 18:32:16 INFO - ##### 18:32:16 INFO - ##### 18:32:16 INFO - ##### Running run-tests step. 18:32:16 INFO - ##### 18:32:16 INFO - Running pre-action listener: _resource_record_pre_action 18:32:16 INFO - Running main action method: run_tests 18:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:16 INFO - Python 2.7.3 18:32:16 INFO - Return code: 0 18:32:16 INFO - grabbing minidump binary from tooltool 18:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:16 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 0x24579c0>, '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 0x24588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2458d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:16 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 18:32:16 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 18:32:16 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 18:32:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:16 INFO - Return code: 0 18:32:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:32:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:32:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:32:17 INFO - Return code: 0 18:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:32:17 INFO - Using env: {'DISPLAY': ':0', 18:32:17 INFO - 'HOME': '/home/cltbld', 18:32:17 INFO - 'LANG': 'en_US.UTF-8', 18:32:17 INFO - 'LANGUAGE': 'en_US:en', 18:32:17 INFO - 'LOGNAME': 'cltbld', 18:32:17 INFO - 'MAIL': '/var/mail/cltbld', 18:32:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:17 INFO - 'MOZ_NO_REMOTE': '1', 18:32:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:17 INFO - 'NO_EM_RESTART': '1', 18:32:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:17 INFO - 'PWD': '/builds/slave/test', 18:32:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:17 INFO - 'SHELL': '/bin/bash', 18:32:17 INFO - 'SHLVL': '1', 18:32:17 INFO - 'TERM': 'linux', 18:32:17 INFO - 'TMOUT': '86400', 18:32:17 INFO - 'USER': 'cltbld', 18:32:17 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107', 18:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:17 INFO - '_': '/tools/buildbot/bin/python'} 18:32:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:32:17 INFO - mozversion application_buildid: 20160601171646 18:32:17 INFO - mozversion application_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 18:32:17 INFO - mozversion application_display_name: Nightly 18:32:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:17 INFO - mozversion application_name: Firefox 18:32:17 INFO - mozversion application_remotingname: firefox 18:32:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 18:32:17 INFO - mozversion application_vendor: Mozilla 18:32:17 INFO - mozversion application_version: 49.0a1 18:32:17 INFO - mozversion platform_buildid: 20160601171646 18:32:17 INFO - mozversion platform_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 18:32:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 18:32:17 INFO - mozversion platform_version: 49.0a1 18:32:17 INFO - using testdate: 1464831137 18:32:17 INFO - actual date: 1464831137 18:32:17 INFO - starting webserver on 'localhost:57262' 18:32:17 INFO - SUITE-START | Running 2 tests 18:32:17 INFO - TEST-START | dromaeo_css 18:32:17 INFO - operating with platform_type : linux_ 18:32:17 INFO - Initialising browser for dromaeo_css test... 18:32:17 INFO - TEST-INFO | started process 3199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprL6wAL/profile http://localhost:57262/getInfo.html) 18:32:22 INFO - PROCESS | 3199 | __metrics Screen width/height:1600/1200 18:32:22 INFO - PROCESS | 3199 | colorDepth:24 18:32:22 INFO - PROCESS | 3199 | Browser inner width/height: 1024/697 18:32:22 INFO - PROCESS | 3199 | __metrics 18:32:23 ERROR - PROCESS | 3199 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:32:24 INFO - TEST-INFO | 3199: exit 0 18:32:24 INFO - Browser initialized. 18:32:24 INFO - Running cycle 1/1 for dromaeo_css test... 18:32:24 INFO - TEST-INFO | started process 3278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprL6wAL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:36 INFO - PROCESS | 3278 | RSS: Main: 157659136 18:32:36 INFO - PROCESS | 3278 | 18:33:27 INFO - PROCESS | 3278 | 1464831207499 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] 18:35:38 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57262/tests/dromaeo/cssquery-ext.html) 18:35:40 INFO - PROCESS | 3278 | RSS: Main: 280813568 18:35:40 INFO - PROCESS | 3278 | 18:38:43 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-ext.html (next: http://localhost:57262/tests/dromaeo/cssquery-jquery.html) 18:38:43 INFO - PROCESS | 3278 | RSS: Main: 234434560 18:38:43 INFO - PROCESS | 3278 | 18:41:45 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57262/tests/dromaeo/cssquery-mootools.html) 18:41:46 INFO - PROCESS | 3278 | RSS: Main: 232685568 18:41:46 INFO - PROCESS | 3278 | 18:44:48 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57262/tests/dromaeo/cssquery-prototype.html) 18:44:49 INFO - PROCESS | 3278 | RSS: Main: 243576832 18:44:49 INFO - PROCESS | 3278 | 18:47:52 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57262/tests/dromaeo/cssquery-yui.html) 18:47:52 INFO - PROCESS | 3278 | RSS: Main: 216555520 18:47:52 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-yui.html (next: http://localhost:57262/tests/dromaeo/cssquery-dojo.html) 18:50:55 INFO - PROCESS | 3278 | RSS: Main: 264626176 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | __start_tp_report 18:50:55 INFO - PROCESS | 3278 | _x_x_mozilla_page_load 18:50:55 INFO - PROCESS | 3278 | _x_x_mozilla_page_load_details 18:50:55 INFO - PROCESS | 3278 | |i|pagename|runs| 18:50:55 INFO - PROCESS | 3278 | |0;dojo.html;2632.1313966391085;2654.3231475973625;2673.478671659026;2692.690340610829;2694.312950197699;4548.840790572331;4605.700629459091;4643.187442197619;4717.198076327024;4751.738654374012;4692.906141877161;4711.505291944348;4717.315989181567;4761.8095276189;4761.952380476194;4147.294959856831;4265.552117027714;4371.978140109295;4415.072834704716;4530.75080870552;4274.508431530383;4314.072474418;4337.089211265638;4340.761258130801;4387.978060109695;4208.116295577947;4238.851640192609;4286.978565107186;4340.435743353375;4355.5208927018;4278.914421711579;4286.57134286572;4333.653307735373;4336.891577710551;4337.414449049369;4331.285337919239;4336.3278691802825;4343.630791382736;4354.956450435487;4373.518912919576;4478.753668548228;4585.312203169551;4639.559241872051;4646.9070618587775;4685.789139488731;5076.543111120017;5109.770060347293;5117.1556693145585;5132.486751324875;5133.768980395891;4933.358663373739;5126.384833819928;5128.102582048126;5166.870828229325;5178.378594568639;4737.597304229144;4775.808967641325;4782.0914026334885;4817.459167511876;4842.273658951115;5300.019496393103;5326.573874090063;5329.840104796862;5330.280412144389;5333.2266821310695;4790.760461976887;5086.516780905808;5093.490650934951;5101.51535604117;5110.105731496973;4971.726555039435;5008.5993120550265;5010.098182327216;5018.673786203885;5020.046191223656;4978.551930326288;4996.075725990703;5000.924986125211;5003.074431230234;5066.189409694505;4815.951840481621;4832.9275060874115;4981.37732783402;4996.575291100297;5030.471800460972;4266.765327906933;4312.676549258818;4313.97843010783;4322.308430651081;4329.696921215547;3731.477593136963;3743.5320584926885;3753.680937120321;3774.169682669808;3785.621437856192;4271.145770845781;4356.76037817923;4388.29787234041;4393.253146965024;4426.380306757057;5112.079825631422;5159.993801208803;5162.174052151638;5165.328507294028;5217.686938783685;4382.715123516961;4391.626711729538;4397.34039894012;4440.555944405533;4456.509783923766;4449.7997590108525;4487.035287413222;4521.434820647419;4612.607928326062;4630.166570017429;4754.786034628519;4769.689970151929;4783.545563171493;4817.614590832725;4823.011282686971;5038.294638750504;5043.344365232496;5043.798248070183;5046.268291097696;5047.318611987482;4598.655100867381;4781.282807578891;4789.7605119743175;4796.592289655418;4828.082664293773;5022.422421421583;5028.446870844131;5043.092243396077;5059.671121377099;5098.107831129463;5104.03023425548;5117.053345131163;5128.410232823124;5130.102232109291;5135.7432128394175;4763.952360476351;4887.804487820447;4905.018996200845;4925.704457732548;4931.728754918514;4864.805407783649;4930.630202734881;4933.975330123327;4944.1100601892;4991.176455884884;4672.906541869075;4863.027394521181;4870.155654383662;4872.196087645499;4927.704337739747;4820.71075735457;4848.490908454562;4910.852674419773;4919.508049195052;4986.102501549756;4833.540813622701;4853.781579828987;4906.435759887516;4907.460179380194;4955.851324460272 18:50:55 INFO - PROCESS | 3278 | |1;ext.html;540.2442063294294;585.3064119018966;586.5981802465315;601.0233370772494;614.520673874378;29184.416311673755;39977.60022399775;40809.795951020205;40858.795706021505;45520.54479455204;41348.58651413485;41369.37945930813;41722.79138604303;41773.79113104438;41963.16073678532;7296.270372962687;7704.19105993871;7872.858435526874;9832.31173817833;10120.949395253014;9925.106740393363;10034.648787292446;10065.144462720678;10065.144462720678;10084.596616135346;9732.854007189897;9745.074217949283;9755.219582433388;9785.951070244675;9786.314957952945;7339.5963222022765;10007.899921000806;10276.537555809931;10281.177505799518;10300.536475858604;9473.66842160521;9560.474173920431;9587.472689002103;9756.317057805956;9796.559154838049;38857;39651;39668.801655991825;39679.20641587181;39685.20629587421;3107.1765982014786;3128.577642018321;3141.842907854621;3314.1880239341276;3430.0052984634426;29570.260743481584;29974.85012574923;30048.39903201924;30052.24869378261;30716.53925191124;3185.442547554192;3219;3230.370077834823;3236.4659831127838;3285.1130194847497;274726.1318467034;278185;279378;279531;280625;59151.704241479376;59683.104753428735;59822;59943;59952.40047599556;34730.479042814375;35488.46767298492;35814.030228337695;37456.81271593625;37554.624453755656;13466.93266533661;14702.338394772261;14733.705325893636;14787.704245915022;14814.777778333333;10545.156387488982;11317.886821131684;11317.943410282896;11993.760124797454;11995.340256285814;19709.142174384633;19769.30807421762;19784.208631654572;20079;20207.777144514384;7205.531640443354;7215.49491535598;7315.195328513859;7496.962515187498;7546.660400282;838.8322335532918;950.7908260182749;951.0679534056663;961.3703024518951;975.317277905469;28109.437811244068;28632.99784507574;29142.708572914424;29331.560026599618;29396.559051614244;964.1852634523816;992.2508506078009;1013.5996281468779;1017.760826205841;1029.3155051890558;14742.852571474363;15192.772108418383;15275.770863437057;15339.0796552205;15357.169627464253;383691;387787;388153;389395;390092;51184;51664.48335516672;51677.48322516802;51817;51836;1079.8488211650376;1111.5276007696534;1167.258790667949;1221.407617305606;1233.8827811357908;26720;27910.3022424441;27988.02041928523;28616.85691571529;28838.567421488275;40259.79870100631;40398.39402408907;40913.38629920613;40917.38623920703;40998.59001409948;206466;207033;207940;208074;208336;22640.66039009383;24952.12667556627;25212.74787252104;26059.478810424065;26130;41515.16969660651;41717.79141104397;42054.15891682088;42213.36679949742;42339.78830105953;36556.26887462289;36621;36746.63253367539;36888.396378049256;37538.624613754604;36197.63802361942;36880.63119368772;37377.62622373742;37427;37491.81254093712;35056;35757.1060723484;35842.10394740153;36468;36827.81586092053;31714.365712686078;32817.50773738348;32881.67118328882;33030.669693303724;33136.50295245621;24602.753972460763;24934.875325623256;25053.373665658506;25176.74823251744;25195.8957541429 18:50:55 INFO - PROCESS | 3278 | |2;jquery.html;376.4071587250081;378.12464145503156;383.67004376236434;391.45392177911816;394.1643715323515;15802.446914357999;17120.914395428037;17679.911600441996;17879.19543620537;17936.103194840256;16342.264598093085;16652;17065.232064557094;17135;17253.741193882102;5046.848594542161;5213.452587478317;5220.530152286294;5232.084385232569;5251.3435820522245;3676.3566375884247;3878.631530004659;5756.050251708463;6070.72681729321;6071.089336599523;5915.438033386821;6126.203593532857;6130.295016073142;6153.169322141498;6155.569110162291;6071.78748743794;6086.908696369558;6089.908651370233;6200.193974783272;6211.40991605799;3393.8812141574926;5018.824341148043;5303.204519322094;5303.628745987782;5402.51377376038;20043.79956200434;20318.08568614416;20535.075921583415;20649.17403303866;20692.068856901475;1923.2883832981747;2068.6069646767064;2159.26584961114;2385.8329916905823;2522.4828910073393;12524.248545087234;12951.352432378344;13047.781877080919;13538.458461661521;13838.515651952133;1673.4059408909905;1820.4447643468736;1872.6441976024512;1873.3068764557065;1906.9523261918425;67219.2863606594;67312.32687673159;67867.66066169686;68205.31794682039;69826.65086674535;8401.327893768485;8635.481871087755;9046.276297896153;9104.180623743763;9183.77040573991;12064.638060858188;12070.758584828254;12095.21381110225;12137.211081279866;12156.817647735113;6695.163104611924;6736.562123461959;6764.594124352555;6845.520813543004;6914.204866440424;6546.214454265519;6562.146920900253;6565.934340656628;6621.569597976116;6750.291219421988;3834.309824231609;3873.2253549290253;3894.337962546374;3940.330143875548;3948.526176858738;1716.5965997990463;1755.806861245262;1839.364800702223;1847.8059803720685;1864.1611274926067;838.0655569040476;863.8315528472014;875.7810547363159;898.4923518212189;907.4237858959608;15869.04785712861;16513.504594862174;16666.416675416545;16734.74897876533;17208;817.8977627796526;873.7204094689638;891.6700820696384;900.1133883125117;905.6467977488785;9549;9692.46691431978;9760.658376956915;9796.461194634363;9815.411075335503;334106;344243;345090;345105;345929;66339;77514.6124269375;78186.6090669543;79783.60108199422;80559;909.3225546967551;922.2898368256405;940.3229674634252;954;958.5878072428783;14234.288285585471;14754.63113422173;14791.92604036973;15273.159976200972;15806.60483487922;34007;35001.124971875906;35019.64980350164;35793.64206357903;35845.28309433744;44984.550154498036;47507.28739068986;47869.5213047865;47992;48465;6993.125859267591;7084.468664850054;7160.069191005136;7250.20247772734;7372.889406658797;6125.768340384748;7840.392719492605;8620.267277281502;10374;10739.14086873064;8467.322614190973;8766.21104100634;9170.312226582899;9220.723378298662;9439.81120377575;8606.397552171287;9409.576569054272;9425.104615061833;9492.57283422234;9522;8395;8424.536650484282;8872;9025.05236950137;9289.907100928904;6949.930500694928;7245.384142347749;7355.963220183865;7399.48203625741;7429.628518574158;8065.475744076616;8773.166549177819;8904.821903562022;8986.415882967585;9489.620415183315 18:50:55 INFO - PROCESS | 3278 | |3;mootools.html;1211.9454624541893;1211.9575814846482;1212.448336007117;1222.3888055972013;1721.5093698295989;6626.66866656667;7428.554286742799;7681.116671582769;7704.3451306639;7820.061592608895;7644.159142494335;7771.961140194306;7776.144624091346;7778.805529861756;7779.64991575379;2198.8021078102965;2233.9888300558555;2236.552689462106;2237.1722462688826;2238.0935721032924;2133.829293656504;2174.0542863854275;2175.4017645147633;2178.8583742056794;2179.1174574297434;2202.9889850550726;2206.8455208135438;2213.9335819925345;2218.223621732397;2247.8426510144295;2143.624865648513;2162.2107930605375;2164.632012557869;2165.9783402165936;2167.6531754919215;2064.318774804311;2126.617208902397;2129.7976692214215;2130.531283117727;2130.9147634094616;7379.225181355934;7587.127480339776;7663.501872378278;7707.344875685518;7718.884216736754;4306.074194048295;4410.669199810027;4412.911741765179;4420.359047938031;4421.624161946206;2833.2916770807296;2970.495015847311;3009.623797025372;3010.232390740347;3022.758179345647;967.5500892085144;977.4037836919473;977.7653363192853;984.5421878826309;1002.3484734922358;10152.39085654863;10274.948625256826;10304.278700490968;10310.278280520366;10318.226133039976;4032.153247817991;4049.1091959768805;4117.814698338582;4120.114175452292;4129.834806607762;8258.545779982038;8290.834183316421;8304.086550479442;8309.501429914104;8327.833443331221;4352.673549483801;4372.737635741865;4372.868813935587;4378.10248898975;4385.210662080856;3580.928381432409;3691.5754688210664;3974.940375894364;4048.9392659110135;4066.5933406659096;3380;3435.4675025371107;3499.055255081114;3513.2797776455764;3518.1908161123083;1054.5781687325132;1055.1137044882337;1055.963041293551;1067.0183431243274;1082.2316155529504;719.7516856684435;851.7998270406449;852.6326772964205;853.9188777066169;875.282268539804;4895.388076490439;4997.550220480101;5032.144535428986;5036.445990940993;5073.416557095908;388.2894303424726;392.15881933253013;393.93106206414336;409.6272392123154;418.33902434153964;3619.8151879386;3677.5219221501034;3738.420544815558;3747.0445036516057;3749.5687995880276;9230.307726920373;9479.76300592477;9598.520073996273;9610.279229057849;9614.375065620712;9365.344425890258;9434.669786557437;9469.810603787886;9487.905120948702;9514.429134251966;6641.867162656623;6990.825229369307;7143.214246432888;7151;7151.213366529678;5433.4566543345345;5450.31871016123;5470.5350045245;5633.408492108433;5657;8469.195426434479;8588.914110858812;8657.350698697748;8684.782880427787;8689.17452841979;8551.786205344915;8718.956405217934;8817.823643526965;8876.911230887608;8912;6084.90872636902;6338.873222535616;6518.120053792677;6529.020646902813;6648.368405001711;7475.738349157753;7496.962515187389;7525.962370188333;7528.698852045857;7552.811179720551;6757.662116894234;6867.553609015597;6934.375906168469;6976.093107895941;7015.158181018311;6665.600063996176;6684.498662600227;6699.631520266432;6748.0552722617895;6915;6195.81412557624;6197.411245931629;6251.031090181029;6254.781082662085;6254.781082662085;4469.3966314548425;4510.030343476168;4562.840300589463;4643.048175124159;4691.624670026322;4537.410136682211;4610.792514336796;4656.3713898623255;4667.579917807414;4670.229412146958 18:50:55 INFO - PROCESS | 3278 | |4;prototype.html;283.6326956591214;286.6445607446766;288.41739685834614;292.48960563816144;295.9127057518032;4843.321934929113;4869.586085182759;4893.363862697849;4916.262560615909;4938.851834444972;4557.794899229534;4639.605633521155;4658.487566367697;4789.904201915968;4804.711717296965;3793.696504279651;3802.771833689974;3805.181885894532;3814.1608846053823;3831.808409579524;3791.241751649667;3793.753406028613;3795.3737633290475;3801.3537698591167;3806.619338066199;3583.3370826397068;3595.209054008114;3749.1002159481664;3777.414500752388;3797.373433383488;3724.9756317012766;3732.320455794903;3777.773333599979;3780.2250538639514;3807.257584770957;3305.4876494143446;3321.8671253149846;3331.4003479373705;3341.2649217172184;3346.6318704942682;4463.709858859163;4570.948681803171;4631.536846315375;4650.697704649187;4675.696079754805;4179.811908464127;4262.2754131797665;4317;4329.307310830252;4332.501762297313;4914.385701787277;4945.629077819142;4987.226979818098;4992.251162325644;5015;3604;3609.097725568608;3613.313470440608;3625.963740362589;3635.5827669381565;5073;5241.484087637917;5250.18622113573;5262.158054711228;5270.736463176864;3683.3001729671278;3690.132818787583;3705.370087085202;3713.517242758478;3717;3725.5343082114737;3725.5842779744066;3729.347364211252;3743.5694895086867;3752.418375151856;3947.8156553033973;4215.683823713233;4268.87193384199;4278.764667943293;4283.421738065384;5081.415637201695;5088.847334579968;5095.8980820383385;5100.693958362436;5107.897842043212;1173.6303064534645;1218.6344096770933;1252.5553428532921;1254.7553227120625;1264.778663733843;797.2306724011241;799.8120441696196;799.9080105787792;800.0119851982796;811.0834756724981;3994.3423479256367;4001.559828418872;4015.558288588253;4017.4978127734034;4038.7374820636564;4600.539946005372;4611.169989401873;4615.9076818463445;4625.912910466038;4632.652551058684;3967.682585393162;3981.7411868228473;3985.3822657488136;4013.3344661964957;4018.2767101921936;2331.8107765039954;2465.680860739496;2473.431110844516;2477.157766359446;2480;4329.6969212154845;4357.128574285156;4409.691321607521;4411.382406463098;4454.3986561813745;4725.834595789131;4735.786889589976;4755.358026666356;4779.832705855348;4789.640776941744;4257.3613957905945;4268.999216018823;4391.279374672304;4435.223835828781;4438.467383913951;4547.704399214041;4568.771561421849;4584.77076146198;4620.468646105606;4678.064387122521;4462.375267462623;4525.208088584551;4529.343245229357;4534.909301814011;4543.136804007228;4316.460442444694;4339.305711086337;4345.435093437959;4348.586884246118;4612.6771126022195;3541.4864844596873;3580.7620237351657;3585.1933315003917;3586.228960773446;3596.3346780845627;5209.140491818959;5209.973950130225;5242.554382877498;5263.473652634706;5285.154375299933;4828.903421931612;4910.484399138039;5059.5952323814745;5072.492750724898;5094.796208151781;5048.864005598858;5061.13960626687;5079.11115554769;5086.109930762028;5115.593211866766;4831.371921650163;5011.148104822116;5021.121303771898;5032.899342013213;5046.722430266371;5089.9745501272255;5117.569154651511;5126.359205099363;5127.2565478004735;5156.40701319338;4961.1070007397575;4976.527229913152;4982.800687972585;4993.550580447777;5014.899702005867 18:50:55 INFO - PROCESS | 3278 | |5;yui.html;606.0967370136323;649.2501161158863;711.14307259752;711.7473296979568;733.8495608400277;10698.358098514085;11469.483873225714;11472.369019703912;12081.818772718394;12423.254604723701;12778.808317875239;12891.162074465177;12907.902828259586;13038.34808259588;13072.673183170426;609.9451049405552;776.8252143267777;782.0927723840347;795.9602019899011;797.4059325802285;796.1719811396141;796.8087658961837;800.0918956983836;800.2117913854848;800.3117319105564;836.6109758962082;837.4975014991018;839.412411312081;839.6389552492426;839.9958000210021;641.8491654461197;799.996000020002;817.7955511122219;818.4066551749993;820.7086484298048;606.1059936593533;606.2694453183902;607.1318015238207;607.2227548737623;607.3744043635063;11608.6517404478;11609.419529023515;11675.241109327866;11676.065914726887;11710.180287379802;12145.271283723005;12683.492660293578;12741.235525868478;12779.488820447172;13020.479180832755;11523.366214858179;11663.708407289801;11689.824652630217;11933.761324773455;12178.939105304416;4058.1883623275166;4061.0862555924978;4124.072083781155;4168.874933751992;4202.285611446062;56980.430195697925;57732;57980;59230.40769592335;59426;9911.355761875599;9980.251481138943;10003.499825008721;10124.392536447836;10253.231007674454;17612.823871761375;17619.55951101233;17627.383041593483;17635.823641763418;17684.91157544204;6360.745570177233;6475.255345635313;6489.1888513935755;6497.18785151856;6503.28463868974;3492.511048453219;3509.192885636318;3549.893503194908;3610.548681414823;3616.8734094306574;2620.646212761291;2714.280715610365;2721.2516557946724;2724.2372135802007;2730.8361498310164;496.76651973572365;497.4329264638315;502.3168490852438;508.4077050236507;510.2856001597793;466.97665116744025;516.3468212710924;525.4404059676432;530.3751288761989;541.6966498760706;7680.9231907680205;7688.577128258;7743.651535680908;7937.4838850433;7938.285554300025;519.7868873761739;524.0200675866786;536.2813829468531;544.1212441906292;551.9199716041231;3444.345574340867;3523.3481805865995;3544.113971507123;3576.7496275261;3588.8564457421935;85674.14325856787;86351.56824215964;87697;90151.54924225337;91575;51191.488085118675;75382.62308688421;76756;77123;78267;67560;68868;69590;69630.3036969634;69819.3018069823;6809.353111454602;6956.652167391509;6981.965090174516;7175.995360649409;7214.206437291999;41542;42690.57309426866;43104.56895431005;43234;43755.56244437515;55201.447985521234;55290;56821.43178568161;60741.392586075344;60947;8915.866262006204;8931.73204803857;9041.728748137566;9060.501672408082;9227.30795190377;9096.818063638559;9243.907560924574;9376.390534615228;9466.195373393339;9602.615895364108;8500.192481714228;8780.385373024083;8845.336599755174;8917.242034427147;9089.909100908906;8455.873161902453;8550.78623034429;8555.61499732526;8617.87073193915;8827.249683776694;8711.25954293892;8837.823243534966;9006.189442950166;9088.954555227181;9224.769380765534;7154.35610795031;7249.746258880914;8611.268042216232;9006.324525660471;9290.489023103524;9604.759881002752;10057.245706572183;10100.949495252476;10237.283390162616;10266.743331416774 18:50:55 INFO - PROCESS | 3278 | __end_tp_report 18:50:55 INFO - PROCESS | 3278 | __start_cc_report 18:50:55 INFO - PROCESS | 3278 | _x_x_mozilla_cycle_collect,1184 18:50:55 INFO - PROCESS | 3278 | __end_cc_report 18:50:55 INFO - PROCESS | 3278 | __startTimestamp1464832255821__endTimestamp 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | ------- Summary: start ------- 18:50:55 INFO - PROCESS | 3278 | Number of tests: 6 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#0] dojo.html Cycles:165 Average:4677.75 Median:4796.59 stddev:497.19 (10.4%) stddev-sans-first:472.11 18:50:55 INFO - PROCESS | 3278 | Values: 2632.1 2654.3 2673.5 2692.7 2694.3 4548.8 4605.7 4643.2 4717.2 4751.7 4692.9 4711.5 4717.3 4761.8 4762.0 4147.3 4265.6 4372.0 4415.1 4530.8 4274.5 4314.1 4337.1 4340.8 4388.0 4208.1 4238.9 4287.0 4340.4 4355.5 4278.9 4286.6 4333.7 4336.9 4337.4 4331.3 4336.3 4343.6 4355.0 4373.5 4478.8 4585.3 4639.6 4646.9 4685.8 5076.5 5109.8 5117.2 5132.5 5133.8 4933.4 5126.4 5128.1 5166.9 5178.4 4737.6 4775.8 4782.1 4817.5 4842.3 5300.0 5326.6 5329.8 5330.3 5333.2 4790.8 5086.5 5093.5 5101.5 5110.1 4971.7 5008.6 5010.1 5018.7 5020.0 4978.6 4996.1 5000.9 5003.1 5066.2 4816.0 4832.9 4981.4 4996.6 5030.5 4266.8 4312.7 4314.0 4322.3 4329.7 3731.5 3743.5 3753.7 3774.2 3785.6 4271.1 4356.8 4388.3 4393.3 4426.4 5112.1 5160.0 5162.2 5165.3 5217.7 4382.7 4391.6 4397.3 4440.6 4456.5 4449.8 4487.0 4521.4 4612.6 4630.2 4754.8 4769.7 4783.5 4817.6 4823.0 5038.3 5043.3 5043.8 5046.3 5047.3 4598.7 4781.3 4789.8 4796.6 4828.1 5022.4 5028.4 5043.1 5059.7 5098.1 5104.0 5117.1 5128.4 5130.1 5135.7 4764.0 4887.8 4905.0 4925.7 4931.7 4864.8 4930.6 4934.0 4944.1 4991.2 4672.9 4863.0 4870.2 4872.2 4927.7 4820.7 4848.5 4910.9 4919.5 4986.1 4833.5 4853.8 4906.4 4907.5 4955.9 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#1] ext.html Cycles:180 Average:45287.03 Median:35622.79 stddev:79001.53 (221.8%) stddev-sans-first:79151.71 18:50:55 INFO - PROCESS | 3278 | Values: 540.2 585.3 586.6 601.0 614.5 29184.4 39977.6 40809.8 40858.8 45520.5 41348.6 41369.4 41722.8 41773.8 41963.2 7296.3 7704.2 7872.9 9832.3 10120.9 9925.1 10034.6 10065.1 10065.1 10084.6 9732.9 9745.1 9755.2 9786.0 9786.3 7339.6 10007.9 10276.5 10281.2 10300.5 9473.7 9560.5 9587.5 9756.3 9796.6 38857.0 39651.0 39668.8 39679.2 39685.2 3107.2 3128.6 3141.8 3314.2 3430.0 29570.3 29974.9 30048.4 30052.2 30716.5 3185.4 3219.0 3230.4 3236.5 3285.1 274726.1 278185.0 279378.0 279531.0 280625.0 59151.7 59683.1 59822.0 59943.0 59952.4 34730.5 35488.5 35814.0 37456.8 37554.6 13466.9 14702.3 14733.7 14787.7 14814.8 10545.2 11317.9 11317.9 11993.8 11995.3 19709.1 19769.3 19784.2 20079.0 20207.8 7205.5 7215.5 7315.2 7497.0 7546.7 838.8 950.8 951.1 961.4 975.3 28109.4 28633.0 29142.7 29331.6 29396.6 964.2 992.3 1013.6 1017.8 1029.3 14742.9 15192.8 15275.8 15339.1 15357.2 383691.0 387787.0 388153.0 389395.0 390092.0 51184.0 51664.5 51677.5 51817.0 51836.0 1079.8 1111.5 1167.3 1221.4 1233.9 26720.0 27910.3 27988.0 28616.9 28838.6 40259.8 40398.4 40913.4 40917.4 40998.6 206466.0 207033.0 207940.0 208074.0 208336.0 22640.7 24952.1 25212.7 26059.5 26130.0 41515.2 41717.8 42054.2 42213.4 42339.8 36556.3 36621.0 36746.6 36888.4 37538.6 36197.6 36880.6 37377.6 37427.0 37491.8 35056.0 35757.1 35842.1 36468.0 36827.8 31714.4 32817.5 32881.7 33030.7 33136.5 24602.8 24934.9 25053.4 25176.7 25195.9 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#2] jquery.html Cycles:180 Average:22577.28 Median:5993.08 stddev:56926.50 (949.9%) stddev-sans-first:57061.79 18:50:55 INFO - PROCESS | 3278 | Values: 376.4 378.1 383.7 391.5 394.2 15802.4 17120.9 17679.9 17879.2 17936.1 16342.3 16652.0 17065.2 17135.0 17253.7 5046.8 5213.5 5220.5 5232.1 5251.3 3676.4 3878.6 5756.1 6070.7 6071.1 5915.4 6126.2 6130.3 6153.2 6155.6 6071.8 6086.9 6089.9 6200.2 6211.4 3393.9 5018.8 5303.2 5303.6 5402.5 20043.8 20318.1 20535.1 20649.2 20692.1 1923.3 2068.6 2159.3 2385.8 2522.5 12524.2 12951.4 13047.8 13538.5 13838.5 1673.4 1820.4 1872.6 1873.3 1907.0 67219.3 67312.3 67867.7 68205.3 69826.7 8401.3 8635.5 9046.3 9104.2 9183.8 12064.6 12070.8 12095.2 12137.2 12156.8 6695.2 6736.6 6764.6 6845.5 6914.2 6546.2 6562.1 6565.9 6621.6 6750.3 3834.3 3873.2 3894.3 3940.3 3948.5 1716.6 1755.8 1839.4 1847.8 1864.2 838.1 863.8 875.8 898.5 907.4 15869.0 16513.5 16666.4 16734.7 17208.0 817.9 873.7 891.7 900.1 905.6 9549.0 9692.5 9760.7 9796.5 9815.4 334106.0 344243.0 345090.0 345105.0 345929.0 66339.0 77514.6 78186.6 79783.6 80559.0 909.3 922.3 940.3 954.0 958.6 14234.3 14754.6 14791.9 15273.2 15806.6 34007.0 35001.1 35019.6 35793.6 35845.3 44984.6 47507.3 47869.5 47992.0 48465.0 6993.1 7084.5 7160.1 7250.2 7372.9 6125.8 7840.4 8620.3 10374.0 10739.1 8467.3 8766.2 9170.3 9220.7 9439.8 8606.4 9409.6 9425.1 9492.6 9522.0 8395.0 8424.5 8872.0 9025.1 9289.9 6949.9 7245.4 7356.0 7399.5 7429.6 8065.5 8773.2 8904.8 8986.4 9489.6 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#3] mootools.html Cycles:180 Average:5018.76 Median:4793.51 stddev:2810.65 (58.6%) stddev-sans-first:2803.98 18:50:55 INFO - PROCESS | 3278 | Values: 1211.9 1212.0 1212.4 1222.4 1721.5 6626.7 7428.6 7681.1 7704.3 7820.1 7644.2 7772.0 7776.1 7778.8 7779.6 2198.8 2234.0 2236.6 2237.2 2238.1 2133.8 2174.1 2175.4 2178.9 2179.1 2203.0 2206.8 2213.9 2218.2 2247.8 2143.6 2162.2 2164.6 2166.0 2167.7 2064.3 2126.6 2129.8 2130.5 2130.9 7379.2 7587.1 7663.5 7707.3 7718.9 4306.1 4410.7 4412.9 4420.4 4421.6 2833.3 2970.5 3009.6 3010.2 3022.8 967.6 977.4 977.8 984.5 1002.3 10152.4 10274.9 10304.3 10310.3 10318.2 4032.2 4049.1 4117.8 4120.1 4129.8 8258.5 8290.8 8304.1 8309.5 8327.8 4352.7 4372.7 4372.9 4378.1 4385.2 3580.9 3691.6 3974.9 4048.9 4066.6 3380.0 3435.5 3499.1 3513.3 3518.2 1054.6 1055.1 1056.0 1067.0 1082.2 719.8 851.8 852.6 853.9 875.3 4895.4 4997.6 5032.1 5036.4 5073.4 388.3 392.2 393.9 409.6 418.3 3619.8 3677.5 3738.4 3747.0 3749.6 9230.3 9479.8 9598.5 9610.3 9614.4 9365.3 9434.7 9469.8 9487.9 9514.4 6641.9 6990.8 7143.2 7151.0 7151.2 5433.5 5450.3 5470.5 5633.4 5657.0 8469.2 8588.9 8657.4 8684.8 8689.2 8551.8 8719.0 8817.8 8876.9 8912.0 6084.9 6338.9 6518.1 6529.0 6648.4 7475.7 7497.0 7526.0 7528.7 7552.8 6757.7 6867.6 6934.4 6976.1 7015.2 6665.6 6684.5 6699.6 6748.1 6915.0 6195.8 6197.4 6251.0 6254.8 6254.8 4469.4 4510.0 4562.8 4643.0 4691.6 4537.4 4610.8 4656.4 4667.6 4670.2 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#4] prototype.html Cycles:180 Average:4050.22 Median:4423.30 stddev:1176.36 (26.6%) stddev-sans-first:1145.18 18:50:55 INFO - PROCESS | 3278 | Values: 283.6 286.6 288.4 292.5 295.9 4843.3 4869.6 4893.4 4916.3 4938.9 4557.8 4639.6 4658.5 4789.9 4804.7 3793.7 3802.8 3805.2 3814.2 3831.8 3791.2 3793.8 3795.4 3801.4 3806.6 3583.3 3595.2 3749.1 3777.4 3797.4 3725.0 3732.3 3777.8 3780.2 3807.3 3305.5 3321.9 3331.4 3341.3 3346.6 4463.7 4570.9 4631.5 4650.7 4675.7 4179.8 4262.3 4317.0 4329.3 4332.5 4914.4 4945.6 4987.2 4992.3 5015.0 3604.0 3609.1 3613.3 3626.0 3635.6 5073.0 5241.5 5250.2 5262.2 5270.7 3683.3 3690.1 3705.4 3713.5 3717.0 3725.5 3725.6 3729.3 3743.6 3752.4 3947.8 4215.7 4268.9 4278.8 4283.4 5081.4 5088.8 5095.9 5100.7 5107.9 1173.6 1218.6 1252.6 1254.8 1264.8 797.2 799.8 799.9 800.0 811.1 3994.3 4001.6 4015.6 4017.5 4038.7 4600.5 4611.2 4615.9 4625.9 4632.7 3967.7 3981.7 3985.4 4013.3 4018.3 2331.8 2465.7 2473.4 2477.2 2480.0 4329.7 4357.1 4409.7 4411.4 4454.4 4725.8 4735.8 4755.4 4779.8 4789.6 4257.4 4269.0 4391.3 4435.2 4438.5 4547.7 4568.8 4584.8 4620.5 4678.1 4462.4 4525.2 4529.3 4534.9 4543.1 4316.5 4339.3 4345.4 4348.6 4612.7 3541.5 3580.8 3585.2 3586.2 3596.3 5209.1 5210.0 5242.6 5263.5 5285.2 4828.9 4910.5 5059.6 5072.5 5094.8 5048.9 5061.1 5079.1 5086.1 5115.6 4831.4 5011.1 5021.1 5032.9 5046.7 5090.0 5117.6 5126.4 5127.3 5156.4 4961.1 4976.5 4982.8 4993.6 5014.9 18:50:55 INFO - PROCESS | 3278 | 18:50:55 INFO - PROCESS | 3278 | [#5] yui.html Cycles:180 Average:16132.74 Median:607.30 stddev:23018.69 (3790.3%) stddev-sans-first:23053.74 18:50:55 INFO - PROCESS | 3278 | Values: 606.1 649.3 711.1 711.7 733.8 10698.4 11469.5 11472.4 12081.8 12423.3 12778.8 12891.2 12907.9 13038.3 13072.7 609.9 776.8 782.1 796.0 797.4 796.2 796.8 800.1 800.2 800.3 836.6 837.5 839.4 839.6 840.0 641.8 800.0 817.8 818.4 820.7 606.1 606.3 607.1 607.2 607.4 11608.7 11609.4 11675.2 11676.1 11710.2 12145.3 12683.5 12741.2 12779.5 13020.5 11523.4 11663.7 11689.8 11933.8 12178.9 4058.2 4061.1 4124.1 4168.9 4202.3 56980.4 57732.0 57980.0 59230.4 59426.0 9911.4 9980.3 10003.5 10124.4 10253.2 17612.8 17619.6 17627.4 17635.8 17684.9 6360.7 6475.3 6489.2 6497.2 6503.3 3492.5 3509.2 3549.9 3610.5 3616.9 2620.6 2714.3 2721.3 2724.2 2730.8 496.8 497.4 502.3 508.4 510.3 467.0 516.3 525.4 530.4 541.7 7680.9 7688.6 7743.7 7937.5 7938.3 519.8 524.0 536.3 544.1 551.9 3444.3 3523.3 3544.1 3576.7 3588.9 85674.1 86351.6 87697.0 90151.5 91575.0 51191.5 75382.6 76756.0 77123.0 78267.0 67560.0 68868.0 69590.0 69630.3 69819.3 6809.4 6956.7 6982.0 7176.0 7214.2 41542.0 42690.6 43104.6 43234.0 43755.6 55201.4 55290.0 56821.4 60741.4 60947.0 8915.9 8931.7 9041.7 9060.5 9227.3 9096.8 9243.9 9376.4 9466.2 9602.6 8500.2 8780.4 8845.3 8917.2 9089.9 8455.9 8550.8 8555.6 8617.9 8827.2 8711.3 8837.8 9006.2 9089.0 9224.8 7154.4 7249.7 8611.3 9006.3 9290.5 9604.8 10057.2 10100.9 10237.3 10266.7 18:50:55 INFO - PROCESS | 3278 | -------- Summary: end -------- 18:50:55 INFO - PROCESS | 3278 | 18:50:56 INFO - TEST-INFO | 3278: exit 0 18:50:56 INFO - TEST-OK | dromaeo_css | took 1118974ms 18:50:56 INFO - TEST-START | kraken 18:50:56 INFO - operating with platform_type : linux_ 18:50:56 INFO - Initialising browser for kraken test... 18:50:56 INFO - TEST-INFO | started process 3456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnDJ3Vz/profile http://localhost:57262/getInfo.html) 18:51:00 INFO - PROCESS | 3456 | __metrics Screen width/height:1600/1200 18:51:00 INFO - PROCESS | 3456 | colorDepth:24 18:51:00 INFO - PROCESS | 3456 | Browser inner width/height: 1024/697 18:51:00 INFO - PROCESS | 3456 | __metrics 18:51:02 ERROR - PROCESS | 3456 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:51:02 INFO - TEST-INFO | 3456: exit 0 18:51:02 INFO - Browser initialized. 18:51:02 INFO - Running cycle 1/1 for kraken test... 18:51:02 INFO - TEST-INFO | started process 3535 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnDJ3Vz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:14 INFO - PROCESS | 3535 | RSS: Main: 158212096 18:51:14 INFO - PROCESS | 3535 | 18:51:17 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:18 INFO - PROCESS | 3535 | RSS: Main: 215130112 18:51:18 INFO - PROCESS | 3535 | 18:51:21 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-dft) 18:51:22 INFO - PROCESS | 3535 | RSS: Main: 201125888 18:51:22 INFO - PROCESS | 3535 | 18:51:25 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-fft) 18:51:25 INFO - PROCESS | 3535 | RSS: Main: 271069184 18:51:25 INFO - PROCESS | 3535 | 18:51:27 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator) 18:51:28 INFO - PROCESS | 3535 | RSS: Main: 181051392 18:51:28 INFO - PROCESS | 3535 | 18:51:30 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:31 INFO - PROCESS | 3535 | RSS: Main: 184647680 18:51:31 INFO - PROCESS | 3535 | 18:51:34 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:35 INFO - PROCESS | 3535 | RSS: Main: 221028352 18:51:35 INFO - PROCESS | 3535 | 18:51:38 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:39 INFO - PROCESS | 3535 | RSS: Main: 221294592 18:51:39 INFO - PROCESS | 3535 | 18:51:42 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial) 18:51:42 INFO - PROCESS | 3535 | RSS: Main: 221564928 18:51:42 INFO - PROCESS | 3535 | 18:51:44 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:45 INFO - PROCESS | 3535 | RSS: Main: 205090816 18:51:45 INFO - PROCESS | 3535 | 18:51:47 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:47 INFO - PROCESS | 3535 | RSS: Main: 210288640 18:51:47 INFO - PROCESS | 3535 | 18:51:49 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:50 INFO - PROCESS | 3535 | RSS: Main: 217636864 18:51:50 INFO - PROCESS | 3535 | 18:51:53 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:53 INFO - PROCESS | 3535 | RSS: Main: 222994432 18:51:53 INFO - PROCESS | 3535 | 18:51:56 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:57 INFO - PROCESS | 3535 | RSS: Main: 221061120 18:51:57 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57262/tests/kraken/driver.html?testName=ai-astar) 18:51:59 INFO - PROCESS | 3535 | RSS: Main: 263745536 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | __start_tp_report 18:51:59 INFO - PROCESS | 3535 | _x_x_mozilla_page_load 18:51:59 INFO - PROCESS | 3535 | _x_x_mozilla_page_load_details 18:51:59 INFO - PROCESS | 3535 | |i|pagename|runs| 18:51:59 INFO - PROCESS | 3535 | |0;ai-astar;116;112;113;117;114;114;114;113;112;114 18:51:59 INFO - PROCESS | 3535 | |1;audio-beat-detection;157;134;131;184;129;137;163;130;178;137 18:51:59 INFO - PROCESS | 3535 | |2;audio-dft;163;161;160;161;163;160;163;161;160;161 18:51:59 INFO - PROCESS | 3535 | |3;audio-fft;74;75;78;112;74;75;73;74;98;75 18:51:59 INFO - PROCESS | 3535 | |4;audio-oscillator;85;85;116;85;86;88;135;84;84;127 18:51:59 INFO - PROCESS | 3535 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;117;117 18:51:59 INFO - PROCESS | 3535 | |6;imaging-darkroom;147;146;147;146;146;146;146;145;146;146 18:51:59 INFO - PROCESS | 3535 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;94 18:51:59 INFO - PROCESS | 3535 | |8;json-parse-financial;64;64;63;63;63;66;64;64;63;64 18:51:59 INFO - PROCESS | 3535 | |9;json-stringify-tinderbox;65;65;65;65;66;65;70;64;65;65 18:51:59 INFO - PROCESS | 3535 | |10;stanford-crypto-aes;86;82;83;83;86;84;83;85;121;84 18:51:59 INFO - PROCESS | 3535 | |11;stanford-crypto-ccm;137;140;131;132;130;130;133;142;140;132 18:51:59 INFO - PROCESS | 3535 | |12;stanford-crypto-pbkdf2;172;173;178;175;171;170;171;172;173;171 18:51:59 INFO - PROCESS | 3535 | |13;stanford-crypto-sha256-iterative;68;67;72;68;68;72;71;67;66;66 18:51:59 INFO - PROCESS | 3535 | __end_tp_report 18:51:59 INFO - PROCESS | 3535 | __start_cc_report 18:51:59 INFO - PROCESS | 3535 | _x_x_mozilla_cycle_collect,1884 18:51:59 INFO - PROCESS | 3535 | __end_cc_report 18:51:59 INFO - PROCESS | 3535 | __startTimestamp1464832319477__endTimestamp 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | ------- Summary: start ------- 18:51:59 INFO - PROCESS | 3535 | Number of tests: 14 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.60 (1.4%) stddev-sans-first:1.50 18:51:59 INFO - PROCESS | 3535 | Values: 116.0 112.0 113.0 117.0 114.0 114.0 114.0 113.0 112.0 114.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:147.00 stddev:20.85 (14.2%) stddev-sans-first:21.86 18:51:59 INFO - PROCESS | 3535 | Values: 157.0 134.0 131.0 184.0 129.0 137.0 163.0 130.0 178.0 137.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.17 18:51:59 INFO - PROCESS | 3535 | Values: 163.0 161.0 160.0 161.0 163.0 160.0 163.0 161.0 160.0 161.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:13.24 (17.7%) stddev-sans-first:13.81 18:51:59 INFO - PROCESS | 3535 | Values: 74.0 75.0 78.0 112.0 74.0 75.0 73.0 74.0 98.0 75.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:85.00 stddev:20.21 (23.8%) stddev-sans-first:20.92 18:51:59 INFO - PROCESS | 3535 | Values: 85.0 85.0 116.0 85.0 86.0 88.0 135.0 84.0 84.0 127.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:51:59 INFO - PROCESS | 3535 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 18:51:59 INFO - PROCESS | 3535 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:51:59 INFO - PROCESS | 3535 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 18:51:59 INFO - PROCESS | 3535 | Values: 64.0 64.0 63.0 63.0 63.0 66.0 64.0 64.0 63.0 64.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.00 stddev:1.65 (2.5%) stddev-sans-first:1.74 18:51:59 INFO - PROCESS | 3535 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 70.0 64.0 65.0 65.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:84.00 stddev:11.78 (14.0%) stddev-sans-first:12.47 18:51:59 INFO - PROCESS | 3535 | Values: 86.0 82.0 83.0 83.0 86.0 84.0 83.0 85.0 121.0 84.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:135.00 stddev:4.60 (3.4%) stddev-sans-first:4.80 18:51:59 INFO - PROCESS | 3535 | Values: 137.0 140.0 131.0 132.0 130.0 130.0 133.0 142.0 140.0 132.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:172.50 stddev:2.37 (1.4%) stddev-sans-first:2.50 18:51:59 INFO - PROCESS | 3535 | Values: 172.0 173.0 178.0 175.0 171.0 170.0 171.0 172.0 173.0 171.0 18:51:59 INFO - PROCESS | 3535 | 18:51:59 INFO - PROCESS | 3535 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.32 (3.4%) stddev-sans-first:2.46 18:51:59 INFO - PROCESS | 3535 | Values: 68.0 67.0 72.0 68.0 68.0 72.0 71.0 67.0 66.0 66.0 18:51:59 INFO - PROCESS | 3535 | -------- Summary: end -------- 18:51:59 INFO - PROCESS | 3535 | 18:52:00 INFO - TEST-INFO | 3535: exit 0 18:52:00 INFO - TEST-OK | kraken | took 63838ms 18:52:00 INFO - SUITE-END | took 1182s 18:52:00 INFO - Completed test suite (00:19:42) 18:52:00 INFO - javascript benchmark 18:52:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2632.1313966391085, 2654.3231475973625, 2673.478671659026, 2692.690340610829, 2694.312950197699, 4548.840790572331, 4605.700629459091, 4643.187442197619, 4717.198076327024, 4751.738654374012, 4692.906141877161, 4711.505291944348, 4717.315989181567, 4761.8095276189, 4761.952380476194, 4147.294959856831, 4265.552117027714, 4371.978140109295, 4415.072834704716, 4530.75080870552, 4274.508431530383, 4314.072474418, 4337.089211265638, 4340.761258130801, 4387.978060109695, 4208.116295577947, 4238.851640192609, 4286.978565107186, 4340.435743353375, 4355.5208927018, 4278.914421711579, 4286.57134286572, 4333.653307735373, 4336.891577710551, 4337.414449049369, 4331.285337919239, 4336.3278691802825, 4343.630791382736, 4354.956450435487, 4373.518912919576, 4478.753668548228, 4585.312203169551, 4639.559241872051, 4646.9070618587775, 4685.789139488731, 5076.543111120017, 5109.770060347293, 5117.1556693145585, 5132.486751324875, 5133.768980395891, 4933.358663373739, 5126.384833819928, 5128.102582048126, 5166.870828229325, 5178.378594568639, 4737.597304229144, 4775.808967641325, 4782.0914026334885, 4817.459167511876, 4842.273658951115, 5300.019496393103, 5326.573874090063, 5329.840104796862, 5330.280412144389, 5333.2266821310695, 4790.760461976887, 5086.516780905808, 5093.490650934951, 5101.51535604117, 5110.105731496973, 4971.726555039435, 5008.5993120550265, 5010.098182327216, 5018.673786203885, 5020.046191223656, 4978.551930326288, 4996.075725990703, 5000.924986125211, 5003.074431230234, 5066.189409694505, 4815.951840481621, 4832.9275060874115, 4981.37732783402, 4996.575291100297, 5030.471800460972, 4266.765327906933, 4312.676549258818, 4313.97843010783, 4322.308430651081, 4329.696921215547, 3731.477593136963, 3743.5320584926885, 3753.680937120321, 3774.169682669808, 3785.621437856192, 4271.145770845781, 4356.76037817923, 4388.29787234041, 4393.253146965024, 4426.380306757057, 5112.079825631422, 5159.993801208803, 5162.174052151638, 5165.328507294028, 5217.686938783685, 4382.715123516961, 4391.626711729538, 4397.34039894012, 4440.555944405533, 4456.509783923766, 4449.7997590108525, 4487.035287413222, 4521.434820647419, 4612.607928326062, 4630.166570017429, 4754.786034628519, 4769.689970151929, 4783.545563171493, 4817.614590832725, 4823.011282686971, 5038.294638750504, 5043.344365232496, 5043.798248070183, 5046.268291097696, 5047.318611987482, 4598.655100867381, 4781.282807578891, 4789.7605119743175, 4796.592289655418, 4828.082664293773, 5022.422421421583, 5028.446870844131, 5043.092243396077, 5059.671121377099, 5098.107831129463, 5104.03023425548, 5117.053345131163, 5128.410232823124, 5130.102232109291, 5135.7432128394175, 4763.952360476351, 4887.804487820447, 4905.018996200845, 4925.704457732548, 4931.728754918514, 4864.805407783649, 4930.630202734881, 4933.975330123327, 4944.1100601892, 4991.176455884884, 4672.906541869075, 4863.027394521181, 4870.155654383662, 4872.196087645499, 4927.704337739747, 4820.71075735457, 4848.490908454562, 4910.852674419773, 4919.508049195052, 4986.102501549756, 4833.540813622701, 4853.781579828987, 4906.435759887516, 4907.460179380194, 4955.851324460272], "value": 4646.168129590217, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.2442063294294, 585.3064119018966, 586.5981802465315, 601.0233370772494, 614.520673874378, 29184.416311673755, 39977.60022399775, 40809.795951020205, 40858.795706021505, 45520.54479455204, 41348.58651413485, 41369.37945930813, 41722.79138604303, 41773.79113104438, 41963.16073678532, 7296.270372962687, 7704.19105993871, 7872.858435526874, 9832.31173817833, 10120.949395253014, 9925.106740393363, 10034.648787292446, 10065.144462720678, 10065.144462720678, 10084.596616135346, 9732.854007189897, 9745.074217949283, 9755.219582433388, 9785.951070244675, 9786.314957952945, 7339.5963222022765, 10007.899921000806, 10276.537555809931, 10281.177505799518, 10300.536475858604, 9473.66842160521, 9560.474173920431, 9587.472689002103, 9756.317057805956, 9796.559154838049, 38857.0, 39651.0, 39668.801655991825, 39679.20641587181, 39685.20629587421, 3107.1765982014786, 3128.577642018321, 3141.842907854621, 3314.1880239341276, 3430.0052984634426, 29570.260743481584, 29974.85012574923, 30048.39903201924, 30052.24869378261, 30716.53925191124, 3185.442547554192, 3219.0, 3230.370077834823, 3236.4659831127838, 3285.1130194847497, 274726.1318467034, 278185.0, 279378.0, 279531.0, 280625.0, 59151.704241479376, 59683.104753428735, 59822.0, 59943.0, 59952.40047599556, 34730.479042814375, 35488.46767298492, 35814.030228337695, 37456.81271593625, 37554.624453755656, 13466.93266533661, 14702.338394772261, 14733.705325893636, 14787.704245915022, 14814.777778333333, 10545.156387488982, 11317.886821131684, 11317.943410282896, 11993.760124797454, 11995.340256285814, 19709.142174384633, 19769.30807421762, 19784.208631654572, 20079.0, 20207.777144514384, 7205.531640443354, 7215.49491535598, 7315.195328513859, 7496.962515187498, 7546.660400282, 838.8322335532918, 950.7908260182749, 951.0679534056663, 961.3703024518951, 975.317277905469, 28109.437811244068, 28632.99784507574, 29142.708572914424, 29331.560026599618, 29396.559051614244, 964.1852634523816, 992.2508506078009, 1013.5996281468779, 1017.760826205841, 1029.3155051890558, 14742.852571474363, 15192.772108418383, 15275.770863437057, 15339.0796552205, 15357.169627464253, 383691.0, 387787.0, 388153.0, 389395.0, 390092.0, 51184.0, 51664.48335516672, 51677.48322516802, 51817.0, 51836.0, 1079.8488211650376, 1111.5276007696534, 1167.258790667949, 1221.407617305606, 1233.8827811357908, 26720.0, 27910.3022424441, 27988.02041928523, 28616.85691571529, 28838.567421488275, 40259.79870100631, 40398.39402408907, 40913.38629920613, 40917.38623920703, 40998.59001409948, 206466.0, 207033.0, 207940.0, 208074.0, 208336.0, 22640.66039009383, 24952.12667556627, 25212.74787252104, 26059.478810424065, 26130.0, 41515.16969660651, 41717.79141104397, 42054.15891682088, 42213.36679949742, 42339.78830105953, 36556.26887462289, 36621.0, 36746.63253367539, 36888.396378049256, 37538.624613754604, 36197.63802361942, 36880.63119368772, 37377.62622373742, 37427.0, 37491.81254093712, 35056.0, 35757.1060723484, 35842.10394740153, 36468.0, 36827.81586092053, 31714.365712686078, 32817.50773738348, 32881.67118328882, 33030.669693303724, 33136.50295245621, 24602.753972460763, 24934.875325623256, 25053.373665658506, 25176.74823251744, 25195.8957541429], "value": 17913.07965169246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.4071587250081, 378.12464145503156, 383.67004376236434, 391.45392177911816, 394.1643715323515, 15802.446914357999, 17120.914395428037, 17679.911600441996, 17879.19543620537, 17936.103194840256, 16342.264598093085, 16652.0, 17065.232064557094, 17135.0, 17253.741193882102, 5046.848594542161, 5213.452587478317, 5220.530152286294, 5232.084385232569, 5251.3435820522245, 3676.3566375884247, 3878.631530004659, 5756.050251708463, 6070.72681729321, 6071.089336599523, 5915.438033386821, 6126.203593532857, 6130.295016073142, 6153.169322141498, 6155.569110162291, 6071.78748743794, 6086.908696369558, 6089.908651370233, 6200.193974783272, 6211.40991605799, 3393.8812141574926, 5018.824341148043, 5303.204519322094, 5303.628745987782, 5402.51377376038, 20043.79956200434, 20318.08568614416, 20535.075921583415, 20649.17403303866, 20692.068856901475, 1923.2883832981747, 2068.6069646767064, 2159.26584961114, 2385.8329916905823, 2522.4828910073393, 12524.248545087234, 12951.352432378344, 13047.781877080919, 13538.458461661521, 13838.515651952133, 1673.4059408909905, 1820.4447643468736, 1872.6441976024512, 1873.3068764557065, 1906.9523261918425, 67219.2863606594, 67312.32687673159, 67867.66066169686, 68205.31794682039, 69826.65086674535, 8401.327893768485, 8635.481871087755, 9046.276297896153, 9104.180623743763, 9183.77040573991, 12064.638060858188, 12070.758584828254, 12095.21381110225, 12137.211081279866, 12156.817647735113, 6695.163104611924, 6736.562123461959, 6764.594124352555, 6845.520813543004, 6914.204866440424, 6546.214454265519, 6562.146920900253, 6565.934340656628, 6621.569597976116, 6750.291219421988, 3834.309824231609, 3873.2253549290253, 3894.337962546374, 3940.330143875548, 3948.526176858738, 1716.5965997990463, 1755.806861245262, 1839.364800702223, 1847.8059803720685, 1864.1611274926067, 838.0655569040476, 863.8315528472014, 875.7810547363159, 898.4923518212189, 907.4237858959608, 15869.04785712861, 16513.504594862174, 16666.416675416545, 16734.74897876533, 17208.0, 817.8977627796526, 873.7204094689638, 891.6700820696384, 900.1133883125117, 905.6467977488785, 9549.0, 9692.46691431978, 9760.658376956915, 9796.461194634363, 9815.411075335503, 334106.0, 344243.0, 345090.0, 345105.0, 345929.0, 66339.0, 77514.6124269375, 78186.6090669543, 79783.60108199422, 80559.0, 909.3225546967551, 922.2898368256405, 940.3229674634252, 954.0, 958.5878072428783, 14234.288285585471, 14754.63113422173, 14791.92604036973, 15273.159976200972, 15806.60483487922, 34007.0, 35001.124971875906, 35019.64980350164, 35793.64206357903, 35845.28309433744, 44984.550154498036, 47507.28739068986, 47869.5213047865, 47992.0, 48465.0, 6993.125859267591, 7084.468664850054, 7160.069191005136, 7250.20247772734, 7372.889406658797, 6125.768340384748, 7840.392719492605, 8620.267277281502, 10374.0, 10739.14086873064, 8467.322614190973, 8766.21104100634, 9170.312226582899, 9220.723378298662, 9439.81120377575, 8606.397552171287, 9409.576569054272, 9425.104615061833, 9492.57283422234, 9522.0, 8395.0, 8424.536650484282, 8872.0, 9025.05236950137, 9289.907100928904, 6949.930500694928, 7245.384142347749, 7355.963220183865, 7399.48203625741, 7429.628518574158, 8065.475744076616, 8773.166549177819, 8904.821903562022, 8986.415882967585, 9489.620415183315], "value": 7990.158869861131, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1211.9454624541893, 1211.9575814846482, 1212.448336007117, 1222.3888055972013, 1721.5093698295989, 6626.66866656667, 7428.554286742799, 7681.116671582769, 7704.3451306639, 7820.061592608895, 7644.159142494335, 7771.961140194306, 7776.144624091346, 7778.805529861756, 7779.64991575379, 2198.8021078102965, 2233.9888300558555, 2236.552689462106, 2237.1722462688826, 2238.0935721032924, 2133.829293656504, 2174.0542863854275, 2175.4017645147633, 2178.8583742056794, 2179.1174574297434, 2202.9889850550726, 2206.8455208135438, 2213.9335819925345, 2218.223621732397, 2247.8426510144295, 2143.624865648513, 2162.2107930605375, 2164.632012557869, 2165.9783402165936, 2167.6531754919215, 2064.318774804311, 2126.617208902397, 2129.7976692214215, 2130.531283117727, 2130.9147634094616, 7379.225181355934, 7587.127480339776, 7663.501872378278, 7707.344875685518, 7718.884216736754, 4306.074194048295, 4410.669199810027, 4412.911741765179, 4420.359047938031, 4421.624161946206, 2833.2916770807296, 2970.495015847311, 3009.623797025372, 3010.232390740347, 3022.758179345647, 967.5500892085144, 977.4037836919473, 977.7653363192853, 984.5421878826309, 1002.3484734922358, 10152.39085654863, 10274.948625256826, 10304.278700490968, 10310.278280520366, 10318.226133039976, 4032.153247817991, 4049.1091959768805, 4117.814698338582, 4120.114175452292, 4129.834806607762, 8258.545779982038, 8290.834183316421, 8304.086550479442, 8309.501429914104, 8327.833443331221, 4352.673549483801, 4372.737635741865, 4372.868813935587, 4378.10248898975, 4385.210662080856, 3580.928381432409, 3691.5754688210664, 3974.940375894364, 4048.9392659110135, 4066.5933406659096, 3380.0, 3435.4675025371107, 3499.055255081114, 3513.2797776455764, 3518.1908161123083, 1054.5781687325132, 1055.1137044882337, 1055.963041293551, 1067.0183431243274, 1082.2316155529504, 719.7516856684435, 851.7998270406449, 852.6326772964205, 853.9188777066169, 875.282268539804, 4895.388076490439, 4997.550220480101, 5032.144535428986, 5036.445990940993, 5073.416557095908, 388.2894303424726, 392.15881933253013, 393.93106206414336, 409.6272392123154, 418.33902434153964, 3619.8151879386, 3677.5219221501034, 3738.420544815558, 3747.0445036516057, 3749.5687995880276, 9230.307726920373, 9479.76300592477, 9598.520073996273, 9610.279229057849, 9614.375065620712, 9365.344425890258, 9434.669786557437, 9469.810603787886, 9487.905120948702, 9514.429134251966, 6641.867162656623, 6990.825229369307, 7143.214246432888, 7151.0, 7151.213366529678, 5433.4566543345345, 5450.31871016123, 5470.5350045245, 5633.408492108433, 5657.0, 8469.195426434479, 8588.914110858812, 8657.350698697748, 8684.782880427787, 8689.17452841979, 8551.786205344915, 8718.956405217934, 8817.823643526965, 8876.911230887608, 8912.0, 6084.90872636902, 6338.873222535616, 6518.120053792677, 6529.020646902813, 6648.368405001711, 7475.738349157753, 7496.962515187389, 7525.962370188333, 7528.698852045857, 7552.811179720551, 6757.662116894234, 6867.553609015597, 6934.375906168469, 6976.093107895941, 7015.158181018311, 6665.600063996176, 6684.498662600227, 6699.631520266432, 6748.0552722617895, 6915.0, 6195.81412557624, 6197.411245931629, 6251.031090181029, 6254.781082662085, 6254.781082662085, 4469.3966314548425, 4510.030343476168, 4562.840300589463, 4643.048175124159, 4691.624670026322, 4537.410136682211, 4610.792514336796, 4656.3713898623255, 4667.579917807414, 4670.229412146958], "value": 3977.0178470763053, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.6326956591214, 286.6445607446766, 288.41739685834614, 292.48960563816144, 295.9127057518032, 4843.321934929113, 4869.586085182759, 4893.363862697849, 4916.262560615909, 4938.851834444972, 4557.794899229534, 4639.605633521155, 4658.487566367697, 4789.904201915968, 4804.711717296965, 3793.696504279651, 3802.771833689974, 3805.181885894532, 3814.1608846053823, 3831.808409579524, 3791.241751649667, 3793.753406028613, 3795.3737633290475, 3801.3537698591167, 3806.619338066199, 3583.3370826397068, 3595.209054008114, 3749.1002159481664, 3777.414500752388, 3797.373433383488, 3724.9756317012766, 3732.320455794903, 3777.773333599979, 3780.2250538639514, 3807.257584770957, 3305.4876494143446, 3321.8671253149846, 3331.4003479373705, 3341.2649217172184, 3346.6318704942682, 4463.709858859163, 4570.948681803171, 4631.536846315375, 4650.697704649187, 4675.696079754805, 4179.811908464127, 4262.2754131797665, 4317.0, 4329.307310830252, 4332.501762297313, 4914.385701787277, 4945.629077819142, 4987.226979818098, 4992.251162325644, 5015.0, 3604.0, 3609.097725568608, 3613.313470440608, 3625.963740362589, 3635.5827669381565, 5073.0, 5241.484087637917, 5250.18622113573, 5262.158054711228, 5270.736463176864, 3683.3001729671278, 3690.132818787583, 3705.370087085202, 3713.517242758478, 3717.0, 3725.5343082114737, 3725.5842779744066, 3729.347364211252, 3743.5694895086867, 3752.418375151856, 3947.8156553033973, 4215.683823713233, 4268.87193384199, 4278.764667943293, 4283.421738065384, 5081.415637201695, 5088.847334579968, 5095.8980820383385, 5100.693958362436, 5107.897842043212, 1173.6303064534645, 1218.6344096770933, 1252.5553428532921, 1254.7553227120625, 1264.778663733843, 797.2306724011241, 799.8120441696196, 799.9080105787792, 800.0119851982796, 811.0834756724981, 3994.3423479256367, 4001.559828418872, 4015.558288588253, 4017.4978127734034, 4038.7374820636564, 4600.539946005372, 4611.169989401873, 4615.9076818463445, 4625.912910466038, 4632.652551058684, 3967.682585393162, 3981.7411868228473, 3985.3822657488136, 4013.3344661964957, 4018.2767101921936, 2331.8107765039954, 2465.680860739496, 2473.431110844516, 2477.157766359446, 2480.0, 4329.6969212154845, 4357.128574285156, 4409.691321607521, 4411.382406463098, 4454.3986561813745, 4725.834595789131, 4735.786889589976, 4755.358026666356, 4779.832705855348, 4789.640776941744, 4257.3613957905945, 4268.999216018823, 4391.279374672304, 4435.223835828781, 4438.467383913951, 4547.704399214041, 4568.771561421849, 4584.77076146198, 4620.468646105606, 4678.064387122521, 4462.375267462623, 4525.208088584551, 4529.343245229357, 4534.909301814011, 4543.136804007228, 4316.460442444694, 4339.305711086337, 4345.435093437959, 4348.586884246118, 4612.6771126022195, 3541.4864844596873, 3580.7620237351657, 3585.1933315003917, 3586.228960773446, 3596.3346780845627, 5209.140491818959, 5209.973950130225, 5242.554382877498, 5263.473652634706, 5285.154375299933, 4828.903421931612, 4910.484399138039, 5059.5952323814745, 5072.492750724898, 5094.796208151781, 5048.864005598858, 5061.13960626687, 5079.11115554769, 5086.109930762028, 5115.593211866766, 4831.371921650163, 5011.148104822116, 5021.121303771898, 5032.899342013213, 5046.722430266371, 5089.9745501272255, 5117.569154651511, 5126.359205099363, 5127.2565478004735, 5156.40701319338, 4961.1070007397575, 4976.527229913152, 4982.800687972585, 4993.550580447777, 5014.899702005867], "value": 3673.6119671571782, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.0967370136323, 649.2501161158863, 711.14307259752, 711.7473296979568, 733.8495608400277, 10698.358098514085, 11469.483873225714, 11472.369019703912, 12081.818772718394, 12423.254604723701, 12778.808317875239, 12891.162074465177, 12907.902828259586, 13038.34808259588, 13072.673183170426, 609.9451049405552, 776.8252143267777, 782.0927723840347, 795.9602019899011, 797.4059325802285, 796.1719811396141, 796.8087658961837, 800.0918956983836, 800.2117913854848, 800.3117319105564, 836.6109758962082, 837.4975014991018, 839.412411312081, 839.6389552492426, 839.9958000210021, 641.8491654461197, 799.996000020002, 817.7955511122219, 818.4066551749993, 820.7086484298048, 606.1059936593533, 606.2694453183902, 607.1318015238207, 607.2227548737623, 607.3744043635063, 11608.6517404478, 11609.419529023515, 11675.241109327866, 11676.065914726887, 11710.180287379802, 12145.271283723005, 12683.492660293578, 12741.235525868478, 12779.488820447172, 13020.479180832755, 11523.366214858179, 11663.708407289801, 11689.824652630217, 11933.761324773455, 12178.939105304416, 4058.1883623275166, 4061.0862555924978, 4124.072083781155, 4168.874933751992, 4202.285611446062, 56980.430195697925, 57732.0, 57980.0, 59230.40769592335, 59426.0, 9911.355761875599, 9980.251481138943, 10003.499825008721, 10124.392536447836, 10253.231007674454, 17612.823871761375, 17619.55951101233, 17627.383041593483, 17635.823641763418, 17684.91157544204, 6360.745570177233, 6475.255345635313, 6489.1888513935755, 6497.18785151856, 6503.28463868974, 3492.511048453219, 3509.192885636318, 3549.893503194908, 3610.548681414823, 3616.8734094306574, 2620.646212761291, 2714.280715610365, 2721.2516557946724, 2724.2372135802007, 2730.8361498310164, 496.76651973572365, 497.4329264638315, 502.3168490852438, 508.4077050236507, 510.2856001597793, 466.97665116744025, 516.3468212710924, 525.4404059676432, 530.3751288761989, 541.6966498760706, 7680.9231907680205, 7688.577128258, 7743.651535680908, 7937.4838850433, 7938.285554300025, 519.7868873761739, 524.0200675866786, 536.2813829468531, 544.1212441906292, 551.9199716041231, 3444.345574340867, 3523.3481805865995, 3544.113971507123, 3576.7496275261, 3588.8564457421935, 85674.14325856787, 86351.56824215964, 87697.0, 90151.54924225337, 91575.0, 51191.488085118675, 75382.62308688421, 76756.0, 77123.0, 78267.0, 67560.0, 68868.0, 69590.0, 69630.3036969634, 69819.3018069823, 6809.353111454602, 6956.652167391509, 6981.965090174516, 7175.995360649409, 7214.206437291999, 41542.0, 42690.57309426866, 43104.56895431005, 43234.0, 43755.56244437515, 55201.447985521234, 55290.0, 56821.43178568161, 60741.392586075344, 60947.0, 8915.866262006204, 8931.73204803857, 9041.728748137566, 9060.501672408082, 9227.30795190377, 9096.818063638559, 9243.907560924574, 9376.390534615228, 9466.195373393339, 9602.615895364108, 8500.192481714228, 8780.385373024083, 8845.336599755174, 8917.242034427147, 9089.909100908906, 8455.873161902453, 8550.78623034429, 8555.61499732526, 8617.87073193915, 8827.249683776694, 8711.25954293892, 8837.823243534966, 9006.189442950166, 9088.954555227181, 9224.769380765534, 7154.35610795031, 7249.746258880914, 8611.268042216232, 9006.324525660471, 9290.489023103524, 9604.759881002752, 10057.245706572183, 10100.949495252476, 10237.283390162616, 10266.743331416774], "value": 6073.119527568602, "unit": "score"}], "name": "dromaeo_css", "value": 6239.5906989264195, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 113.0, 117.0, 114.0, 114.0, 114.0, 113.0, 112.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 134.0, 131.0, 184.0, 129.0, 137.0, 163.0, 130.0, 178.0, 137.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 161.0, 163.0, 160.0, 163.0, 161.0, 160.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 78.0, 112.0, 74.0, 75.0, 73.0, 74.0, 98.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 116.0, 85.0, 86.0, 88.0, 135.0, 84.0, 84.0, 127.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 63.0, 66.0, 64.0, 64.0, 63.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 70.0, 64.0, 65.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 83.0, 83.0, 86.0, 84.0, 83.0, 85.0, 121.0, 84.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 131.0, 132.0, 130.0, 130.0, 133.0, 142.0, 140.0, 132.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 178.0, 175.0, 171.0, 170.0, 171.0, 172.0, 173.0, 171.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 68.0, 68.0, 72.0, 71.0, 67.0, 66.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1551.9, "alertThreshold": 2.0}]} 18:52:00 INFO - Return code: 0 18:52:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:52:00 ERROR - # TBPL SUCCESS # 18:52:00 INFO - Running post-action listener: _resource_record_post_action 18:52:00 INFO - ##### 18:52:00 INFO - ##### Finished run-tests step (success) 18:52:00 INFO - ##### 18:52:00 INFO - Running post-run listener: _resource_record_post_run 18:52:00 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 528691200; Read time: 16; Write time: 367552 18:52:00 INFO - TinderboxPrint: CPU usage
13.2% 18:52:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:52:00 INFO - TinderboxPrint: I/O write bytes / time
528,691,200 / 367,552 18:52:00 INFO - TinderboxPrint: CPU idle
8,315.7 (86.8%) 18:52:00 INFO - TinderboxPrint: CPU user
1,217.3 (12.7%) 18:52:00 INFO - TinderboxPrint: Swap in / out
0 / 0 18:52:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 237322240; Read time: 0; Write time: 196364 18:52:00 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291368960; Read time: 16; Write time: 171188 18:52:01 INFO - Running post-run listener: _upload_blobber_files 18:52:01 INFO - Blob upload gear active. 18:52:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:52:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:52:01 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:52:01 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:52:01 INFO - (blobuploader) - INFO - Open directory for files ... 18:52:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:52:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:02 INFO - (blobuploader) - INFO - Done attempting. 18:52:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:52:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:06 INFO - (blobuploader) - INFO - Done attempting. 18:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:52:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:07 INFO - (blobuploader) - INFO - Done attempting. 18:52:07 INFO - (blobuploader) - INFO - Iteration through files over. 18:52:07 INFO - Return code: 0 18:52:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:52:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:52:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/fc9df77cfd181aed78b187e6ab85abbaf91cd802073f1bcac67f3f8f97eff0a85c435149402f9cac156d8ffeb2d48cb67d0107cb42f0de6dc394689d26529507", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/18851c332b5da8cdcc0c90898b15f8b8ef836faae63ba00db4d6bd6a8fb87931ff426471ca11037536cbff4644866686ae7108a44619aa31c84b93bdad92eadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa2b66f19408c3f8a6926d792bf88b5af159a2926e78f7f51441319b935f2fbcd4eb99e387e19f51b1a4710e21d00da9158bbfb47d689801ffb52f2af9a78389"} 18:52:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:52:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:52:07 INFO - Contents: 18:52:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/fc9df77cfd181aed78b187e6ab85abbaf91cd802073f1bcac67f3f8f97eff0a85c435149402f9cac156d8ffeb2d48cb67d0107cb42f0de6dc394689d26529507", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/18851c332b5da8cdcc0c90898b15f8b8ef836faae63ba00db4d6bd6a8fb87931ff426471ca11037536cbff4644866686ae7108a44619aa31c84b93bdad92eadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa2b66f19408c3f8a6926d792bf88b5af159a2926e78f7f51441319b935f2fbcd4eb99e387e19f51b1a4710e21d00da9158bbfb47d689801ffb52f2af9a78389"} 18:52:07 INFO - Running post-run listener: copy_logs_to_upload_dir 18:52:07 INFO - Copying logs to upload dir... 18:52:07 INFO - mkdir: /builds/slave/test/build/upload/logs 18:52:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.241075 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-06-01 18:52:07.137383) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:07.140976) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/fc9df77cfd181aed78b187e6ab85abbaf91cd802073f1bcac67f3f8f97eff0a85c435149402f9cac156d8ffeb2d48cb67d0107cb42f0de6dc394689d26529507", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/18851c332b5da8cdcc0c90898b15f8b8ef836faae63ba00db4d6bd6a8fb87931ff426471ca11037536cbff4644866686ae7108a44619aa31c84b93bdad92eadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa2b66f19408c3f8a6926d792bf88b5af159a2926e78f7f51441319b935f2fbcd4eb99e387e19f51b1a4710e21d00da9158bbfb47d689801ffb52f2af9a78389"} build_url:https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/fc9df77cfd181aed78b187e6ab85abbaf91cd802073f1bcac67f3f8f97eff0a85c435149402f9cac156d8ffeb2d48cb67d0107cb42f0de6dc394689d26529507", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/18851c332b5da8cdcc0c90898b15f8b8ef836faae63ba00db4d6bd6a8fb87931ff426471ca11037536cbff4644866686ae7108a44619aa31c84b93bdad92eadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa2b66f19408c3f8a6926d792bf88b5af159a2926e78f7f51441319b935f2fbcd4eb99e387e19f51b1a4710e21d00da9158bbfb47d689801ffb52f2af9a78389"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:07.171625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:07.172379) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464830167.149813-1470088107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:07.254581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:52:07.255323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:52:07.256054) ========= ========= Total master_lag: 0.21 =========