builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1463771475.8 results: success (0) buildid: 20160520105741 builduid: 77153f17d4a54554ba738427aa2c8d92 revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.803217) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.803758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.804035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.827999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.828332) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 12:11:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.4M=0s 2016-05-20 12:11:17 (70.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077932 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.920420) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.920769) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014758 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.981252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:15.981589) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --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 b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 12:11:17,388 truncating revision to first 12 chars 2016-05-20 12:11:17,388 Setting DEBUG logging. 2016-05-20 12:11:17,388 attempt 1/10 2016-05-20 12:11:17,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b4ae8c5edb7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 12:11:17,652 unpacking tar archive at: larch-b4ae8c5edb7e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862797 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:16.859510) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:16.859988) ========= script_repo_revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:16.860614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:16.861074) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 12:11:16.871415) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-20 12:11:16.871976) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:11:18 INFO - MultiFileLogger online at 20160520 12:11:18 in /builds/slave/test 12:11:18 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 12:11:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:11:18 INFO - {'append_to_log': False, 12:11:18 INFO - 'base_work_dir': '/builds/slave/test', 12:11:18 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 12:11:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:11:18 INFO - 'branch': 'Larch-Non-PGO', 12:11:18 INFO - 'buildbot_json_path': 'buildprops.json', 12:11:18 INFO - 'config_files': ('talos/linux_config.py',), 12:11:18 INFO - 'default_actions': ('clobber', 12:11:18 INFO - 'read-buildbot-config', 12:11:18 INFO - 'download-and-extract', 12:11:18 INFO - 'populate-webroot', 12:11:18 INFO - 'create-virtualenv', 12:11:18 INFO - 'install', 12:11:18 INFO - 'run-tests'), 12:11:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:11:18 INFO - 'download_minidump_stackwalk': True, 12:11:18 INFO - 'download_symbols': 'ondemand', 12:11:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:11:18 INFO - 'tooltool.py': '/tools/tooltool.py', 12:11:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:11:18 INFO - '/tools/misc-python/virtualenv.py')}, 12:11:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:11:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:11:18 INFO - 'installer_path': 'installer.exe', 12:11:18 INFO - 'log_level': 'info', 12:11:18 INFO - 'log_name': 'talos', 12:11:18 INFO - 'log_to_console': True, 12:11:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:11:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:11:18 INFO - 'opt_config_files': (), 12:11:18 INFO - 'pip_index': False, 12:11:18 INFO - 'sps_profile': False, 12:11:18 INFO - 'sps_profile_interval': 0, 12:11:18 INFO - 'suite': 'dromaeojs', 12:11:18 INFO - 'system_bits': '32', 12:11:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:11:18 INFO - 'title': 'talos-linux64-ix-068', 12:11:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:11:18 INFO - 'use_talos_json': True, 12:11:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:11:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:11:18 INFO - 'work_dir': 'build'} 12:11:18 INFO - ##### 12:11:18 INFO - ##### Running clobber step. 12:11:18 INFO - ##### 12:11:18 INFO - Running pre-action listener: _resource_record_pre_action 12:11:18 INFO - Running main action method: clobber 12:11:18 INFO - rmtree: /builds/slave/test/build 12:11:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:11:18 INFO - Running post-action listener: _resource_record_post_action 12:11:18 INFO - ##### 12:11:18 INFO - ##### Finished clobber step (success) 12:11:18 INFO - ##### 12:11:18 INFO - ##### 12:11:18 INFO - ##### Running read-buildbot-config step. 12:11:18 INFO - ##### 12:11:18 INFO - Running pre-action listener: _resource_record_pre_action 12:11:18 INFO - Running main action method: read_buildbot_config 12:11:18 INFO - Using buildbot properties: 12:11:18 INFO - { 12:11:18 INFO - "project": "", 12:11:18 INFO - "product": "firefox", 12:11:18 INFO - "script_repo_revision": "production", 12:11:18 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 12:11:18 INFO - "repository": "", 12:11:18 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 12:11:18 INFO - "buildid": "20160520105741", 12:11:18 INFO - "pgo_build": "False", 12:11:18 INFO - "basedir": "/builds/slave/test", 12:11:18 INFO - "buildnumber": 0, 12:11:18 INFO - "slavename": "talos-linux64-ix-068", 12:11:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:11:18 INFO - "platform": "ubuntu64_hw", 12:11:18 INFO - "scheduler": "tests-larch-linux64-talos", 12:11:18 INFO - "branch": "larch", 12:11:18 INFO - "repo_path": "projects/larch", 12:11:18 INFO - "revision": "b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19", 12:11:18 INFO - "stage_platform": "linux64", 12:11:18 INFO - "builduid": "77153f17d4a54554ba738427aa2c8d92", 12:11:18 INFO - "slavebuilddir": "test" 12:11:18 INFO - } 12:11:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:11:18 INFO - Running post-action listener: _resource_record_post_action 12:11:18 INFO - ##### 12:11:18 INFO - ##### Finished read-buildbot-config step (success) 12:11:18 INFO - ##### 12:11:18 INFO - ##### 12:11:18 INFO - ##### Running download-and-extract step. 12:11:18 INFO - ##### 12:11:18 INFO - Running pre-action listener: _resource_record_pre_action 12:11:18 INFO - Running main action method: download_and_extract 12:11:18 INFO - mkdir: /builds/slave/test/build/tests 12:11:18 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')]} 12:11:18 INFO - https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:11:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:11:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:11:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:29 INFO - Downloaded 1361 bytes. 12:11:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:11:29 INFO - Using the following test package requirements: 12:11:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:11:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:11:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:11:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'jsshell-linux-x86_64.zip'], 12:11:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:11:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:11:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:11:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:11:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:11:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:11:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:11:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:29 INFO - https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:11:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:11:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:11:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:43 INFO - Downloaded 22328845 bytes. 12:11:43 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'] 12:11:43 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 12:11:44 INFO - Return code: 0 12:11:44 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 12:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:44 INFO - https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:11:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:11:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:11:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:46 INFO - Downloaded 22328845 bytes. 12:11:46 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'] 12:11:46 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 12:11:47 INFO - Return code: 0 12:11: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')]} 12:11:47 INFO - https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:11:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:11:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:11:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:49 INFO - Downloaded 10840160 bytes. 12:11:49 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'] 12:11:49 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 12:11:49 INFO - Return code: 0 12:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:49 INFO - https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:11:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:11:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:11:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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 12:11:50 INFO - Downloaded 54804128 bytes. 12:11:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:11:50 INFO - mkdir: /builds/slave/test/properties 12:11:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:50 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:50 INFO - Contents: 12:11:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:11:51 INFO - Running post-action listener: _resource_record_post_action 12:11:51 INFO - Running post-action listener: set_extra_try_arguments 12:11:51 INFO - ##### 12:11:51 INFO - ##### Finished download-and-extract step (success) 12:11:51 INFO - ##### 12:11:51 INFO - ##### 12:11:51 INFO - ##### Running populate-webroot step. 12:11:51 INFO - ##### 12:11:51 INFO - Running pre-action listener: _resource_record_pre_action 12:11:51 INFO - Running main action method: populate_webroot 12:11:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:51 INFO - '--tppagecycles', 12:11:51 INFO - '7'], 12:11:51 INFO - 'tests': ['tsvgm']}}, 12:11:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['damp', 'tps']}, 12:11:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'tests': ['damp', 'tps']}, 12:11:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['dromaeo_dom']}, 12:11:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['a11yr', 12:11:51 INFO - 'ts_paint', 12:11:51 INFO - 'tpaint', 12:11:51 INFO - 'sessionrestore', 12:11:51 INFO - 'sessionrestore_no_auto_restore', 12:11:51 INFO - 'tabpaint']}, 12:11:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['tsvgx', 12:11:51 INFO - 'tsvgr_opacity', 12:11:51 INFO - 'tart', 12:11:51 INFO - 'tscrollx', 12:11:51 INFO - 'cart']}, 12:11:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:51 INFO - 'tsvgr_opacity', 12:11:51 INFO - 'tart', 12:11:51 INFO - 'tscrollx', 12:11:51 INFO - 'cart']}, 12:11:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'talos_options': ['--disable-e10s'], 12:11:51 INFO - 'tests': ['tp5o']}, 12:11:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'tests': ['tp5o']}, 12:11:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'talos_options': ['--disable-e10s', 12:11:51 INFO - '--xperf_path', 12:11:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:51 INFO - 'tests': ['tp5n']}, 12:11:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:51 INFO - 'talos_options': ['--xperf_path', 12:11:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:51 INFO - 'tests': ['tp5n']}}, 12:11:51 INFO - 'talos.zip': {'path': '', 12:11:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:51 INFO - Running post-action listener: _resource_record_post_action 12:11:51 INFO - ##### 12:11:51 INFO - ##### Finished populate-webroot step (success) 12:11:51 INFO - ##### 12:11:51 INFO - ##### 12:11:51 INFO - ##### Running create-virtualenv step. 12:11:51 INFO - ##### 12:11:51 INFO - Running pre-action listener: _resource_record_pre_action 12:11:51 INFO - Running main action method: create_virtualenv 12:11:51 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:51 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 12:11:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:51 INFO - Using real prefix '/usr' 12:11:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:52 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:54 INFO - Installing pip.................done. 12:11:54 INFO - Return code: 0 12:11:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:11: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', 'pip>=1.5'] in /builds/slave/test/build 12:11: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 pip>=1.5 12:11:54 INFO - Using env: {'DISPLAY': ':0', 12:11:54 INFO - 'HOME': '/home/cltbld', 12:11:54 INFO - 'LANG': 'en_US.UTF-8', 12:11:54 INFO - 'LANGUAGE': 'en_US:en', 12:11:54 INFO - 'LOGNAME': 'cltbld', 12:11:54 INFO - 'MAIL': '/var/mail/cltbld', 12:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:54 INFO - 'MOZ_NO_REMOTE': '1', 12:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:54 INFO - 'NO_EM_RESTART': '1', 12:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:54 INFO - 'PWD': '/builds/slave/test', 12:11:54 INFO - 'SHELL': '/bin/bash', 12:11:54 INFO - 'SHLVL': '1', 12:11:54 INFO - 'TERM': 'linux', 12:11:54 INFO - 'TMOUT': '86400', 12:11:54 INFO - 'USER': 'cltbld', 12:11:54 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:54 INFO - '_': '/tools/buildbot/bin/python'} 12:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:54 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 12:11:54 INFO - Cleaning up... 12:11:54 INFO - Return code: 0 12:11:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:11: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', 'psutil>=0.7.1'] in /builds/slave/test/build 12:11: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 psutil>=0.7.1 12:11:54 INFO - Using env: {'DISPLAY': ':0', 12:11:54 INFO - 'HOME': '/home/cltbld', 12:11:54 INFO - 'LANG': 'en_US.UTF-8', 12:11:54 INFO - 'LANGUAGE': 'en_US:en', 12:11:54 INFO - 'LOGNAME': 'cltbld', 12:11:54 INFO - 'MAIL': '/var/mail/cltbld', 12:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:54 INFO - 'MOZ_NO_REMOTE': '1', 12:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:54 INFO - 'NO_EM_RESTART': '1', 12:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:54 INFO - 'PWD': '/builds/slave/test', 12:11:54 INFO - 'SHELL': '/bin/bash', 12:11:54 INFO - 'SHLVL': '1', 12:11:54 INFO - 'TERM': 'linux', 12:11:54 INFO - 'TMOUT': '86400', 12:11:54 INFO - 'USER': 'cltbld', 12:11:54 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:54 INFO - '_': '/tools/buildbot/bin/python'} 12:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:54 INFO - Downloading/unpacking psutil>=0.7.1 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:56 INFO - Installing collected packages: psutil 12:11:56 INFO - Running setup.py install for psutil 12:11:56 INFO - building 'psutil._psutil_linux' extension 12:11:56 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 12:11:56 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 12:11:56 INFO - building 'psutil._psutil_posix' extension 12:11:56 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 12:11:57 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 12:11:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:57 INFO - Successfully installed psutil 12:11:57 INFO - Cleaning up... 12:11:57 INFO - Return code: 0 12:11:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:11: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:11: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 mozsystemmonitor==0.0.0 12:11:57 INFO - Using env: {'DISPLAY': ':0', 12:11:57 INFO - 'HOME': '/home/cltbld', 12:11:57 INFO - 'LANG': 'en_US.UTF-8', 12:11:57 INFO - 'LANGUAGE': 'en_US:en', 12:11:57 INFO - 'LOGNAME': 'cltbld', 12:11:57 INFO - 'MAIL': '/var/mail/cltbld', 12:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:57 INFO - 'MOZ_NO_REMOTE': '1', 12:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:57 INFO - 'NO_EM_RESTART': '1', 12:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:57 INFO - 'PWD': '/builds/slave/test', 12:11:57 INFO - 'SHELL': '/bin/bash', 12:11:57 INFO - 'SHLVL': '1', 12:11:57 INFO - 'TERM': 'linux', 12:11:57 INFO - 'TMOUT': '86400', 12:11:57 INFO - 'USER': 'cltbld', 12:11:57 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:57 INFO - '_': '/tools/buildbot/bin/python'} 12:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:11:59 INFO - Installing collected packages: mozsystemmonitor 12:11:59 INFO - Running setup.py install for mozsystemmonitor 12:11:59 INFO - Successfully installed mozsystemmonitor 12:11:59 INFO - Cleaning up... 12:11:59 INFO - Return code: 0 12:11:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:59 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:11:59 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 12:11:59 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 12:11:59 INFO - Using env: {'DISPLAY': ':0', 12:11:59 INFO - 'HOME': '/home/cltbld', 12:11:59 INFO - 'LANG': 'en_US.UTF-8', 12:11:59 INFO - 'LANGUAGE': 'en_US:en', 12:11:59 INFO - 'LOGNAME': 'cltbld', 12:11:59 INFO - 'MAIL': '/var/mail/cltbld', 12:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:59 INFO - 'MOZ_NO_REMOTE': '1', 12:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:59 INFO - 'NO_EM_RESTART': '1', 12:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:59 INFO - 'PWD': '/builds/slave/test', 12:11:59 INFO - 'SHELL': '/bin/bash', 12:11:59 INFO - 'SHLVL': '1', 12:11:59 INFO - 'TERM': 'linux', 12:11:59 INFO - 'TMOUT': '86400', 12:11:59 INFO - 'USER': 'cltbld', 12:11:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:59 INFO - '_': '/tools/buildbot/bin/python'} 12:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:12:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:12:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:12:01 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 12:12:01 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 12:12:01 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 12:12:01 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 12:12:01 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 12:12:01 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 12:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:12:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:12:02 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 12:12:02 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 12:12:02 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 12:12:02 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 12:12:02 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 12:12:02 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 12:12:02 INFO - Downloading docopt-0.6.1.tar.gz 12:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:12:02 INFO - Installing collected packages: blobuploader, requests, docopt 12:12:02 INFO - Running setup.py install for blobuploader 12:12:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:12:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:12:02 INFO - Running setup.py install for requests 12:12:02 INFO - Running setup.py install for docopt 12:12:03 INFO - Successfully installed blobuploader requests docopt 12:12:03 INFO - Cleaning up... 12:12:03 INFO - Return code: 0 12:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:03 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:12:03 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 12:12:03 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 12:12:03 INFO - Using env: {'DISPLAY': ':0', 12:12:03 INFO - 'HOME': '/home/cltbld', 12:12:03 INFO - 'LANG': 'en_US.UTF-8', 12:12:03 INFO - 'LANGUAGE': 'en_US:en', 12:12:03 INFO - 'LOGNAME': 'cltbld', 12:12:03 INFO - 'MAIL': '/var/mail/cltbld', 12:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:03 INFO - 'MOZ_NO_REMOTE': '1', 12:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:03 INFO - 'NO_EM_RESTART': '1', 12:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:03 INFO - 'PWD': '/builds/slave/test', 12:12:03 INFO - 'SHELL': '/bin/bash', 12:12:03 INFO - 'SHLVL': '1', 12:12:03 INFO - 'TERM': 'linux', 12:12:03 INFO - 'TMOUT': '86400', 12:12:03 INFO - 'USER': 'cltbld', 12:12:03 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:03 INFO - '_': '/tools/buildbot/bin/python'} 12:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:03 INFO - Running setup.py (path:/tmp/pip-SgyCz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:03 INFO - Running setup.py (path:/tmp/pip-_ANX5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:03 INFO - Running setup.py (path:/tmp/pip-qQpVdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:03 INFO - Running setup.py (path:/tmp/pip-14iOb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:03 INFO - Running setup.py (path:/tmp/pip-9ULzEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:03 INFO - Running setup.py (path:/tmp/pip-HofXnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:03 INFO - Running setup.py (path:/tmp/pip-tziQGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:03 INFO - Running setup.py (path:/tmp/pip-efBRpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:03 INFO - Running setup.py (path:/tmp/pip-uwHrzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:04 INFO - Running setup.py (path:/tmp/pip-P0CqTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:04 INFO - Running setup.py (path:/tmp/pip-jScQZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:04 INFO - Running setup.py (path:/tmp/pip-u1gJUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:04 INFO - Running setup.py (path:/tmp/pip-Qau99j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:04 INFO - Running setup.py (path:/tmp/pip-SEkDIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:04 INFO - Running setup.py (path:/tmp/pip-k_Rs40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:04 INFO - Running setup.py (path:/tmp/pip-CwLLxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:04 INFO - Running setup.py (path:/tmp/pip-w4DejD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:12:04 INFO - Running setup.py install for manifestparser 12:12:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:12:04 INFO - Running setup.py install for mozcrash 12:12:04 INFO - Running setup.py install for mozdebug 12:12:04 INFO - Running setup.py install for mozdevice 12:12:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:12:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozfile 12:12:05 INFO - Running setup.py install for mozhttpd 12:12:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozinfo 12:12:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozInstall 12:12:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozleak 12:12:05 INFO - Running setup.py install for mozlog 12:12:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for moznetwork 12:12:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozprocess 12:12:05 INFO - Running setup.py install for mozprofile 12:12:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:12:05 INFO - Running setup.py install for mozrunner 12:12:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:12:06 INFO - Running setup.py install for mozscreenshot 12:12:06 INFO - Running setup.py install for moztest 12:12:06 INFO - Running setup.py install for mozversion 12:12:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:12:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:12:06 INFO - Cleaning up... 12:12:06 INFO - Return code: 0 12:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:06 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:12:06 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 12:12:06 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 12:12:06 INFO - Using env: {'DISPLAY': ':0', 12:12:06 INFO - 'HOME': '/home/cltbld', 12:12:06 INFO - 'LANG': 'en_US.UTF-8', 12:12:06 INFO - 'LANGUAGE': 'en_US:en', 12:12:06 INFO - 'LOGNAME': 'cltbld', 12:12:06 INFO - 'MAIL': '/var/mail/cltbld', 12:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:06 INFO - 'MOZ_NO_REMOTE': '1', 12:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:06 INFO - 'NO_EM_RESTART': '1', 12:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:06 INFO - 'PWD': '/builds/slave/test', 12:12:06 INFO - 'SHELL': '/bin/bash', 12:12:06 INFO - 'SHLVL': '1', 12:12:06 INFO - 'TERM': 'linux', 12:12:06 INFO - 'TMOUT': '86400', 12:12:06 INFO - 'USER': 'cltbld', 12:12:06 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:06 INFO - '_': '/tools/buildbot/bin/python'} 12:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:06 INFO - Running setup.py (path:/tmp/pip-6h0AVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:06 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)) 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:06 INFO - Running setup.py (path:/tmp/pip-q6_TX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:06 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)) 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:06 INFO - Running setup.py (path:/tmp/pip-6QfpGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:06 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)) 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:06 INFO - Running setup.py (path:/tmp/pip-Z_sbS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:06 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)) 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:06 INFO - Running setup.py (path:/tmp/pip-8MBqpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:06 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)) 12:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:06 INFO - Running setup.py (path:/tmp/pip-1nbsNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:07 INFO - Running setup.py (path:/tmp/pip-dVFARL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:07 INFO - Running setup.py (path:/tmp/pip-pK8eny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:07 INFO - Running setup.py (path:/tmp/pip-RbuAYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:07 INFO - Running setup.py (path:/tmp/pip-mPTyD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:07 INFO - Running setup.py (path:/tmp/pip-9hfU7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:07 INFO - Running setup.py (path:/tmp/pip-JpSso0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:07 INFO - Running setup.py (path:/tmp/pip-HbP6jM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:07 INFO - Running setup.py (path:/tmp/pip-0Micup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:07 INFO - Running setup.py (path:/tmp/pip-FwRz6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:07 INFO - Running setup.py (path:/tmp/pip-b7UItY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:07 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)) 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:07 INFO - Running setup.py (path:/tmp/pip-29Nxey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:07 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)) 12:12:07 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)) 12:12:07 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)) 12:12:07 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)) 12:12:07 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)) 12:12:07 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)) 12:12:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:09 INFO - Downloading blessings-1.6.tar.gz 12:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:12:09 INFO - Installing collected packages: blessings 12:12:09 INFO - Running setup.py install for blessings 12:12:09 INFO - Successfully installed blessings 12:12:09 INFO - Cleaning up... 12:12:09 INFO - Return code: 0 12:12:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:12:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:10 INFO - Reading from file tmpfile_stdout 12:12:10 INFO - Current package versions: 12:12:10 INFO - argparse == 1.2.1 12:12:10 INFO - blessings == 1.6 12:12:10 INFO - blobuploader == 1.2.4 12:12:10 INFO - docopt == 0.6.1 12:12:10 INFO - manifestparser == 1.1 12:12:10 INFO - mozInstall == 1.12 12:12:10 INFO - mozcrash == 0.17 12:12:10 INFO - mozdebug == 0.1 12:12:10 INFO - mozdevice == 0.48 12:12:10 INFO - mozfile == 1.2 12:12:10 INFO - mozhttpd == 0.7 12:12:10 INFO - mozinfo == 0.9 12:12:10 INFO - mozleak == 0.1 12:12:10 INFO - mozlog == 3.1 12:12:10 INFO - moznetwork == 0.27 12:12:10 INFO - mozprocess == 0.22 12:12:10 INFO - mozprofile == 0.28 12:12:10 INFO - mozrunner == 6.11 12:12:10 INFO - mozscreenshot == 0.1 12:12:10 INFO - mozsystemmonitor == 0.0 12:12:10 INFO - moztest == 0.7 12:12:10 INFO - mozversion == 1.4 12:12:10 INFO - psutil == 3.1.1 12:12:10 INFO - requests == 1.2.3 12:12:10 INFO - wsgiref == 0.1.2 12:12:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:10 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')]} 12:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:10 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')]} 12:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:10 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:12:10 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 12:12:10 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 12:12:10 INFO - Using env: {'DISPLAY': ':0', 12:12:10 INFO - 'HOME': '/home/cltbld', 12:12:10 INFO - 'LANG': 'en_US.UTF-8', 12:12:10 INFO - 'LANGUAGE': 'en_US:en', 12:12:10 INFO - 'LOGNAME': 'cltbld', 12:12:10 INFO - 'MAIL': '/var/mail/cltbld', 12:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:10 INFO - 'MOZ_NO_REMOTE': '1', 12:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:10 INFO - 'NO_EM_RESTART': '1', 12:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:10 INFO - 'PWD': '/builds/slave/test', 12:12:10 INFO - 'SHELL': '/bin/bash', 12:12:10 INFO - 'SHLVL': '1', 12:12:10 INFO - 'TERM': 'linux', 12:12:10 INFO - 'TMOUT': '86400', 12:12:10 INFO - 'USER': 'cltbld', 12:12:10 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:10 INFO - '_': '/tools/buildbot/bin/python'} 12:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 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)) 12:12:10 INFO - Cleaning up... 12:12:10 INFO - Return code: 0 12:12:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:12:10 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')]} 12:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:10 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')]} 12:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:10 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:12:10 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 12:12:10 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 12:12:10 INFO - Using env: {'DISPLAY': ':0', 12:12:10 INFO - 'HOME': '/home/cltbld', 12:12:10 INFO - 'LANG': 'en_US.UTF-8', 12:12:10 INFO - 'LANGUAGE': 'en_US:en', 12:12:10 INFO - 'LOGNAME': 'cltbld', 12:12:10 INFO - 'MAIL': '/var/mail/cltbld', 12:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:10 INFO - 'MOZ_NO_REMOTE': '1', 12:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:10 INFO - 'NO_EM_RESTART': '1', 12:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:10 INFO - 'PWD': '/builds/slave/test', 12:12:10 INFO - 'SHELL': '/bin/bash', 12:12:10 INFO - 'SHLVL': '1', 12:12:10 INFO - 'TERM': 'linux', 12:12:10 INFO - 'TMOUT': '86400', 12:12:10 INFO - 'USER': 'cltbld', 12:12:10 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:10 INFO - '_': '/tools/buildbot/bin/python'} 12:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:10 INFO - Downloading/unpacking jsonschema 12:12:10 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 12:12:10 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 12:12:10 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 12:12:10 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 12:12:10 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 12:12:10 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 12:12:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:12:12 INFO - Downloading/unpacking functools32 (from jsonschema) 12:12:12 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 12:12:12 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 12:12:12 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 12:12:12 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 12:12:12 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 12:12:12 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 12:12:12 INFO - Downloading functools32-3.2.3-2.tar.gz 12:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:12:12 INFO - warning: no files found matching '*.txt' 12:12:12 INFO - no previously-included directories found matching 'build' 12:12:12 INFO - no previously-included directories found matching 'dist' 12:12:12 INFO - no previously-included directories found matching '.git*' 12:12:12 INFO - Installing collected packages: jsonschema, functools32 12:12:12 INFO - Running setup.py install for functools32 12:12:12 INFO - warning: no files found matching '*.txt' 12:12:12 INFO - no previously-included directories found matching 'build' 12:12:12 INFO - no previously-included directories found matching 'dist' 12:12:12 INFO - no previously-included directories found matching '.git*' 12:12:12 INFO - Successfully installed jsonschema functools32 12:12:12 INFO - Cleaning up... 12:12:12 INFO - Return code: 0 12:12:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:12:12 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')]} 12:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:12 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')]} 12:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:12 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 0x7f9b40298f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', '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 12:12:12 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 12:12:12 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 12:12:12 INFO - Using env: {'DISPLAY': ':0', 12:12:12 INFO - 'HOME': '/home/cltbld', 12:12:12 INFO - 'LANG': 'en_US.UTF-8', 12:12:12 INFO - 'LANGUAGE': 'en_US:en', 12:12:12 INFO - 'LOGNAME': 'cltbld', 12:12:12 INFO - 'MAIL': '/var/mail/cltbld', 12:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:12 INFO - 'MOZ_NO_REMOTE': '1', 12:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:12 INFO - 'NO_EM_RESTART': '1', 12:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:13 INFO - 'PWD': '/builds/slave/test', 12:12:13 INFO - 'SHELL': '/bin/bash', 12:12:13 INFO - 'SHLVL': '1', 12:12:13 INFO - 'TERM': 'linux', 12:12:13 INFO - 'TMOUT': '86400', 12:12:13 INFO - 'USER': 'cltbld', 12:12:13 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:13 INFO - '_': '/tools/buildbot/bin/python'} 12:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:13 INFO - Downloading/unpacking flake8 12:12:13 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 12:12:13 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 12:12:13 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 12:12:13 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 12:12:13 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 12:12:13 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 12:12:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:12:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:12:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:12:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:15 INFO - no previously-included directories found matching 'docs/_build' 12:12:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:12:15 INFO - Running setup.py install for pep8 12:12:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:15 INFO - no previously-included directories found matching 'docs/_build' 12:12:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:12:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:12:15 INFO - Cleaning up... 12:12:16 INFO - Return code: 0 12:12:16 INFO - Running post-action listener: _resource_record_post_action 12:12:16 INFO - Running post-action listener: _start_resource_monitoring 12:12:16 INFO - Starting resource monitoring. 12:12:16 INFO - ##### 12:12:16 INFO - ##### Finished create-virtualenv step (success) 12:12:16 INFO - ##### 12:12:16 INFO - ##### 12:12:16 INFO - ##### Running install step. 12:12:16 INFO - ##### 12:12:16 INFO - Running pre-action listener: _resource_record_pre_action 12:12:16 INFO - Running main action method: install 12:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:16 INFO - Reading from file tmpfile_stdout 12:12:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:12:16 INFO - Reading from file tmpfile_stdout 12:12:16 INFO - Output received: 12:12:16 INFO - Usage: mozinstall [options] installer 12:12:16 INFO - Options: 12:12:16 INFO - -h, --help show this help message and exit 12:12:16 INFO - -d DEST, --destination=DEST 12:12:16 INFO - Directory to install application into. [default: 12:12:16 INFO - "/builds/slave/test"] 12:12:16 INFO - --app=APP Application being installed. [default: firefox] 12:12:16 INFO - mkdir: /builds/slave/test/build/application 12:12:16 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'] 12:12:16 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 12:12:32 INFO - Reading from file tmpfile_stdout 12:12:32 INFO - Output received: 12:12:32 INFO - /builds/slave/test/build/application/firefox/firefox 12:12:32 INFO - Running post-action listener: _resource_record_post_action 12:12:32 INFO - ##### 12:12:32 INFO - ##### Finished install step (success) 12:12:32 INFO - ##### 12:12:32 INFO - ##### 12:12:32 INFO - ##### Running run-tests step. 12:12:32 INFO - ##### 12:12:32 INFO - Running pre-action listener: _resource_record_pre_action 12:12:32 INFO - Running main action method: run_tests 12:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:32 INFO - Python 2.7.3 12:12:32 INFO - Return code: 0 12:12:32 INFO - grabbing minidump binary from tooltool 12:12:32 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')]} 12:12:32 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 0x1bd69c0>, '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 0x1bd78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd7d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:32 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 12:12:32 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 12:12:32 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 12:12:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:32 INFO - Return code: 0 12:12:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:12:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:12:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:12:33 INFO - Return code: 0 12:12:33 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/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:12:33 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/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 12:12:33 INFO - Using env: {'DISPLAY': ':0', 12:12:33 INFO - 'HOME': '/home/cltbld', 12:12:33 INFO - 'LANG': 'en_US.UTF-8', 12:12:33 INFO - 'LANGUAGE': 'en_US:en', 12:12:33 INFO - 'LOGNAME': 'cltbld', 12:12:33 INFO - 'MAIL': '/var/mail/cltbld', 12:12:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:33 INFO - 'MOZ_NO_REMOTE': '1', 12:12:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:33 INFO - 'NO_EM_RESTART': '1', 12:12:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:33 INFO - 'PWD': '/builds/slave/test', 12:12:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:33 INFO - 'SHELL': '/bin/bash', 12:12:33 INFO - 'SHLVL': '1', 12:12:33 INFO - 'TERM': 'linux', 12:12:33 INFO - 'TMOUT': '86400', 12:12:33 INFO - 'USER': 'cltbld', 12:12:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799', 12:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:33 INFO - '_': '/tools/buildbot/bin/python'} 12:12:33 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/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:12:33 INFO - mozversion application_buildid: 20160520105741 12:12:33 INFO - mozversion application_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 12:12:33 INFO - mozversion application_display_name: Nightly 12:12:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:33 INFO - mozversion application_name: Firefox 12:12:33 INFO - mozversion application_remotingname: firefox 12:12:33 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 12:12:33 INFO - mozversion application_vendor: Mozilla 12:12:33 INFO - mozversion application_version: 49.0a1 12:12:33 INFO - mozversion platform_buildid: 20160520105741 12:12:33 INFO - mozversion platform_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 12:12:33 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 12:12:33 INFO - mozversion platform_version: 49.0a1 12:12:33 INFO - using testdate: 1463771553 12:12:33 INFO - actual date: 1463771553 12:12:33 INFO - starting webserver on 'localhost:53195' 12:12:33 INFO - SUITE-START | Running 2 tests 12:12:33 INFO - TEST-START | dromaeo_css 12:12:33 INFO - operating with platform_type : linux_ 12:12:33 INFO - Initialising browser for dromaeo_css test... 12:12:33 INFO - TEST-INFO | started process 3165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXUZ4C4/profile http://localhost:53195/getInfo.html) 12:12:37 INFO - PROCESS | 3165 | __metrics Screen width/height:1600/1200 12:12:37 INFO - PROCESS | 3165 | colorDepth:24 12:12:37 INFO - PROCESS | 3165 | Browser inner width/height: 1024/697 12:12:37 INFO - PROCESS | 3165 | __metrics 12:12:38 ERROR - PROCESS | 3165 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:12:39 INFO - TEST-INFO | 3165: exit 0 12:12:39 INFO - Browser initialized. 12:12:39 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:39 INFO - TEST-INFO | started process 3247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXUZ4C4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:51 INFO - PROCESS | 3247 | RSS: Main: 153739264 12:12:51 INFO - PROCESS | 3247 | 12:13:43 INFO - PROCESS | 3247 | 1463771623322 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] 12:15:54 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53195/tests/dromaeo/cssquery-ext.html) 12:15:55 INFO - PROCESS | 3247 | RSS: Main: 262344704 12:15:55 INFO - PROCESS | 3247 | 12:18:58 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-ext.html (next: http://localhost:53195/tests/dromaeo/cssquery-jquery.html) 12:18:58 INFO - PROCESS | 3247 | RSS: Main: 240332800 12:18:58 INFO - PROCESS | 3247 | 12:22:01 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53195/tests/dromaeo/cssquery-mootools.html) 12:22:01 INFO - PROCESS | 3247 | RSS: Main: 243191808 12:22:01 INFO - PROCESS | 3247 | 12:25:04 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53195/tests/dromaeo/cssquery-prototype.html) 12:25:04 INFO - PROCESS | 3247 | RSS: Main: 241246208 12:25:04 INFO - PROCESS | 3247 | 12:28:07 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53195/tests/dromaeo/cssquery-yui.html) 12:28:08 INFO - PROCESS | 3247 | RSS: Main: 210276352 12:28:08 INFO - PROCESS | 3247 | 12:31:10 INFO - PROCESS | 3247 | Cycle 1(1): loaded http://localhost:53195/tests/dromaeo/cssquery-yui.html (next: http://localhost:53195/tests/dromaeo/cssquery-dojo.html) 12:31:11 INFO - PROCESS | 3247 | RSS: Main: 265318400 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | __start_tp_report 12:31:11 INFO - PROCESS | 3247 | _x_x_mozilla_page_load 12:31:11 INFO - PROCESS | 3247 | _x_x_mozilla_page_load_details 12:31:11 INFO - PROCESS | 3247 | |i|pagename|runs| 12:31:11 INFO - PROCESS | 3247 | |0;dojo.html;2638.116231062594;2659.5079910216623;2695.5013322535337;2709.322669332667;2713.982256653755;4582.862514124573;4644.326572646973;4672.135654903841;4756.7859446324865;4780.641451891109;4683.063387322532;4721.2209985352465;4787.688800227991;4813.350197723306;4886.26705994101;4304.504981927072;4319.76241306728;4339.544347843478;4399.780010999437;4423.314386270125;4349.913001739963;4384.18892504886;4443.311286750559;4480.059187570614;4482.731036137842;4246.40550322955;4392.538783427726;4395.230834603947;4418.270985287424;4438.866833994985;4225;4254;4254.808533615995;4277.230098582254;4289.948962504175;4221.577842215785;4259.084296876155;4270.722403043792;4272.850450234258;4315.611594956437;4481.932771008437;4596.172688915994;4610.838620648261;4616.0767846430845;4620.399348084761;4966.87582810429;5012.548870601663;5025.698458092526;5035.823746168903;5085.847424577269;5034.572061374787;5034.924476132861;5075.974620126913;5097.694138351673;5105.361829771278;4651.348811166439;4661.160991021648;4767.523247675239;4768.403949506312;4771.28430735389;5151.278820965068;5199.376074870964;5212.582993360522;5212.713300768418;5220.843374698765;4924.162892308316;4938.75306234694;4985.202367621163;4987.825426110068;4993.550580447704;4909;5017.071841709296;5027.824026159067;5033;5034.093863104603;4919.975400122977;4987.900241995139;4994.650374473752;5018.673786203958;5022.372203474566;5000.349954505964;5005.099082165245;5006.249062640561;5014.89970200594;5052.242163675478;4117.114820313647;4151.14901445203;4220.725652832556;4231.767252801125;4280.935785963213;3654.4335627977707;3664.285464334429;3668.853245870135;3697.408414653696;3697.9075523112137;4322.7190232634775;4343.3484977253665;4351.8476853309985;4383.583559561836;4392.472903251566;4867.7322747249245;4990.351254336913;5028.748562571856;5030.245463180552;5071;4399.692021558524;4400.537943515948;4402.141582391467;4435.179491794029;4456.153330867125;4513.616342610849;4521.61566266871;4544.182047231464;4546.090781843644;4555.886102847456;4680.4149481314835;4768.02255537607;4784.210605250164;4790.856274311776;4800.567948884617;5082.567981721595;5093.261477085876;5106.412762532356;5112.233165025276;5155.484451554965;4822.300766388924;4832.299316599003;4839.443464001685;4842.322074909586;4871.634627402888;4898.436679781871;4967.379077615298;4980.975095124501;5040;5058.089543882136;5128.230765385222;5151.356080489939;5154.381474223117;5156.587473002076;5195.376554813295;4979.278004689372;4985.576226020684;4988.476209997898;4989.276554899592;5048.040872234264;4785.928211076767;4886.65793394459;4964.354633897715;4983.177775667119;4984.476629953802;4890.24201248807;4895.363602731622;4919.409670839522;4945.629077819106;4955.975220123876;5013.623978201576;5013.849584512471;5023.824166154167;5044.873878153076;5069.391672999116;4707.952920470751;4726.740029298421;4797.064572408347;4800.399950006249;4856.1016212000895 12:31:11 INFO - PROCESS | 3247 | |1;ext.html;560.7252446301311;598.362743677983;606.3845197124916;609.551979295218;618.3569088148327;40932.59067409325;42670.57329426705;42761.35857962133;42772.65341832271;47122.52877471224;43099.13801723964;43750.343744843856;43752.12495750083;43792.78103609486;44060.559394406046;7834.72578459754;8022.277994980451;8067.112617612058;8067.717629882955;8067.919320806775;7578.545287282781;7592.848143037136;7668.348190403794;7673.155952845183;7675.846483070335;8019.438639295252;8068.959655201716;8080.515169089874;8081.1514790946985;8086.433949623529;7961.562114083723;8019.5188288702575;8024.2376974187355;8029.478083924526;8031.397645176577;7919.445638805286;8001.919980800176;8006.479578827416;8006.55963921984;8011.639476223584;39922.800385998176;40267;40337.79831100855;40358.39462408066;40391.39412908809;3405.7615966882327;3493.493443450711;3681.245344704329;3769.5476542814763;3882.2041481496226;29020.41959160805;29804.254893627614;29949.401011979855;30374.54438183429;31526.527102093485;3468.6357932416986;3479.9826000869834;3485.302939412102;3532.876349327761;3662.285854258421;179862;181162;181416;182634;182654;33822.661773382446;34829;34845.1288717779;36771.26457470836;56081;35942.8202858984;35989.64010359915;36111.638883611355;36114;36172.81913590415;13255.867441325463;13464.663383415493;13487.123336983064;13489.460421583222;13490;10251.84622230667;10299.845502317472;10405.739856503647;10436.060754532129;10437.2693911427;8396.748097557083;8411.915880841236;8416.537090460084;8468.788280292918;8502.447340922943;3266.477363621809;3274.8526316315824;3293.2425542124965;3305.355455686118;3350.564426624523;986.7927735175693;991.9107280344659;1020.4999550220487;1046.4401545173296;1097.3854641400842;28239.15282541527;28739.712602874122;28829;28837;29058.709412906024;969.2827307792325;991.5785791038779;996.7807082441851;1021.9437930913871;1087.472575800736;15457.768133478006;15493.380264789283;15494.11223734996;15658.9217053914;15928.362865485482;344284;349772;350267;350603;351939;50567;52054;52078;52089;52096.739516302936;1144.9885501144881;1227.2759072147476;1255.1088727003746;1255.704909346321;1273.4078653426016;27835.860820695765;29502.852485737432;29535;30191;30284.84857575698;38509.2298154041;38956.80521597374;38974.80512597533;38995;39092.804535977135;246707;252199;252265;253010;253021;23448.765512344657;26025;26191.738082619693;26266.737332626428;26922.192334230003;43847.78076109599;43964.120717586105;44112.11775764531;44759.77620111879;45016.77491612521;36841.26317473582;37406;37477.25045499129;38241.61758382492;38253;36940.63059369372;37572.812135939144;37648.81175594214;37780.43329350117;37899;36518.45222321614;36726.63273367339;36985.81507092447;37060.81469592635;37193.442098368;32152;33496.66503335033;33543.664563354054;33724.662753372155;33869;24839.379015524755;25371.61942570826;25565.3608659785;25677.229683109537;25946.481070378864 12:31:11 INFO - PROCESS | 3247 | |2;jquery.html;376.27191384670647;379.8252803710293;387.370522900287;392.73097927919395;403.5540727496117;15811.288492017859;17176.570585735364;17977.64044719105;18096.638067238648;18132.09339533025;16529.173541322947;16928.576785580368;17067;17290.30838766451;17326.653466930686;5039.017391608638;5150.33045704058;5183.196604526285;5196.532312091911;5231.921521177166;3638.5269914911155;3862.092408284051;5597.524210442118;5895.322037965646;5911.29064512257;5681.857953551153;5925.585209035369;5930.199423077873;5937.703114844262;5949.077892926603;5931.228940237785;5975.014122669754;5979.880402391949;5999.040153575429;6005.939940600581;3338.599368075822;4981.651284410093;5273.235380869791;5280.52475277223;5371;20205.595888082302;20348.491287717778;20497;20958.476038099016;20999.265025724028;2051.0462634874707;2214.7563767985507;2294.300238427279;2394.0304176808245;2455.7544245575477;12623.810642840364;13093.47626094955;13218.339083045808;13472.393742281622;13734.65663358414;1731.9393821216197;1759.516133063405;1814.4828723813782;1841.3555255660547;1851.2132343753985;67311.66344168296;67454;68246;68767;70628.29371706268;9696.61213551464;9744.415335079917;9805.656802012038;10042.297039207186;10151.644692435877;13783.310834458238;13906.096103753223;14029.579112626638;14038.087524311091;14052.227127508086;7046.823829404306;7195.532290401107;7241.094863142107;7406.259374062551;7468.925310746823;6885.51801373899;6899.75850845218;6983;7036;7042.577445353295;6119.816405507842;6201.875962480725;6300.59046161998;6340.112384266206;6464.547481676332;1606.526074807911;1712.5119340988008;1724.9223784929457;1725.0770837602074;1733.7226043832927;801.1988011988012;925.3013974449248;938.6057855700623;984.950752462374;1001.1340190735108;17094.914525427543;17192.570185745208;17248.310067597406;17509.64980700379;17957.371491997717;909.7179874239007;922.5663937949154;948.3219498058921;949.159993405827;1010.9797804043878;9872.506374681238;10050.09549140581;10095.444750538643;10200.744981375525;10317.432541210306;312592;319690;319904;372844;373532;49292.26061609079;55441.722791385786;82433.58783206045;86795;86916;893.0845882970008;958.7842735384482;960.2462067277154;969.3844408800321;987.5852142100335;12858.292793896426;14854.777178342118;14951.925240373728;15804.60488487797;16170.838291617403;26662.600060999488;35092.473612896334;35589.1102722434;35739.46390804088;37024.44463333106;39406.408903867035;41203.58796411997;50798.238026428895;51580;54684.45315546953;7781.844363112593;7892.881606775788;7903.9209607903185;8076.071251805883;8107.918920810716;11133;11417.143714221633;11789.174757767218;11824.40877956116;12362.876371236172;5088.669236499616;5425.647332923348;10452.32059916103;10649.733756656145;10782.622608208674;9958.15355694774;10101.191904647463;10415.479226038819;10453;10697.625583104555;10340.741481463023;10383.584656613652;10539.94730026345;10577.153827693919;10634.574617015232;7830.765077047698;7949.443538952449;8014.799630009064;8061.9596902017465;8224.913563962744;10786;10798.56805727762;10818.51316690735;10833.404162770808;10904.836427453436 12:31:11 INFO - PROCESS | 3247 | |3;mootools.html;1285.8842704156625;1477.8817694584438;1478.0983600003997;1653.2064608987685;1802.9819701802978;6223.8444038899;7130.928690713091;7393.8151546211375;7393.963030184842;7399.260073992598;7077.327653872887;7291.781246562598;7307;7395.408367330614;7464.402847772179;1853.9165737541844;2151.7202763640703;2158.3740715192653;2220.555888822234;2237.8881055947218;2246.5619204255177;2259.085070546423;2259.8757068361233;2260.480089579398;2262.7850354216325;2167.046499540197;2175.4561359660083;2187.3765976696645;2189.288481243594;2193.594185075758;1804.9729254061199;2046.94882627934;2088.457001179689;2118.05746442833;2124.3945475539554;2191.1564047841534;2207.536417352358;2210.9778902210933;2212.6570381590877;2212.734471863371;7489.063867016623;7527.322540971284;7527.811804704871;7536.246375362474;7540.095188577351;4301.78491075445;4402.3176407656865;4414.823407063714;4433.733975961452;4439.889002774924;2713.226730381851;2870.8277503349864;2878.870450829697;2886.07645553423;2896.985515072432;1651.5375694805473;1678.7649729037944;1682.2513981278337;1716.3220527891538;1720.888142270761;10099.848502272473;10307.948460257725;10308.94845525775;10322.638707645272;10339.534720937576;4237.089025859455;4269.436462324371;4291.463567054118;4318.287482565342;4346.630536404377;9014.323925705598;9038.548072596344;9045.457272563668;9050.456972581665;9073.092690731006;4473.865784026484;4560.384348113029;4594.448666160082;4598.655100867449;4605.746683932416;3436.2440263142566;4011.338129208648;4110.157417729418;4121.093359460914;4164.8542301019315;3624.4019736743144;3725.1618385863508;3755.8685446009804;3762.736608437438;3862.1310205204168;1186.2882270637722;1204.4158583087196;1226.1294480918648;1234.111439763369;1248.6316536621719;713.9143302803593;854.2311919272704;862.2670729879552;882.7793051737066;891.2647066170435;5006.224035274466;5180.48195180479;5189.273501709764;5206.7243525336535;5438.184272359178;403.6548750818067;422.4993382841343;423.82199476220063;428.52433798483656;430.3544682975537;3458.775179613317;3665.138692407282;3665.340238756996;3695.7597756145765;3713.20166164276;8720.912790872137;9215.216706579882;9234.584443700049;9265.397749146285;9321.394109382869;9151.95424022889;9220.723378298662;9238.399504032217;9245.167934885758;9245.861312080324;6830.282820303796;7072.9646351767915;7088.822779430452;7093.538919970186;7218.422526197892;5338.944506365;5379.273798037215;5414.891702166014;5421.810236641699;5550.333959924835;8524.659013639384;8613.009503907131;8637.438566493158;8664.176903194186;8831.69089081879;8743.475391476532;8775.254103401283;8945;8988.370814043215;8995.23540499065;5784.595078344475;6314.147590075281;6442.613443193423;6602.273749887605;6706;7285.417166626763;7307.378872795872;7356.117265928123;7361.374283185989;7375.631218438949;6521.510886683613;6611.834704132243;6679.76620818269;6695.196576410862;6727.663616819237;6634.900476492953;6672.5329226953645;6686;6739;6760.459163267025;5997.850053748692;6035.456808887221;6042.395760423922;6053.727582258721;6059.091136329585;4297.484301883794;4332.9133417332105;4356.716813407118;4490.989527356319;4505.864824055284;4412.073464572476;4437;4450.910981780282;4470.418845550058;4492.124035813115 12:31:11 INFO - PROCESS | 3247 | |4;prototype.html;305.925048363151;316.6421943204179;320.8187374133614;323.05345338159213;325.66293885828156;4737.289406589009;4753.764021354446;4772.856814295572;4788.186008378575;4836.678394370704;4678.649101317406;4712.468447754485;4744.715317080969;4782.9433343662295;4788.42538895333;3610.783352998815;3693.815309234541;3727.930998295945;3765.8493660253557;3767.9811600941957;3765.868194613189;3776.6978641708597;3779.338615742248;3780.584135745066;3782.862682584345;3580.677739003489;3749.568799588041;3752.324581575329;3766.9811650941706;3773.433984902259;3617.782933024027;3725.720570957189;3734.663880250763;3745.400735882246;3768.4158955361963;3341.031100980713;3341.3484370547753;3350.5979282486014;3361;3365.612954510238;4530.048689775152;4541.500434952152;4557.658175636808;4571.679982401233;4588.655850811202;4081.6122468365456;4265.424167737347;4278.957210427887;4313.352997050436;4381.956180438218;4991.450940396553;5016.247562865563;5019.716029206702;5025.221090730943;5034.270030845507;3716.6956486977974;3721.9441708374397;3722.571904230994;3727.273181729564;3752.962470375316;4952.653314268003;5155.587552995751;5278.340207474066;5293.735313234323;5301.416844147141;3753.2868754936476;3782.6406491383273;3786.6024067472517;3803.410471376941;3810.142717888453;3828.674562662205;3852.4799152114665;3854.113553882623;3857.1321452673487;3859.768413895119;4322.7190232634775;4342.457192850894;4346.913061738747;4370.361114582031;4388.363687265329;4712.811487540528;4950.480199579064;4958.404991400983;4986.875328116754;4988.102141614544;1323.503686117706;1341.3896677011935;1346.5322798150064;1353.5397964691856;1354.464986330394;873.5326600268826;886.1847100667402;932.9393589416666;946.6355453150586;949.5726922884729;4410.294352903584;4423.845165419195;4440.911181776411;4448.644108471314;4464.330350447394;5015.84952451427;5034.8237811677145;5047.192449208109;5103.387593488805;5145.253938178943;4243.511996120424;4353.670786129405;4390.012247244408;4408.316710909814;4450.866474005785;2650.8409495430337;2688.5295073362086;2704.35095577064;2711.8068050058305;2718.877650505732;4853.12643724126;4854.344663470457;4859.198232291703;4872.635662014642;4877.341558889504;5082.2538345386665;5125.846224613268;5186.247994040843;5217.217417387422;5247.239150323182;4755.595774359149;4770.61835053195;4795.760211989317;4805.1590971579835;4808.4372578912;5054.7978080876355;5082.534970539212;5091.134507133722;5094.2613321068975;5106.514881086291;4829.4204695435365;4974.925376119433;4982;4987.650864439454;4996.875078122931;4839.322494850794;4841.782119804688;4844.539768721966;4846;4851.80592776285;3774.358359079018;3791.601881802371;3799.335116354682;3801.5438147422487;3808.3906574947873;4822.204336284473;4974.228994505857;5014.924776128288;5057.620678449058;5084.3644544431945;4855.635827312895;4865.318855360323;4869;4895.730734809477;4899.853004409873;4879.316635760657;4893;4922.704637721748;4926.852194434173;4930.433000204879;4654.883627909329;4800;4835.129676658235;4835.879103022453;4935.629827763004;4819.710637447184;4912.189488734319;4993.675411098267;5025.3718285214345;5026.723530205873;4929.802807887788;4933.136701077369;4937.8271760488215;4940.901181976268;4947 12:31:11 INFO - PROCESS | 3247 | |5;yui.html;691.7060249394008;707.978760637181;749.9362554182894;752.0701799702947;754.2457542457539;13797.51708690196;14166.85833141667;14217.21805300708;14247.28763561823;14344.426222951082;14019.789703154462;14032.438702451915;14065.437382504688;14078.64803379916;14083.155010699365;662.6653539962313;815.1440986963694;853.9658413663461;858.5578427110044;859.2438653984485;839.7144970709958;843.8692002739585;844.5566077809137;845.5518575155147;845.9111793261711;872.3806097670662;881.5680316644862;881.9162179592928;881.9250363719092;882.7528292078197;682.3551743602297;853.0318088969065;875.3391189651804;875.6541166239364;890.0965519997208;658.0688326018678;658.6311665467354;658.6805399381296;658.6805399381296;658.7793089315085;12902.096853220279;12940.223586584741;12968.221906685534;12978.740425191443;12981.221126732426;11465.484053217626;11640.243384180001;11737.823932641017;11872.347020913847;12310.322932238723;10734.409607471585;11193.552257909674;11255.324680519114;11347.943260283646;11354.943225283821;4159.9376009359885;4171.124063946575;4196.412502249687;4200.285951388271;4259.488861336628;44458.33312500283;44505.777471113084;44561.77719111416;44727;45167.54832451699;9570.61717531291;9659.71020869375;9713.70858874235;9716.562754675915;9756.609735610638;16878.74681879773;16890;16941.830581694026;16960.491185264462;16968.321267149422;6786.864262714718;6889.0355350251;6946.235914049451;6952.2352541220425;7045.682944267418;3601.3157500075426;3614.313280476701;3617.6382361763613;3632.5822530408814;3682.797446140488;2826.293426643339;2936.339323652161;2939.9706002939697;2945.4698154331995;2946.7495262902466;520.2482412913304;521.3795583255913;526.9420363759983;531.2244123579617;531.7952588253551;522.7334059629541;525.332827309315;620.6865532905944;632.8987362022146;654.7937399719074;7929.167437419103;8006.759797206093;8040.71857484997;8253.09215986241;8369.665213391518;496.7590718501524;513.278843225269;556.3324011186511;585.0522154110369;620.3610281410065;3681.0981309579324;3872.612738726105;3965.0880297531153;4038.394240863894;4111.732737372061;47045.76477117593;48767;49013.754931225114;49113.75443122833;49277.75361123172;43839.34240986388;44780.77609611931;44940.3258951116;46081.539184608395;46167.769161154654;41028.58971410307;41490.585094149275;41691.37462938058;42032.369514456695;42184.57815421806;7543.811404714926;7741.225877412214;7864.764057078297;7874.291313781558;8173.713920012771;28621.14136575906;28792.280192995342;28980.27549311284;29296.41407171887;29479;32858.50712239271;35128;36330.091747706516;36751.816240918626;36855.26289474249;9751.463669498246;9994.750131246778;10060.547275372479;10179.643712470028;10519.26365154432;8322.916770832215;9148.313876459159;10490.685279441628;11012.889871101186;11103.888961110286;9102.726918192464;10324.948375258076;11327.830082548933;11342.092632589209;11592.26522072835;11342.376169310766;11489.368084755084;11532.711682208012;11672.654690618763;11791.587294444653;10539.209559282932;10755.516001780095;11332.036776874058;11411;11704.648860534198;9013.278937684838;9022.278217742434;9176.403533770284;9387.812243755223;9410.43537387759;9230.861537076815;9770.560324785543;10030.375556843881;10047.849282260626;10141.174836552664 12:31:11 INFO - PROCESS | 3247 | __end_tp_report 12:31:11 INFO - PROCESS | 3247 | __start_cc_report 12:31:11 INFO - PROCESS | 3247 | _x_x_mozilla_cycle_collect,1050 12:31:11 INFO - PROCESS | 3247 | __end_cc_report 12:31:11 INFO - PROCESS | 3247 | __startTimestamp1463772671175__endTimestamp 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | ------- Summary: start ------- 12:31:11 INFO - PROCESS | 3247 | Number of tests: 6 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#0] dojo.html Cycles:165 Average:4670.33 Median:4800.57 stddev:494.81 (10.3%) stddev-sans-first:469.95 12:31:11 INFO - PROCESS | 3247 | Values: 2638.1 2659.5 2695.5 2709.3 2714.0 4582.9 4644.3 4672.1 4756.8 4780.6 4683.1 4721.2 4787.7 4813.4 4886.3 4304.5 4319.8 4339.5 4399.8 4423.3 4349.9 4384.2 4443.3 4480.1 4482.7 4246.4 4392.5 4395.2 4418.3 4438.9 4225.0 4254.0 4254.8 4277.2 4289.9 4221.6 4259.1 4270.7 4272.9 4315.6 4481.9 4596.2 4610.8 4616.1 4620.4 4966.9 5012.5 5025.7 5035.8 5085.8 5034.6 5034.9 5076.0 5097.7 5105.4 4651.3 4661.2 4767.5 4768.4 4771.3 5151.3 5199.4 5212.6 5212.7 5220.8 4924.2 4938.8 4985.2 4987.8 4993.6 4909.0 5017.1 5027.8 5033.0 5034.1 4920.0 4987.9 4994.7 5018.7 5022.4 5000.3 5005.1 5006.2 5014.9 5052.2 4117.1 4151.1 4220.7 4231.8 4280.9 3654.4 3664.3 3668.9 3697.4 3697.9 4322.7 4343.3 4351.8 4383.6 4392.5 4867.7 4990.4 5028.7 5030.2 5071.0 4399.7 4400.5 4402.1 4435.2 4456.2 4513.6 4521.6 4544.2 4546.1 4555.9 4680.4 4768.0 4784.2 4790.9 4800.6 5082.6 5093.3 5106.4 5112.2 5155.5 4822.3 4832.3 4839.4 4842.3 4871.6 4898.4 4967.4 4981.0 5040.0 5058.1 5128.2 5151.4 5154.4 5156.6 5195.4 4979.3 4985.6 4988.5 4989.3 5048.0 4785.9 4886.7 4964.4 4983.2 4984.5 4890.2 4895.4 4919.4 4945.6 4956.0 5013.6 5013.8 5023.8 5044.9 5069.4 4708.0 4726.7 4797.1 4800.4 4856.1 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#1] ext.html Cycles:180 Average:41858.06 Median:20194.46 stddev:70876.91 (351.0%) stddev-sans-first:71007.91 12:31:11 INFO - PROCESS | 3247 | Values: 560.7 598.4 606.4 609.6 618.4 40932.6 42670.6 42761.4 42772.7 47122.5 43099.1 43750.3 43752.1 43792.8 44060.6 7834.7 8022.3 8067.1 8067.7 8067.9 7578.5 7592.8 7668.3 7673.2 7675.8 8019.4 8069.0 8080.5 8081.2 8086.4 7961.6 8019.5 8024.2 8029.5 8031.4 7919.4 8001.9 8006.5 8006.6 8011.6 39922.8 40267.0 40337.8 40358.4 40391.4 3405.8 3493.5 3681.2 3769.5 3882.2 29020.4 29804.3 29949.4 30374.5 31526.5 3468.6 3480.0 3485.3 3532.9 3662.3 179862.0 181162.0 181416.0 182634.0 182654.0 33822.7 34829.0 34845.1 36771.3 56081.0 35942.8 35989.6 36111.6 36114.0 36172.8 13255.9 13464.7 13487.1 13489.5 13490.0 10251.8 10299.8 10405.7 10436.1 10437.3 8396.7 8411.9 8416.5 8468.8 8502.4 3266.5 3274.9 3293.2 3305.4 3350.6 986.8 991.9 1020.5 1046.4 1097.4 28239.2 28739.7 28829.0 28837.0 29058.7 969.3 991.6 996.8 1021.9 1087.5 15457.8 15493.4 15494.1 15658.9 15928.4 344284.0 349772.0 350267.0 350603.0 351939.0 50567.0 52054.0 52078.0 52089.0 52096.7 1145.0 1227.3 1255.1 1255.7 1273.4 27835.9 29502.9 29535.0 30191.0 30284.8 38509.2 38956.8 38974.8 38995.0 39092.8 246707.0 252199.0 252265.0 253010.0 253021.0 23448.8 26025.0 26191.7 26266.7 26922.2 43847.8 43964.1 44112.1 44759.8 45016.8 36841.3 37406.0 37477.3 38241.6 38253.0 36940.6 37572.8 37648.8 37780.4 37899.0 36518.5 36726.6 36985.8 37060.8 37193.4 32152.0 33496.7 33543.7 33724.7 33869.0 24839.4 25371.6 25565.4 25677.2 25946.5 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#2] jquery.html Cycles:180 Average:22797.05 Median:373188.00 stddev:56475.10 (15.1%) stddev-sans-first:56608.43 12:31:11 INFO - PROCESS | 3247 | Values: 376.3 379.8 387.4 392.7 403.6 15811.3 17176.6 17977.6 18096.6 18132.1 16529.2 16928.6 17067.0 17290.3 17326.7 5039.0 5150.3 5183.2 5196.5 5231.9 3638.5 3862.1 5597.5 5895.3 5911.3 5681.9 5925.6 5930.2 5937.7 5949.1 5931.2 5975.0 5979.9 5999.0 6005.9 3338.6 4981.7 5273.2 5280.5 5371.0 20205.6 20348.5 20497.0 20958.5 20999.3 2051.0 2214.8 2294.3 2394.0 2455.8 12623.8 13093.5 13218.3 13472.4 13734.7 1731.9 1759.5 1814.5 1841.4 1851.2 67311.7 67454.0 68246.0 68767.0 70628.3 9696.6 9744.4 9805.7 10042.3 10151.6 13783.3 13906.1 14029.6 14038.1 14052.2 7046.8 7195.5 7241.1 7406.3 7468.9 6885.5 6899.8 6983.0 7036.0 7042.6 6119.8 6201.9 6300.6 6340.1 6464.5 1606.5 1712.5 1724.9 1725.1 1733.7 801.2 925.3 938.6 985.0 1001.1 17094.9 17192.6 17248.3 17509.6 17957.4 909.7 922.6 948.3 949.2 1011.0 9872.5 10050.1 10095.4 10200.7 10317.4 312592.0 319690.0 319904.0 372844.0 373532.0 49292.3 55441.7 82433.6 86795.0 86916.0 893.1 958.8 960.2 969.4 987.6 12858.3 14854.8 14951.9 15804.6 16170.8 26662.6 35092.5 35589.1 35739.5 37024.4 39406.4 41203.6 50798.2 51580.0 54684.5 7781.8 7892.9 7903.9 8076.1 8107.9 11133.0 11417.1 11789.2 11824.4 12362.9 5088.7 5425.6 10452.3 10649.7 10782.6 9958.2 10101.2 10415.5 10453.0 10697.6 10340.7 10383.6 10539.9 10577.2 10634.6 7830.8 7949.4 8014.8 8062.0 8224.9 10786.0 10798.6 10818.5 10833.4 10904.8 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#3] mootools.html Cycles:180 Average:5021.76 Median:4533.12 stddev:2744.05 (60.5%) stddev-sans-first:2737.38 12:31:11 INFO - PROCESS | 3247 | Values: 1285.9 1477.9 1478.1 1653.2 1803.0 6223.8 7130.9 7393.8 7394.0 7399.3 7077.3 7291.8 7307.0 7395.4 7464.4 1853.9 2151.7 2158.4 2220.6 2237.9 2246.6 2259.1 2259.9 2260.5 2262.8 2167.0 2175.5 2187.4 2189.3 2193.6 1805.0 2046.9 2088.5 2118.1 2124.4 2191.2 2207.5 2211.0 2212.7 2212.7 7489.1 7527.3 7527.8 7536.2 7540.1 4301.8 4402.3 4414.8 4433.7 4439.9 2713.2 2870.8 2878.9 2886.1 2897.0 1651.5 1678.8 1682.3 1716.3 1720.9 10099.8 10307.9 10308.9 10322.6 10339.5 4237.1 4269.4 4291.5 4318.3 4346.6 9014.3 9038.5 9045.5 9050.5 9073.1 4473.9 4560.4 4594.4 4598.7 4605.7 3436.2 4011.3 4110.2 4121.1 4164.9 3624.4 3725.2 3755.9 3762.7 3862.1 1186.3 1204.4 1226.1 1234.1 1248.6 713.9 854.2 862.3 882.8 891.3 5006.2 5180.5 5189.3 5206.7 5438.2 403.7 422.5 423.8 428.5 430.4 3458.8 3665.1 3665.3 3695.8 3713.2 8720.9 9215.2 9234.6 9265.4 9321.4 9152.0 9220.7 9238.4 9245.2 9245.9 6830.3 7073.0 7088.8 7093.5 7218.4 5338.9 5379.3 5414.9 5421.8 5550.3 8524.7 8613.0 8637.4 8664.2 8831.7 8743.5 8775.3 8945.0 8988.4 8995.2 5784.6 6314.1 6442.6 6602.3 6706.0 7285.4 7307.4 7356.1 7361.4 7375.6 6521.5 6611.8 6679.8 6695.2 6727.7 6634.9 6672.5 6686.0 6739.0 6760.5 5997.9 6035.5 6042.4 6053.7 6059.1 4297.5 4332.9 4356.7 4491.0 4505.9 4412.1 4437.0 4450.9 4470.4 4492.1 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#4] prototype.html Cycles:180 Average:4156.02 Median:4749.24 stddev:1169.20 (24.6%) stddev-sans-first:1136.21 12:31:11 INFO - PROCESS | 3247 | Values: 305.9 316.6 320.8 323.1 325.7 4737.3 4753.8 4772.9 4788.2 4836.7 4678.6 4712.5 4744.7 4782.9 4788.4 3610.8 3693.8 3727.9 3765.8 3768.0 3765.9 3776.7 3779.3 3780.6 3782.9 3580.7 3749.6 3752.3 3767.0 3773.4 3617.8 3725.7 3734.7 3745.4 3768.4 3341.0 3341.3 3350.6 3361.0 3365.6 4530.0 4541.5 4557.7 4571.7 4588.7 4081.6 4265.4 4279.0 4313.4 4382.0 4991.5 5016.2 5019.7 5025.2 5034.3 3716.7 3721.9 3722.6 3727.3 3753.0 4952.7 5155.6 5278.3 5293.7 5301.4 3753.3 3782.6 3786.6 3803.4 3810.1 3828.7 3852.5 3854.1 3857.1 3859.8 4322.7 4342.5 4346.9 4370.4 4388.4 4712.8 4950.5 4958.4 4986.9 4988.1 1323.5 1341.4 1346.5 1353.5 1354.5 873.5 886.2 932.9 946.6 949.6 4410.3 4423.8 4440.9 4448.6 4464.3 5015.8 5034.8 5047.2 5103.4 5145.3 4243.5 4353.7 4390.0 4408.3 4450.9 2650.8 2688.5 2704.4 2711.8 2718.9 4853.1 4854.3 4859.2 4872.6 4877.3 5082.3 5125.8 5186.2 5217.2 5247.2 4755.6 4770.6 4795.8 4805.2 4808.4 5054.8 5082.5 5091.1 5094.3 5106.5 4829.4 4974.9 4982.0 4987.7 4996.9 4839.3 4841.8 4844.5 4846.0 4851.8 3774.4 3791.6 3799.3 3801.5 3808.4 4822.2 4974.2 5014.9 5057.6 5084.4 4855.6 4865.3 4869.0 4895.7 4899.9 4879.3 4893.0 4922.7 4926.9 4930.4 4654.9 4800.0 4835.1 4835.9 4935.6 4819.7 4912.2 4993.7 5025.4 5026.7 4929.8 4933.1 4937.8 4940.9 4947.0 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - PROCESS | 3247 | [#5] yui.html Cycles:180 Average:12560.05 Median:44533.78 stddev:13761.22 (30.9%) stddev-sans-first:13770.95 12:31:11 INFO - PROCESS | 3247 | Values: 691.7 708.0 749.9 752.1 754.2 13797.5 14166.9 14217.2 14247.3 14344.4 14019.8 14032.4 14065.4 14078.6 14083.2 662.7 815.1 854.0 858.6 859.2 839.7 843.9 844.6 845.6 845.9 872.4 881.6 881.9 881.9 882.8 682.4 853.0 875.3 875.7 890.1 658.1 658.6 658.7 658.7 658.8 12902.1 12940.2 12968.2 12978.7 12981.2 11465.5 11640.2 11737.8 11872.3 12310.3 10734.4 11193.6 11255.3 11347.9 11354.9 4159.9 4171.1 4196.4 4200.3 4259.5 44458.3 44505.8 44561.8 44727.0 45167.5 9570.6 9659.7 9713.7 9716.6 9756.6 16878.7 16890.0 16941.8 16960.5 16968.3 6786.9 6889.0 6946.2 6952.2 7045.7 3601.3 3614.3 3617.6 3632.6 3682.8 2826.3 2936.3 2940.0 2945.5 2946.7 520.2 521.4 526.9 531.2 531.8 522.7 525.3 620.7 632.9 654.8 7929.2 8006.8 8040.7 8253.1 8369.7 496.8 513.3 556.3 585.1 620.4 3681.1 3872.6 3965.1 4038.4 4111.7 47045.8 48767.0 49013.8 49113.8 49277.8 43839.3 44780.8 44940.3 46081.5 46167.8 41028.6 41490.6 41691.4 42032.4 42184.6 7543.8 7741.2 7864.8 7874.3 8173.7 28621.1 28792.3 28980.3 29296.4 29479.0 32858.5 35128.0 36330.1 36751.8 36855.3 9751.5 9994.8 10060.5 10179.6 10519.3 8322.9 9148.3 10490.7 11012.9 11103.9 9102.7 10324.9 11327.8 11342.1 11592.3 11342.4 11489.4 11532.7 11672.7 11791.6 10539.2 10755.5 11332.0 11411.0 11704.6 9013.3 9022.3 9176.4 9387.8 9410.4 9230.9 9770.6 10030.4 10047.8 10141.2 12:31:11 INFO - PROCESS | 3247 | -------- Summary: end -------- 12:31:11 INFO - PROCESS | 3247 | 12:31:11 INFO - TEST-INFO | 3247: exit 0 12:31:11 INFO - TEST-OK | dromaeo_css | took 1118619ms 12:31:11 INFO - TEST-START | kraken 12:31:11 INFO - operating with platform_type : linux_ 12:31:11 INFO - Initialising browser for kraken test... 12:31:11 INFO - TEST-INFO | started process 3427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4ytndU/profile http://localhost:53195/getInfo.html) 12:31:16 INFO - PROCESS | 3427 | __metrics Screen width/height:1600/1200 12:31:16 INFO - PROCESS | 3427 | colorDepth:24 12:31:16 INFO - PROCESS | 3427 | Browser inner width/height: 1024/697 12:31:16 INFO - PROCESS | 3427 | __metrics 12:31:17 ERROR - PROCESS | 3427 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:31:18 INFO - TEST-INFO | 3427: exit 0 12:31:18 INFO - Browser initialized. 12:31:18 INFO - Running cycle 1/1 for kraken test... 12:31:18 INFO - TEST-INFO | started process 3506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4ytndU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:30 INFO - PROCESS | 3506 | RSS: Main: 154046464 12:31:30 INFO - PROCESS | 3506 | 12:31:32 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53195/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:33 INFO - PROCESS | 3506 | RSS: Main: 209887232 12:31:33 INFO - PROCESS | 3506 | 12:31:36 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53195/tests/kraken/driver.html?testName=audio-dft) 12:31:37 INFO - PROCESS | 3506 | RSS: Main: 196792320 12:31:37 INFO - PROCESS | 3506 | 12:31:40 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53195/tests/kraken/driver.html?testName=audio-fft) 12:31:41 INFO - PROCESS | 3506 | RSS: Main: 266678272 12:31:41 INFO - PROCESS | 3506 | 12:31:43 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53195/tests/kraken/driver.html?testName=audio-oscillator) 12:31:44 INFO - PROCESS | 3506 | RSS: Main: 175575040 12:31:44 INFO - PROCESS | 3506 | 12:31:46 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53195/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:47 INFO - PROCESS | 3506 | RSS: Main: 179331072 12:31:47 INFO - PROCESS | 3506 | 12:31:50 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53195/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:51 INFO - PROCESS | 3506 | RSS: Main: 216178688 12:31:51 INFO - PROCESS | 3506 | 12:31:54 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53195/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:55 INFO - PROCESS | 3506 | RSS: Main: 214343680 12:31:55 INFO - PROCESS | 3506 | 12:31:58 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53195/tests/kraken/driver.html?testName=json-parse-financial) 12:31:59 INFO - PROCESS | 3506 | RSS: Main: 214687744 12:31:59 INFO - PROCESS | 3506 | 12:32:00 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53195/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:32:01 INFO - PROCESS | 3506 | RSS: Main: 210194432 12:32:01 INFO - PROCESS | 3506 | 12:32:03 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:32:04 INFO - PROCESS | 3506 | RSS: Main: 209162240 12:32:04 INFO - PROCESS | 3506 | 12:32:06 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:32:07 INFO - PROCESS | 3506 | RSS: Main: 215666688 12:32:07 INFO - PROCESS | 3506 | 12:32:09 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:32:10 INFO - PROCESS | 3506 | RSS: Main: 221229056 12:32:10 INFO - PROCESS | 3506 | 12:32:13 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:32:14 INFO - PROCESS | 3506 | RSS: Main: 217767936 12:32:14 INFO - PROCESS | 3506 | 12:32:15 INFO - PROCESS | 3506 | Cycle 1(1): loaded http://localhost:53195/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53195/tests/kraken/driver.html?testName=ai-astar) 12:32:16 INFO - PROCESS | 3506 | RSS: Main: 264687616 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | __start_tp_report 12:32:16 INFO - PROCESS | 3506 | _x_x_mozilla_page_load 12:32:16 INFO - PROCESS | 3506 | _x_x_mozilla_page_load_details 12:32:16 INFO - PROCESS | 3506 | |i|pagename|runs| 12:32:16 INFO - PROCESS | 3506 | |0;ai-astar;115;108;114;116;114;113;112;116;112;114 12:32:16 INFO - PROCESS | 3506 | |1;audio-beat-detection;159;129;156;211;131;159;153;131;198;134 12:32:16 INFO - PROCESS | 3506 | |2;audio-dft;162;162;162;162;170;161;164;162;162;163 12:32:16 INFO - PROCESS | 3506 | |3;audio-fft;77;76;78;151;78;75;78;75;143;76 12:32:16 INFO - PROCESS | 3506 | |4;audio-oscillator;93;93;150;93;94;94;124;93;93;128 12:32:16 INFO - PROCESS | 3506 | |5;imaging-gaussian-blur;118;117;117;116;118;117;117;116;116;116 12:32:16 INFO - PROCESS | 3506 | |6;imaging-darkroom;146;146;147;146;147;146;146;145;146;146 12:32:16 INFO - PROCESS | 3506 | |7;imaging-desaturate;95;94;95;95;94;95;94;95;95;95 12:32:16 INFO - PROCESS | 3506 | |8;json-parse-financial;66;68;67;68;68;68;66;68;66;66 12:32:16 INFO - PROCESS | 3506 | |9;json-stringify-tinderbox;65;64;65;65;65;64;64;64;64;65 12:32:16 INFO - PROCESS | 3506 | |10;stanford-crypto-aes;88;86;83;83;83;84;88;82;164;84 12:32:16 INFO - PROCESS | 3506 | |11;stanford-crypto-ccm;136;135;145;133;134;132;132;139;161;135 12:32:16 INFO - PROCESS | 3506 | |12;stanford-crypto-pbkdf2;177;178;176;176;173;178;174;175;174;173 12:32:16 INFO - PROCESS | 3506 | |13;stanford-crypto-sha256-iterative;69;69;69;69;69;72;69;68;69;68 12:32:16 INFO - PROCESS | 3506 | __end_tp_report 12:32:16 INFO - PROCESS | 3506 | __start_cc_report 12:32:16 INFO - PROCESS | 3506 | _x_x_mozilla_cycle_collect,2029 12:32:16 INFO - PROCESS | 3506 | __end_cc_report 12:32:16 INFO - PROCESS | 3506 | __startTimestamp1463772736219__endTimestamp 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | ------- Summary: start ------- 12:32:16 INFO - PROCESS | 3506 | Number of tests: 14 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.37 (2.1%) stddev-sans-first:2.44 12:32:16 INFO - PROCESS | 3506 | Values: 115.0 108.0 114.0 116.0 114.0 113.0 112.0 116.0 112.0 114.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#1] audio-beat-detection Cycles:10 Average:156.10 Median:157.50 stddev:28.44 (18.1%) stddev-sans-first:30.14 12:32:16 INFO - PROCESS | 3506 | Values: 159.0 129.0 156.0 211.0 131.0 159.0 153.0 131.0 198.0 134.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#2] audio-dft Cycles:10 Average:163.00 Median:162.00 stddev:2.58 (1.6%) stddev-sans-first:2.71 12:32:16 INFO - PROCESS | 3506 | Values: 162.0 162.0 162.0 162.0 170.0 161.0 164.0 162.0 162.0 163.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#3] audio-fft Cycles:10 Average:90.70 Median:76.50 stddev:29.75 (38.9%) stddev-sans-first:31.14 12:32:16 INFO - PROCESS | 3506 | Values: 77.0 76.0 78.0 151.0 78.0 75.0 78.0 75.0 143.0 76.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#4] audio-oscillator Cycles:10 Average:105.50 Median:93.00 stddev:20.75 (22.3%) stddev-sans-first:21.51 12:32:16 INFO - PROCESS | 3506 | Values: 93.0 93.0 150.0 93.0 94.0 94.0 124.0 93.0 93.0 128.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 12:32:16 INFO - PROCESS | 3506 | Values: 118.0 117.0 117.0 116.0 118.0 117.0 117.0 116.0 116.0 116.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:32:16 INFO - PROCESS | 3506 | Values: 146.0 146.0 147.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 12:32:16 INFO - PROCESS | 3506 | Values: 95.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 12:32:16 INFO - PROCESS | 3506 | Values: 66.0 68.0 67.0 68.0 68.0 68.0 66.0 68.0 66.0 66.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 12:32:16 INFO - PROCESS | 3506 | Values: 65.0 64.0 65.0 65.0 65.0 64.0 64.0 64.0 64.0 65.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:84.00 stddev:25.21 (30.0%) stddev-sans-first:26.69 12:32:16 INFO - PROCESS | 3506 | Values: 88.0 86.0 83.0 83.0 83.0 84.0 88.0 82.0 164.0 84.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:135.50 stddev:8.90 (6.6%) stddev-sans-first:9.41 12:32:16 INFO - PROCESS | 3506 | Values: 136.0 135.0 145.0 133.0 134.0 132.0 132.0 139.0 161.0 135.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:176.00 stddev:1.90 (1.1%) stddev-sans-first:1.92 12:32:16 INFO - PROCESS | 3506 | Values: 177.0 178.0 176.0 176.0 173.0 178.0 174.0 175.0 174.0 173.0 12:32:16 INFO - PROCESS | 3506 | 12:32:16 INFO - PROCESS | 3506 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 12:32:16 INFO - PROCESS | 3506 | Values: 69.0 69.0 69.0 69.0 69.0 72.0 69.0 68.0 69.0 68.0 12:32:16 INFO - PROCESS | 3506 | -------- Summary: end -------- 12:32:16 INFO - PROCESS | 3506 | 12:32:17 INFO - TEST-INFO | 3506: exit 0 12:32:17 INFO - TEST-OK | kraken | took 65385ms 12:32:17 INFO - SUITE-END | took 1184s 12:32:17 INFO - Completed test suite (00:19:44) 12:32:17 INFO - javascript benchmark 12:32:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2638.116231062594, 2659.5079910216623, 2695.5013322535337, 2709.322669332667, 2713.982256653755, 4582.862514124573, 4644.326572646973, 4672.135654903841, 4756.7859446324865, 4780.641451891109, 4683.063387322532, 4721.2209985352465, 4787.688800227991, 4813.350197723306, 4886.26705994101, 4304.504981927072, 4319.76241306728, 4339.544347843478, 4399.780010999437, 4423.314386270125, 4349.913001739963, 4384.18892504886, 4443.311286750559, 4480.059187570614, 4482.731036137842, 4246.40550322955, 4392.538783427726, 4395.230834603947, 4418.270985287424, 4438.866833994985, 4225.0, 4254.0, 4254.808533615995, 4277.230098582254, 4289.948962504175, 4221.577842215785, 4259.084296876155, 4270.722403043792, 4272.850450234258, 4315.611594956437, 4481.932771008437, 4596.172688915994, 4610.838620648261, 4616.0767846430845, 4620.399348084761, 4966.87582810429, 5012.548870601663, 5025.698458092526, 5035.823746168903, 5085.847424577269, 5034.572061374787, 5034.924476132861, 5075.974620126913, 5097.694138351673, 5105.361829771278, 4651.348811166439, 4661.160991021648, 4767.523247675239, 4768.403949506312, 4771.28430735389, 5151.278820965068, 5199.376074870964, 5212.582993360522, 5212.713300768418, 5220.843374698765, 4924.162892308316, 4938.75306234694, 4985.202367621163, 4987.825426110068, 4993.550580447704, 4909.0, 5017.071841709296, 5027.824026159067, 5033.0, 5034.093863104603, 4919.975400122977, 4987.900241995139, 4994.650374473752, 5018.673786203958, 5022.372203474566, 5000.349954505964, 5005.099082165245, 5006.249062640561, 5014.89970200594, 5052.242163675478, 4117.114820313647, 4151.14901445203, 4220.725652832556, 4231.767252801125, 4280.935785963213, 3654.4335627977707, 3664.285464334429, 3668.853245870135, 3697.408414653696, 3697.9075523112137, 4322.7190232634775, 4343.3484977253665, 4351.8476853309985, 4383.583559561836, 4392.472903251566, 4867.7322747249245, 4990.351254336913, 5028.748562571856, 5030.245463180552, 5071.0, 4399.692021558524, 4400.537943515948, 4402.141582391467, 4435.179491794029, 4456.153330867125, 4513.616342610849, 4521.61566266871, 4544.182047231464, 4546.090781843644, 4555.886102847456, 4680.4149481314835, 4768.02255537607, 4784.210605250164, 4790.856274311776, 4800.567948884617, 5082.567981721595, 5093.261477085876, 5106.412762532356, 5112.233165025276, 5155.484451554965, 4822.300766388924, 4832.299316599003, 4839.443464001685, 4842.322074909586, 4871.634627402888, 4898.436679781871, 4967.379077615298, 4980.975095124501, 5040.0, 5058.089543882136, 5128.230765385222, 5151.356080489939, 5154.381474223117, 5156.587473002076, 5195.376554813295, 4979.278004689372, 4985.576226020684, 4988.476209997898, 4989.276554899592, 5048.040872234264, 4785.928211076767, 4886.65793394459, 4964.354633897715, 4983.177775667119, 4984.476629953802, 4890.24201248807, 4895.363602731622, 4919.409670839522, 4945.629077819106, 4955.975220123876, 5013.623978201576, 5013.849584512471, 5023.824166154167, 5044.873878153076, 5069.391672999116, 4707.952920470751, 4726.740029298421, 4797.064572408347, 4800.399950006249, 4856.1016212000895], "value": 4638.810108348574, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.7252446301311, 598.362743677983, 606.3845197124916, 609.551979295218, 618.3569088148327, 40932.59067409325, 42670.57329426705, 42761.35857962133, 42772.65341832271, 47122.52877471224, 43099.13801723964, 43750.343744843856, 43752.12495750083, 43792.78103609486, 44060.559394406046, 7834.72578459754, 8022.277994980451, 8067.112617612058, 8067.717629882955, 8067.919320806775, 7578.545287282781, 7592.848143037136, 7668.348190403794, 7673.155952845183, 7675.846483070335, 8019.438639295252, 8068.959655201716, 8080.515169089874, 8081.1514790946985, 8086.433949623529, 7961.562114083723, 8019.5188288702575, 8024.2376974187355, 8029.478083924526, 8031.397645176577, 7919.445638805286, 8001.919980800176, 8006.479578827416, 8006.55963921984, 8011.639476223584, 39922.800385998176, 40267.0, 40337.79831100855, 40358.39462408066, 40391.39412908809, 3405.7615966882327, 3493.493443450711, 3681.245344704329, 3769.5476542814763, 3882.2041481496226, 29020.41959160805, 29804.254893627614, 29949.401011979855, 30374.54438183429, 31526.527102093485, 3468.6357932416986, 3479.9826000869834, 3485.302939412102, 3532.876349327761, 3662.285854258421, 179862.0, 181162.0, 181416.0, 182634.0, 182654.0, 33822.661773382446, 34829.0, 34845.1288717779, 36771.26457470836, 56081.0, 35942.8202858984, 35989.64010359915, 36111.638883611355, 36114.0, 36172.81913590415, 13255.867441325463, 13464.663383415493, 13487.123336983064, 13489.460421583222, 13490.0, 10251.84622230667, 10299.845502317472, 10405.739856503647, 10436.060754532129, 10437.2693911427, 8396.748097557083, 8411.915880841236, 8416.537090460084, 8468.788280292918, 8502.447340922943, 3266.477363621809, 3274.8526316315824, 3293.2425542124965, 3305.355455686118, 3350.564426624523, 986.7927735175693, 991.9107280344659, 1020.4999550220487, 1046.4401545173296, 1097.3854641400842, 28239.15282541527, 28739.712602874122, 28829.0, 28837.0, 29058.709412906024, 969.2827307792325, 991.5785791038779, 996.7807082441851, 1021.9437930913871, 1087.472575800736, 15457.768133478006, 15493.380264789283, 15494.11223734996, 15658.9217053914, 15928.362865485482, 344284.0, 349772.0, 350267.0, 350603.0, 351939.0, 50567.0, 52054.0, 52078.0, 52089.0, 52096.739516302936, 1144.9885501144881, 1227.2759072147476, 1255.1088727003746, 1255.704909346321, 1273.4078653426016, 27835.860820695765, 29502.852485737432, 29535.0, 30191.0, 30284.84857575698, 38509.2298154041, 38956.80521597374, 38974.80512597533, 38995.0, 39092.804535977135, 246707.0, 252199.0, 252265.0, 253010.0, 253021.0, 23448.765512344657, 26025.0, 26191.738082619693, 26266.737332626428, 26922.192334230003, 43847.78076109599, 43964.120717586105, 44112.11775764531, 44759.77620111879, 45016.77491612521, 36841.26317473582, 37406.0, 37477.25045499129, 38241.61758382492, 38253.0, 36940.63059369372, 37572.812135939144, 37648.81175594214, 37780.43329350117, 37899.0, 36518.45222321614, 36726.63273367339, 36985.81507092447, 37060.81469592635, 37193.442098368, 32152.0, 33496.66503335033, 33543.664563354054, 33724.662753372155, 33869.0, 24839.379015524755, 25371.61942570826, 25565.3608659785, 25677.229683109537, 25946.481070378864], "value": 16605.729150551182, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.27191384670647, 379.8252803710293, 387.370522900287, 392.73097927919395, 403.5540727496117, 15811.288492017859, 17176.570585735364, 17977.64044719105, 18096.638067238648, 18132.09339533025, 16529.173541322947, 16928.576785580368, 17067.0, 17290.30838766451, 17326.653466930686, 5039.017391608638, 5150.33045704058, 5183.196604526285, 5196.532312091911, 5231.921521177166, 3638.5269914911155, 3862.092408284051, 5597.524210442118, 5895.322037965646, 5911.29064512257, 5681.857953551153, 5925.585209035369, 5930.199423077873, 5937.703114844262, 5949.077892926603, 5931.228940237785, 5975.014122669754, 5979.880402391949, 5999.040153575429, 6005.939940600581, 3338.599368075822, 4981.651284410093, 5273.235380869791, 5280.52475277223, 5371.0, 20205.595888082302, 20348.491287717778, 20497.0, 20958.476038099016, 20999.265025724028, 2051.0462634874707, 2214.7563767985507, 2294.300238427279, 2394.0304176808245, 2455.7544245575477, 12623.810642840364, 13093.47626094955, 13218.339083045808, 13472.393742281622, 13734.65663358414, 1731.9393821216197, 1759.516133063405, 1814.4828723813782, 1841.3555255660547, 1851.2132343753985, 67311.66344168296, 67454.0, 68246.0, 68767.0, 70628.29371706268, 9696.61213551464, 9744.415335079917, 9805.656802012038, 10042.297039207186, 10151.644692435877, 13783.310834458238, 13906.096103753223, 14029.579112626638, 14038.087524311091, 14052.227127508086, 7046.823829404306, 7195.532290401107, 7241.094863142107, 7406.259374062551, 7468.925310746823, 6885.51801373899, 6899.75850845218, 6983.0, 7036.0, 7042.577445353295, 6119.816405507842, 6201.875962480725, 6300.59046161998, 6340.112384266206, 6464.547481676332, 1606.526074807911, 1712.5119340988008, 1724.9223784929457, 1725.0770837602074, 1733.7226043832927, 801.1988011988012, 925.3013974449248, 938.6057855700623, 984.950752462374, 1001.1340190735108, 17094.914525427543, 17192.570185745208, 17248.310067597406, 17509.64980700379, 17957.371491997717, 909.7179874239007, 922.5663937949154, 948.3219498058921, 949.159993405827, 1010.9797804043878, 9872.506374681238, 10050.09549140581, 10095.444750538643, 10200.744981375525, 10317.432541210306, 312592.0, 319690.0, 319904.0, 372844.0, 373532.0, 49292.26061609079, 55441.722791385786, 82433.58783206045, 86795.0, 86916.0, 893.0845882970008, 958.7842735384482, 960.2462067277154, 969.3844408800321, 987.5852142100335, 12858.292793896426, 14854.777178342118, 14951.925240373728, 15804.60488487797, 16170.838291617403, 26662.600060999488, 35092.473612896334, 35589.1102722434, 35739.46390804088, 37024.44463333106, 39406.408903867035, 41203.58796411997, 50798.238026428895, 51580.0, 54684.45315546953, 7781.844363112593, 7892.881606775788, 7903.9209607903185, 8076.071251805883, 8107.918920810716, 11133.0, 11417.143714221633, 11789.174757767218, 11824.40877956116, 12362.876371236172, 5088.669236499616, 5425.647332923348, 10452.32059916103, 10649.733756656145, 10782.622608208674, 9958.15355694774, 10101.191904647463, 10415.479226038819, 10453.0, 10697.625583104555, 10340.741481463023, 10383.584656613652, 10539.94730026345, 10577.153827693919, 10634.574617015232, 7830.765077047698, 7949.443538952449, 8014.799630009064, 8061.9596902017465, 8224.913563962744, 10786.0, 10798.56805727762, 10818.51316690735, 10833.404162770808, 10904.836427453436], "value": 8401.782488451492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1285.8842704156625, 1477.8817694584438, 1478.0983600003997, 1653.2064608987685, 1802.9819701802978, 6223.8444038899, 7130.928690713091, 7393.8151546211375, 7393.963030184842, 7399.260073992598, 7077.327653872887, 7291.781246562598, 7307.0, 7395.408367330614, 7464.402847772179, 1853.9165737541844, 2151.7202763640703, 2158.3740715192653, 2220.555888822234, 2237.8881055947218, 2246.5619204255177, 2259.085070546423, 2259.8757068361233, 2260.480089579398, 2262.7850354216325, 2167.046499540197, 2175.4561359660083, 2187.3765976696645, 2189.288481243594, 2193.594185075758, 1804.9729254061199, 2046.94882627934, 2088.457001179689, 2118.05746442833, 2124.3945475539554, 2191.1564047841534, 2207.536417352358, 2210.9778902210933, 2212.6570381590877, 2212.734471863371, 7489.063867016623, 7527.322540971284, 7527.811804704871, 7536.246375362474, 7540.095188577351, 4301.78491075445, 4402.3176407656865, 4414.823407063714, 4433.733975961452, 4439.889002774924, 2713.226730381851, 2870.8277503349864, 2878.870450829697, 2886.07645553423, 2896.985515072432, 1651.5375694805473, 1678.7649729037944, 1682.2513981278337, 1716.3220527891538, 1720.888142270761, 10099.848502272473, 10307.948460257725, 10308.94845525775, 10322.638707645272, 10339.534720937576, 4237.089025859455, 4269.436462324371, 4291.463567054118, 4318.287482565342, 4346.630536404377, 9014.323925705598, 9038.548072596344, 9045.457272563668, 9050.456972581665, 9073.092690731006, 4473.865784026484, 4560.384348113029, 4594.448666160082, 4598.655100867449, 4605.746683932416, 3436.2440263142566, 4011.338129208648, 4110.157417729418, 4121.093359460914, 4164.8542301019315, 3624.4019736743144, 3725.1618385863508, 3755.8685446009804, 3762.736608437438, 3862.1310205204168, 1186.2882270637722, 1204.4158583087196, 1226.1294480918648, 1234.111439763369, 1248.6316536621719, 713.9143302803593, 854.2311919272704, 862.2670729879552, 882.7793051737066, 891.2647066170435, 5006.224035274466, 5180.48195180479, 5189.273501709764, 5206.7243525336535, 5438.184272359178, 403.6548750818067, 422.4993382841343, 423.82199476220063, 428.52433798483656, 430.3544682975537, 3458.775179613317, 3665.138692407282, 3665.340238756996, 3695.7597756145765, 3713.20166164276, 8720.912790872137, 9215.216706579882, 9234.584443700049, 9265.397749146285, 9321.394109382869, 9151.95424022889, 9220.723378298662, 9238.399504032217, 9245.167934885758, 9245.861312080324, 6830.282820303796, 7072.9646351767915, 7088.822779430452, 7093.538919970186, 7218.422526197892, 5338.944506365, 5379.273798037215, 5414.891702166014, 5421.810236641699, 5550.333959924835, 8524.659013639384, 8613.009503907131, 8637.438566493158, 8664.176903194186, 8831.69089081879, 8743.475391476532, 8775.254103401283, 8945.0, 8988.370814043215, 8995.23540499065, 5784.595078344475, 6314.147590075281, 6442.613443193423, 6602.273749887605, 6706.0, 7285.417166626763, 7307.378872795872, 7356.117265928123, 7361.374283185989, 7375.631218438949, 6521.510886683613, 6611.834704132243, 6679.76620818269, 6695.196576410862, 6727.663616819237, 6634.900476492953, 6672.5329226953645, 6686.0, 6739.0, 6760.459163267025, 5997.850053748692, 6035.456808887221, 6042.395760423922, 6053.727582258721, 6059.091136329585, 4297.484301883794, 4332.9133417332105, 4356.716813407118, 4490.989527356319, 4505.864824055284, 4412.073464572476, 4437.0, 4450.910981780282, 4470.418845550058, 4492.124035813115], "value": 4061.3047380854086, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.925048363151, 316.6421943204179, 320.8187374133614, 323.05345338159213, 325.66293885828156, 4737.289406589009, 4753.764021354446, 4772.856814295572, 4788.186008378575, 4836.678394370704, 4678.649101317406, 4712.468447754485, 4744.715317080969, 4782.9433343662295, 4788.42538895333, 3610.783352998815, 3693.815309234541, 3727.930998295945, 3765.8493660253557, 3767.9811600941957, 3765.868194613189, 3776.6978641708597, 3779.338615742248, 3780.584135745066, 3782.862682584345, 3580.677739003489, 3749.568799588041, 3752.324581575329, 3766.9811650941706, 3773.433984902259, 3617.782933024027, 3725.720570957189, 3734.663880250763, 3745.400735882246, 3768.4158955361963, 3341.031100980713, 3341.3484370547753, 3350.5979282486014, 3361.0, 3365.612954510238, 4530.048689775152, 4541.500434952152, 4557.658175636808, 4571.679982401233, 4588.655850811202, 4081.6122468365456, 4265.424167737347, 4278.957210427887, 4313.352997050436, 4381.956180438218, 4991.450940396553, 5016.247562865563, 5019.716029206702, 5025.221090730943, 5034.270030845507, 3716.6956486977974, 3721.9441708374397, 3722.571904230994, 3727.273181729564, 3752.962470375316, 4952.653314268003, 5155.587552995751, 5278.340207474066, 5293.735313234323, 5301.416844147141, 3753.2868754936476, 3782.6406491383273, 3786.6024067472517, 3803.410471376941, 3810.142717888453, 3828.674562662205, 3852.4799152114665, 3854.113553882623, 3857.1321452673487, 3859.768413895119, 4322.7190232634775, 4342.457192850894, 4346.913061738747, 4370.361114582031, 4388.363687265329, 4712.811487540528, 4950.480199579064, 4958.404991400983, 4986.875328116754, 4988.102141614544, 1323.503686117706, 1341.3896677011935, 1346.5322798150064, 1353.5397964691856, 1354.464986330394, 873.5326600268826, 886.1847100667402, 932.9393589416666, 946.6355453150586, 949.5726922884729, 4410.294352903584, 4423.845165419195, 4440.911181776411, 4448.644108471314, 4464.330350447394, 5015.84952451427, 5034.8237811677145, 5047.192449208109, 5103.387593488805, 5145.253938178943, 4243.511996120424, 4353.670786129405, 4390.012247244408, 4408.316710909814, 4450.866474005785, 2650.8409495430337, 2688.5295073362086, 2704.35095577064, 2711.8068050058305, 2718.877650505732, 4853.12643724126, 4854.344663470457, 4859.198232291703, 4872.635662014642, 4877.341558889504, 5082.2538345386665, 5125.846224613268, 5186.247994040843, 5217.217417387422, 5247.239150323182, 4755.595774359149, 4770.61835053195, 4795.760211989317, 4805.1590971579835, 4808.4372578912, 5054.7978080876355, 5082.534970539212, 5091.134507133722, 5094.2613321068975, 5106.514881086291, 4829.4204695435365, 4974.925376119433, 4982.0, 4987.650864439454, 4996.875078122931, 4839.322494850794, 4841.782119804688, 4844.539768721966, 4846.0, 4851.80592776285, 3774.358359079018, 3791.601881802371, 3799.335116354682, 3801.5438147422487, 3808.3906574947873, 4822.204336284473, 4974.228994505857, 5014.924776128288, 5057.620678449058, 5084.3644544431945, 4855.635827312895, 4865.318855360323, 4869.0, 4895.730734809477, 4899.853004409873, 4879.316635760657, 4893.0, 4922.704637721748, 4926.852194434173, 4930.433000204879, 4654.883627909329, 4800.0, 4835.129676658235, 4835.879103022453, 4935.629827763004, 4819.710637447184, 4912.189488734319, 4993.675411098267, 5025.3718285214345, 5026.723530205873, 4929.802807887788, 4933.136701077369, 4937.8271760488215, 4940.901181976268, 4947.0], "value": 3795.349279714683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.7060249394008, 707.978760637181, 749.9362554182894, 752.0701799702947, 754.2457542457539, 13797.51708690196, 14166.85833141667, 14217.21805300708, 14247.28763561823, 14344.426222951082, 14019.789703154462, 14032.438702451915, 14065.437382504688, 14078.64803379916, 14083.155010699365, 662.6653539962313, 815.1440986963694, 853.9658413663461, 858.5578427110044, 859.2438653984485, 839.7144970709958, 843.8692002739585, 844.5566077809137, 845.5518575155147, 845.9111793261711, 872.3806097670662, 881.5680316644862, 881.9162179592928, 881.9250363719092, 882.7528292078197, 682.3551743602297, 853.0318088969065, 875.3391189651804, 875.6541166239364, 890.0965519997208, 658.0688326018678, 658.6311665467354, 658.6805399381296, 658.6805399381296, 658.7793089315085, 12902.096853220279, 12940.223586584741, 12968.221906685534, 12978.740425191443, 12981.221126732426, 11465.484053217626, 11640.243384180001, 11737.823932641017, 11872.347020913847, 12310.322932238723, 10734.409607471585, 11193.552257909674, 11255.324680519114, 11347.943260283646, 11354.943225283821, 4159.9376009359885, 4171.124063946575, 4196.412502249687, 4200.285951388271, 4259.488861336628, 44458.33312500283, 44505.777471113084, 44561.77719111416, 44727.0, 45167.54832451699, 9570.61717531291, 9659.71020869375, 9713.70858874235, 9716.562754675915, 9756.609735610638, 16878.74681879773, 16890.0, 16941.830581694026, 16960.491185264462, 16968.321267149422, 6786.864262714718, 6889.0355350251, 6946.235914049451, 6952.2352541220425, 7045.682944267418, 3601.3157500075426, 3614.313280476701, 3617.6382361763613, 3632.5822530408814, 3682.797446140488, 2826.293426643339, 2936.339323652161, 2939.9706002939697, 2945.4698154331995, 2946.7495262902466, 520.2482412913304, 521.3795583255913, 526.9420363759983, 531.2244123579617, 531.7952588253551, 522.7334059629541, 525.332827309315, 620.6865532905944, 632.8987362022146, 654.7937399719074, 7929.167437419103, 8006.759797206093, 8040.71857484997, 8253.09215986241, 8369.665213391518, 496.7590718501524, 513.278843225269, 556.3324011186511, 585.0522154110369, 620.3610281410065, 3681.0981309579324, 3872.612738726105, 3965.0880297531153, 4038.394240863894, 4111.732737372061, 47045.76477117593, 48767.0, 49013.754931225114, 49113.75443122833, 49277.75361123172, 43839.34240986388, 44780.77609611931, 44940.3258951116, 46081.539184608395, 46167.769161154654, 41028.58971410307, 41490.585094149275, 41691.37462938058, 42032.369514456695, 42184.57815421806, 7543.811404714926, 7741.225877412214, 7864.764057078297, 7874.291313781558, 8173.713920012771, 28621.14136575906, 28792.280192995342, 28980.27549311284, 29296.41407171887, 29479.0, 32858.50712239271, 35128.0, 36330.091747706516, 36751.816240918626, 36855.26289474249, 9751.463669498246, 9994.750131246778, 10060.547275372479, 10179.643712470028, 10519.26365154432, 8322.916770832215, 9148.313876459159, 10490.685279441628, 11012.889871101186, 11103.888961110286, 9102.726918192464, 10324.948375258076, 11327.830082548933, 11342.092632589209, 11592.26522072835, 11342.376169310766, 11489.368084755084, 11532.711682208012, 11672.654690618763, 11791.587294444653, 10539.209559282932, 10755.516001780095, 11332.036776874058, 11411.0, 11704.648860534198, 9013.278937684838, 9022.278217742434, 9176.403533770284, 9387.812243755223, 9410.43537387759, 9230.861537076815, 9770.560324785543, 10030.375556843881, 10047.849282260626, 10141.174836552664], "value": 5990.1371152737, "unit": "score"}], "name": "dromaeo_css", "value": 6252.779415358679, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 114.0, 116.0, 114.0, 113.0, 112.0, 116.0, 112.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 129.0, 156.0, 211.0, 131.0, 159.0, 153.0, 131.0, 198.0, 134.0], "value": 156.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 170.0, 161.0, 164.0, 162.0, 162.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 78.0, 151.0, 78.0, 75.0, 78.0, 75.0, 143.0, 76.0], "value": 90.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 93.0, 94.0, 94.0, 124.0, 93.0, 93.0, 128.0], "value": 105.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.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, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 68.0, 68.0, 68.0, 66.0, 68.0, 66.0, 66.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 83.0, 83.0, 83.0, 84.0, 88.0, 82.0, 164.0, 84.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 145.0, 133.0, 134.0, 132.0, 132.0, 139.0, 161.0, 135.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 176.0, 176.0, 173.0, 178.0, 174.0, 175.0, 174.0, 173.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 69.0, 72.0, 69.0, 68.0, 69.0, 68.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1593.1000000000001, "alertThreshold": 2.0}]} 12:32:17 INFO - Return code: 0 12:32:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:32:17 ERROR - # TBPL SUCCESS # 12:32:17 INFO - Running post-action listener: _resource_record_post_action 12:32:17 INFO - ##### 12:32:17 INFO - ##### Finished run-tests step (success) 12:32:17 INFO - ##### 12:32:17 INFO - Running post-run listener: _resource_record_post_run 12:32:18 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 529018880; Read time: 16; Write time: 519704 12:32:18 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234790912; Read time: 0; Write time: 277312 12:32:18 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293965824; Read time: 16; Write time: 242356 12:32:18 INFO - Running post-run listener: _upload_blobber_files 12:32:18 INFO - Blob upload gear active. 12:32:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:32:18 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 12:32:18 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'] 12:32:18 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 12:32:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:19 INFO - (blobuploader) - INFO - Done attempting. 12:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:20 INFO - (blobuploader) - INFO - Done attempting. 12:32:20 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:20 INFO - Return code: 0 12:32:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e1cdaded32b00976a20da0efecf122aca097e878d91372c05a29c2a52808b231ba0ca9a8e72289bfacf63b6509e48f63dc0fc41a6f072d34f289d1a08fa4819b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d5a8de9fe27ad4148a5c4b4a2fc934d80b00cab75d379969deb94dd50c2de78ed6bd654973af7d7701dcedd6d3dc31dba37c4f64a4b5243fce341c3a5096d507"} 12:32:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:20 INFO - Contents: 12:32:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e1cdaded32b00976a20da0efecf122aca097e878d91372c05a29c2a52808b231ba0ca9a8e72289bfacf63b6509e48f63dc0fc41a6f072d34f289d1a08fa4819b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d5a8de9fe27ad4148a5c4b4a2fc934d80b00cab75d379969deb94dd50c2de78ed6bd654973af7d7701dcedd6d3dc31dba37c4f64a4b5243fce341c3a5096d507"} 12:32:20 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:20 INFO - Copying logs to upload dir... 12:32:20 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.332513 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-20 12:32:19.201593) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 12:32:19.205180) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 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/e1cdaded32b00976a20da0efecf122aca097e878d91372c05a29c2a52808b231ba0ca9a8e72289bfacf63b6509e48f63dc0fc41a6f072d34f289d1a08fa4819b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d5a8de9fe27ad4148a5c4b4a2fc934d80b00cab75d379969deb94dd50c2de78ed6bd654973af7d7701dcedd6d3dc31dba37c4f64a4b5243fce341c3a5096d507"} build_url:https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/Jx1uzO7aQbCZfxyLN7O9NQ/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/e1cdaded32b00976a20da0efecf122aca097e878d91372c05a29c2a52808b231ba0ca9a8e72289bfacf63b6509e48f63dc0fc41a6f072d34f289d1a08fa4819b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d5a8de9fe27ad4148a5c4b4a2fc934d80b00cab75d379969deb94dd50c2de78ed6bd654973af7d7701dcedd6d3dc31dba37c4f64a4b5243fce341c3a5096d507"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 12:32:19.230365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:32:19.230703) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463769000.408642-1456001799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004610 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:32:19.315489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 12:32:19.316048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 12:32:19.316510) ========= ========= Total master_lag: 0.17 =========