builder: larch_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-025 starttime: 1463778017.82 results: success (0) buildid: 20160520134337 builduid: 0ee45410773548bb873e20b747a7fad9 revision: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:17.824817) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:17.825250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:17.825661) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:17.906261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:17.906669) ========= 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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 14:00:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 99.8M=0s 2016-05-20 14:00:18 (99.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103489 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.036797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.037294) ========= 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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009549 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.095426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.095874) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 894f88d5f1062eb50ac67f4abd48f54d0ee8775b --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 894f88d5f1062eb50ac67f4abd48f54d0ee8775b --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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 14:00:18,946 truncating revision to first 12 chars 2016-05-20 14:00:18,946 Setting DEBUG logging. 2016-05-20 14:00:18,946 attempt 1/10 2016-05-20 14:00:18,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/894f88d5f106?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 14:00:19,207 unpacking tar archive at: larch-894f88d5f106/testing/mozharness/ program finished with exit code 0 elapsedTime=0.510119 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.617798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.618423) ========= script_repo_revision: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.619338) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.619771) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 14:00:18.634381) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-20 14:00:18.634710) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:00:19 INFO - MultiFileLogger online at 20160520 14:00:19 in /builds/slave/test 14:00:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 14:00:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:00:19 INFO - {'append_to_log': False, 14:00:19 INFO - 'base_work_dir': '/builds/slave/test', 14:00:19 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 14:00:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:00:19 INFO - 'branch': 'Larch-Non-PGO', 14:00:19 INFO - 'buildbot_json_path': 'buildprops.json', 14:00:19 INFO - 'config_files': ('talos/linux_config.py',), 14:00:19 INFO - 'default_actions': ('clobber', 14:00:19 INFO - 'read-buildbot-config', 14:00:19 INFO - 'download-and-extract', 14:00:19 INFO - 'populate-webroot', 14:00:19 INFO - 'create-virtualenv', 14:00:19 INFO - 'install', 14:00:19 INFO - 'run-tests'), 14:00:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:00:19 INFO - 'download_minidump_stackwalk': True, 14:00:19 INFO - 'download_symbols': 'ondemand', 14:00:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:00:19 INFO - 'tooltool.py': '/tools/tooltool.py', 14:00:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:00:19 INFO - '/tools/misc-python/virtualenv.py')}, 14:00:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:00:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:00:19 INFO - 'installer_path': 'installer.exe', 14:00:19 INFO - 'log_level': 'info', 14:00:19 INFO - 'log_name': 'talos', 14:00:19 INFO - 'log_to_console': True, 14:00:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:00:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:00:19 INFO - 'opt_config_files': (), 14:00:19 INFO - 'pip_index': False, 14:00:19 INFO - 'sps_profile': False, 14:00:19 INFO - 'sps_profile_interval': 0, 14:00:19 INFO - 'suite': 'dromaeojs-e10s', 14:00:19 INFO - 'system_bits': '32', 14:00:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:00:19 INFO - 'title': 'talos-linux64-ix-025', 14:00:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:00:19 INFO - 'use_talos_json': True, 14:00:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:00:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:00:19 INFO - 'work_dir': 'build'} 14:00:19 INFO - ##### 14:00:19 INFO - ##### Running clobber step. 14:00:19 INFO - ##### 14:00:19 INFO - Running pre-action listener: _resource_record_pre_action 14:00:19 INFO - Running main action method: clobber 14:00:19 INFO - rmtree: /builds/slave/test/build 14:00:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:00:20 INFO - Running post-action listener: _resource_record_post_action 14:00:20 INFO - ##### 14:00:20 INFO - ##### Finished clobber step (success) 14:00:20 INFO - ##### 14:00:20 INFO - ##### 14:00:20 INFO - ##### Running read-buildbot-config step. 14:00:20 INFO - ##### 14:00:20 INFO - Running pre-action listener: _resource_record_pre_action 14:00:20 INFO - Running main action method: read_buildbot_config 14:00:20 INFO - Using buildbot properties: 14:00:20 INFO - { 14:00:20 INFO - "project": "", 14:00:20 INFO - "product": "firefox", 14:00:20 INFO - "script_repo_revision": "production", 14:00:20 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 14:00:20 INFO - "repository": "", 14:00:20 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs-e10s", 14:00:20 INFO - "buildid": "20160520134337", 14:00:20 INFO - "pgo_build": "False", 14:00:20 INFO - "basedir": "/builds/slave/test", 14:00:20 INFO - "buildnumber": 1, 14:00:20 INFO - "slavename": "talos-linux64-ix-025", 14:00:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:00:20 INFO - "platform": "ubuntu64_hw", 14:00:20 INFO - "scheduler": "tests-larch-linux64-talos", 14:00:20 INFO - "branch": "larch", 14:00:20 INFO - "repo_path": "projects/larch", 14:00:20 INFO - "revision": "894f88d5f1062eb50ac67f4abd48f54d0ee8775b", 14:00:20 INFO - "stage_platform": "linux64", 14:00:20 INFO - "builduid": "0ee45410773548bb873e20b747a7fad9", 14:00:20 INFO - "slavebuilddir": "test" 14:00:20 INFO - } 14:00:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:00:20 INFO - Running post-action listener: _resource_record_post_action 14:00:20 INFO - ##### 14:00:20 INFO - ##### Finished read-buildbot-config step (success) 14:00:20 INFO - ##### 14:00:20 INFO - ##### 14:00:20 INFO - ##### Running download-and-extract step. 14:00:20 INFO - ##### 14:00:20 INFO - Running pre-action listener: _resource_record_pre_action 14:00:20 INFO - Running main action method: download_and_extract 14:00:20 INFO - mkdir: /builds/slave/test/build/tests 14:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:20 INFO - https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:00:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:00:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:00:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:00:23 INFO - Downloaded 1361 bytes. 14:00:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:00:23 INFO - Using the following test package requirements: 14:00:23 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:00:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:00:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:00:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'jsshell-linux-x86_64.zip'], 14:00:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:00:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:00:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:00:23 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:00:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:00:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:00:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:23 INFO - https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:00:26 INFO - Downloaded 22329312 bytes. 14:00:26 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'] 14:00:26 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 14:00:27 INFO - Return code: 0 14:00:27 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 14:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:27 INFO - https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:01:11 INFO - Downloaded 22329312 bytes. 14:01:11 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'] 14:01:11 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 14:01:13 INFO - Return code: 0 14:01:13 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')]} 14:01:13 INFO - https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:01:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:01:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:01:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:01:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:01:15 INFO - Downloaded 10840160 bytes. 14:01:15 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'] 14:01:15 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 14:01:15 INFO - Return code: 0 14:01:15 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')]} 14:01:15 INFO - https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:01:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:01:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:01:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:01:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bjn683KhQiy9Zol3VtYh2A/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 14:01:17 INFO - Downloaded 54804122 bytes. 14:01:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:01:17 INFO - mkdir: /builds/slave/test/properties 14:01:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:01:17 INFO - Writing to file /builds/slave/test/properties/build_url 14:01:17 INFO - Contents: 14:01:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:01:18 INFO - Running post-action listener: _resource_record_post_action 14:01:18 INFO - Running post-action listener: set_extra_try_arguments 14:01:18 INFO - ##### 14:01:18 INFO - ##### Finished download-and-extract step (success) 14:01:18 INFO - ##### 14:01:18 INFO - ##### 14:01:18 INFO - ##### Running populate-webroot step. 14:01:18 INFO - ##### 14:01:18 INFO - Running pre-action listener: _resource_record_pre_action 14:01:18 INFO - Running main action method: populate_webroot 14:01:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:01:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:01:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:01:18 INFO - '--tppagecycles', 14:01:18 INFO - '7'], 14:01:18 INFO - 'tests': ['tsvgm']}}, 14:01:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:01:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:01:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:01:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:01:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['damp', 'tps']}, 14:01:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'tests': ['damp', 'tps']}, 14:01:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['dromaeo_dom']}, 14:01:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:01:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'other-e10s': {'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['a11yr', 14:01:18 INFO - 'ts_paint', 14:01:18 INFO - 'tpaint', 14:01:18 INFO - 'sessionrestore', 14:01:18 INFO - 'sessionrestore_no_auto_restore', 14:01:18 INFO - 'tabpaint']}, 14:01:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['tsvgx', 14:01:18 INFO - 'tsvgr_opacity', 14:01:18 INFO - 'tart', 14:01:18 INFO - 'tscrollx', 14:01:18 INFO - 'cart']}, 14:01:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:01:18 INFO - 'tsvgr_opacity', 14:01:18 INFO - 'tart', 14:01:18 INFO - 'tscrollx', 14:01:18 INFO - 'cart']}, 14:01:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'talos_options': ['--disable-e10s'], 14:01:18 INFO - 'tests': ['tp5o']}, 14:01:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'tests': ['tp5o']}, 14:01:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'talos_options': ['--disable-e10s', 14:01:18 INFO - '--xperf_path', 14:01:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:18 INFO - 'tests': ['tp5n']}, 14:01:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:18 INFO - 'talos_options': ['--xperf_path', 14:01:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:18 INFO - 'tests': ['tp5n']}}, 14:01:18 INFO - 'talos.zip': {'path': '', 14:01:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:01:18 INFO - Running post-action listener: _resource_record_post_action 14:01:18 INFO - ##### 14:01:18 INFO - ##### Finished populate-webroot step (success) 14:01:18 INFO - ##### 14:01:18 INFO - ##### 14:01:18 INFO - ##### Running create-virtualenv step. 14:01:18 INFO - ##### 14:01:18 INFO - Running pre-action listener: _resource_record_pre_action 14:01:18 INFO - Running main action method: create_virtualenv 14:01:18 INFO - Creating virtualenv /builds/slave/test/build/venv 14:01:18 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 14:01:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:01:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:01:18 INFO - Using real prefix '/usr' 14:01:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:01:18 INFO - Installing distribute.............................................................................................................................................................................................done. 14:01:20 INFO - Installing pip.................done. 14:01:20 INFO - Return code: 0 14:01:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:20 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:20 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 14:01:20 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 14:01:20 INFO - Using env: {'DISPLAY': ':0', 14:01:20 INFO - 'HOME': '/home/cltbld', 14:01:20 INFO - 'LANG': 'en_US.UTF-8', 14:01:20 INFO - 'LANGUAGE': 'en_US:en', 14:01:20 INFO - 'LOGNAME': 'cltbld', 14:01:20 INFO - 'MAIL': '/var/mail/cltbld', 14:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:20 INFO - 'MOZ_NO_REMOTE': '1', 14:01:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:20 INFO - 'NO_EM_RESTART': '1', 14:01:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:20 INFO - 'PWD': '/builds/slave/test', 14:01:20 INFO - 'SHELL': '/bin/bash', 14:01:20 INFO - 'SHLVL': '1', 14:01:20 INFO - 'TERM': 'linux', 14:01:20 INFO - 'TMOUT': '86400', 14:01:20 INFO - 'USER': 'cltbld', 14:01:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:20 INFO - '_': '/tools/buildbot/bin/python'} 14:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:21 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 14:01:21 INFO - Cleaning up... 14:01:21 INFO - Return code: 0 14:01:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:01:21 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')]} 14:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:21 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')]} 14:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:21 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:21 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 14:01:21 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 14:01:21 INFO - Using env: {'DISPLAY': ':0', 14:01:21 INFO - 'HOME': '/home/cltbld', 14:01:21 INFO - 'LANG': 'en_US.UTF-8', 14:01:21 INFO - 'LANGUAGE': 'en_US:en', 14:01:21 INFO - 'LOGNAME': 'cltbld', 14:01:21 INFO - 'MAIL': '/var/mail/cltbld', 14:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:21 INFO - 'MOZ_NO_REMOTE': '1', 14:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:21 INFO - 'NO_EM_RESTART': '1', 14:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:21 INFO - 'PWD': '/builds/slave/test', 14:01:21 INFO - 'SHELL': '/bin/bash', 14:01:21 INFO - 'SHLVL': '1', 14:01:21 INFO - 'TERM': 'linux', 14:01:21 INFO - 'TMOUT': '86400', 14:01:21 INFO - 'USER': 'cltbld', 14:01:21 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:21 INFO - '_': '/tools/buildbot/bin/python'} 14:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:21 INFO - Downloading/unpacking psutil>=0.7.1 14:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:01:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:23 INFO - Installing collected packages: psutil 14:01:23 INFO - Running setup.py install for psutil 14:01:23 INFO - building 'psutil._psutil_linux' extension 14:01:23 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 14:01:23 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 14:01:23 INFO - building 'psutil._psutil_posix' extension 14:01:23 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 14:01:23 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 14:01:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:23 INFO - Successfully installed psutil 14:01:23 INFO - Cleaning up... 14:01:23 INFO - Return code: 0 14:01:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:23 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 14:01:23 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 14:01:23 INFO - Using env: {'DISPLAY': ':0', 14:01:23 INFO - 'HOME': '/home/cltbld', 14:01:23 INFO - 'LANG': 'en_US.UTF-8', 14:01:23 INFO - 'LANGUAGE': 'en_US:en', 14:01:23 INFO - 'LOGNAME': 'cltbld', 14:01:23 INFO - 'MAIL': '/var/mail/cltbld', 14:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:23 INFO - 'MOZ_NO_REMOTE': '1', 14:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:23 INFO - 'NO_EM_RESTART': '1', 14:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:23 INFO - 'PWD': '/builds/slave/test', 14:01:23 INFO - 'SHELL': '/bin/bash', 14:01:23 INFO - 'SHLVL': '1', 14:01:23 INFO - 'TERM': 'linux', 14:01:23 INFO - 'TMOUT': '86400', 14:01:23 INFO - 'USER': 'cltbld', 14:01:23 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:23 INFO - '_': '/tools/buildbot/bin/python'} 14:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:01:23 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 14:01:23 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 14:01:23 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 14:01:23 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 14:01:23 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 14:01:23 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 14:01:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:01:25 INFO - Installing collected packages: mozsystemmonitor 14:01:25 INFO - Running setup.py install for mozsystemmonitor 14:01:25 INFO - Successfully installed mozsystemmonitor 14:01:25 INFO - Cleaning up... 14:01:25 INFO - Return code: 0 14:01:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:25 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:25 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 14:01:25 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 14:01:25 INFO - Using env: {'DISPLAY': ':0', 14:01:25 INFO - 'HOME': '/home/cltbld', 14:01:25 INFO - 'LANG': 'en_US.UTF-8', 14:01:25 INFO - 'LANGUAGE': 'en_US:en', 14:01:25 INFO - 'LOGNAME': 'cltbld', 14:01:25 INFO - 'MAIL': '/var/mail/cltbld', 14:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:25 INFO - 'MOZ_NO_REMOTE': '1', 14:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:25 INFO - 'NO_EM_RESTART': '1', 14:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:25 INFO - 'PWD': '/builds/slave/test', 14:01:25 INFO - 'SHELL': '/bin/bash', 14:01:25 INFO - 'SHLVL': '1', 14:01:25 INFO - 'TERM': 'linux', 14:01:25 INFO - 'TMOUT': '86400', 14:01:25 INFO - 'USER': 'cltbld', 14:01:25 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:25 INFO - '_': '/tools/buildbot/bin/python'} 14:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:26 INFO - Downloading/unpacking blobuploader==1.2.4 14:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:28 INFO - Downloading blobuploader-1.2.4.tar.gz 14:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:01:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:01:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 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 14:01:28 INFO - Downloading docopt-0.6.1.tar.gz 14:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:01:28 INFO - Installing collected packages: blobuploader, requests, docopt 14:01:28 INFO - Running setup.py install for blobuploader 14:01:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:01:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:01:28 INFO - Running setup.py install for requests 14:01:29 INFO - Running setup.py install for docopt 14:01:29 INFO - Successfully installed blobuploader requests docopt 14:01:29 INFO - Cleaning up... 14:01:29 INFO - Return code: 0 14:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:29 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:29 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 14:01:29 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 14:01:29 INFO - Using env: {'DISPLAY': ':0', 14:01:29 INFO - 'HOME': '/home/cltbld', 14:01:29 INFO - 'LANG': 'en_US.UTF-8', 14:01:29 INFO - 'LANGUAGE': 'en_US:en', 14:01:29 INFO - 'LOGNAME': 'cltbld', 14:01:29 INFO - 'MAIL': '/var/mail/cltbld', 14:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:29 INFO - 'MOZ_NO_REMOTE': '1', 14:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:29 INFO - 'NO_EM_RESTART': '1', 14:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:29 INFO - 'PWD': '/builds/slave/test', 14:01:29 INFO - 'SHELL': '/bin/bash', 14:01:29 INFO - 'SHLVL': '1', 14:01:29 INFO - 'TERM': 'linux', 14:01:29 INFO - 'TMOUT': '86400', 14:01:29 INFO - 'USER': 'cltbld', 14:01:29 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:29 INFO - '_': '/tools/buildbot/bin/python'} 14:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:29 INFO - Running setup.py (path:/tmp/pip-Zffd_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:29 INFO - Running setup.py (path:/tmp/pip-amqLlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:29 INFO - Running setup.py (path:/tmp/pip-bJzBWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:29 INFO - Running setup.py (path:/tmp/pip-jA3wXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:29 INFO - Running setup.py (path:/tmp/pip-2luX1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:29 INFO - Running setup.py (path:/tmp/pip-mFthdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:29 INFO - Running setup.py (path:/tmp/pip-r5VDDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:29 INFO - Running setup.py (path:/tmp/pip-F2tNCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:29 INFO - Running setup.py (path:/tmp/pip-p6irVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:29 INFO - Running setup.py (path:/tmp/pip-NHoZzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:30 INFO - Running setup.py (path:/tmp/pip-SwS2_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:30 INFO - Running setup.py (path:/tmp/pip-Sb5an0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:30 INFO - Running setup.py (path:/tmp/pip-85H2Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:30 INFO - Running setup.py (path:/tmp/pip-atSjQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:30 INFO - Running setup.py (path:/tmp/pip-rCYFO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:30 INFO - Running setup.py (path:/tmp/pip-otvZlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:30 INFO - Running setup.py (path:/tmp/pip-TOt9gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:01:30 INFO - Running setup.py install for manifestparser 14:01:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:01:30 INFO - Running setup.py install for mozcrash 14:01:30 INFO - Running setup.py install for mozdebug 14:01:30 INFO - Running setup.py install for mozdevice 14:01:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:01:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:01:30 INFO - Running setup.py install for mozfile 14:01:30 INFO - Running setup.py install for mozhttpd 14:01:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:01:30 INFO - Running setup.py install for mozinfo 14:01:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for mozInstall 14:01:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for mozleak 14:01:31 INFO - Running setup.py install for mozlog 14:01:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for moznetwork 14:01:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for mozprocess 14:01:31 INFO - Running setup.py install for mozprofile 14:01:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for mozrunner 14:01:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:01:31 INFO - Running setup.py install for mozscreenshot 14:01:31 INFO - Running setup.py install for moztest 14:01:31 INFO - Running setup.py install for mozversion 14:01:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:01:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:01:32 INFO - Cleaning up... 14:01:32 INFO - Return code: 0 14:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:32 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:32 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 14:01:32 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 14:01:32 INFO - Using env: {'DISPLAY': ':0', 14:01:32 INFO - 'HOME': '/home/cltbld', 14:01:32 INFO - 'LANG': 'en_US.UTF-8', 14:01:32 INFO - 'LANGUAGE': 'en_US:en', 14:01:32 INFO - 'LOGNAME': 'cltbld', 14:01:32 INFO - 'MAIL': '/var/mail/cltbld', 14:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:32 INFO - 'MOZ_NO_REMOTE': '1', 14:01:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:32 INFO - 'NO_EM_RESTART': '1', 14:01:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:32 INFO - 'PWD': '/builds/slave/test', 14:01:32 INFO - 'SHELL': '/bin/bash', 14:01:32 INFO - 'SHLVL': '1', 14:01:32 INFO - 'TERM': 'linux', 14:01:32 INFO - 'TMOUT': '86400', 14:01:32 INFO - 'USER': 'cltbld', 14:01:32 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:32 INFO - '_': '/tools/buildbot/bin/python'} 14:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:32 INFO - Running setup.py (path:/tmp/pip-1Neq5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:32 INFO - Running setup.py (path:/tmp/pip-IFKd0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:32 INFO - Running setup.py (path:/tmp/pip-HY5xiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:32 INFO - Running setup.py (path:/tmp/pip-GKolOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:32 INFO - Running setup.py (path:/tmp/pip-V7KTBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:32 INFO - Running setup.py (path:/tmp/pip-QDjS8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:32 INFO - Running setup.py (path:/tmp/pip-EdTNM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:32 INFO - Running setup.py (path:/tmp/pip-FrZUxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:32 INFO - Running setup.py (path:/tmp/pip-y1fqH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:32 INFO - Running setup.py (path:/tmp/pip-PySPGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:32 INFO - Running setup.py (path:/tmp/pip-6Krd2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:32 INFO - Running setup.py (path:/tmp/pip-3rPA6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:32 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)) 14:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:32 INFO - Running setup.py (path:/tmp/pip-b7TCB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:33 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)) 14:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:33 INFO - Running setup.py (path:/tmp/pip-ldTIht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:33 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)) 14:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:33 INFO - Running setup.py (path:/tmp/pip-GH3TQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:33 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)) 14:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:33 INFO - Running setup.py (path:/tmp/pip-QsdiGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:33 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)) 14:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:33 INFO - Running setup.py (path:/tmp/pip-4WxJqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:33 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)) 14:01:33 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)) 14:01:33 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)) 14:01:33 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)) 14:01:33 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)) 14:01:33 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)) 14:01:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:01:33 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 14:01:33 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 14:01:33 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 14:01:33 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 14:01:33 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 14:01:33 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 14:01:35 INFO - Downloading blessings-1.6.tar.gz 14:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:01:35 INFO - Installing collected packages: blessings 14:01:35 INFO - Running setup.py install for blessings 14:01:35 INFO - Successfully installed blessings 14:01:35 INFO - Cleaning up... 14:01:35 INFO - Return code: 0 14:01:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:01:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:35 INFO - Reading from file tmpfile_stdout 14:01:35 INFO - Current package versions: 14:01:35 INFO - argparse == 1.2.1 14:01:35 INFO - blessings == 1.6 14:01:35 INFO - blobuploader == 1.2.4 14:01:35 INFO - docopt == 0.6.1 14:01:35 INFO - manifestparser == 1.1 14:01:35 INFO - mozInstall == 1.12 14:01:35 INFO - mozcrash == 0.17 14:01:35 INFO - mozdebug == 0.1 14:01:35 INFO - mozdevice == 0.48 14:01:35 INFO - mozfile == 1.2 14:01:35 INFO - mozhttpd == 0.7 14:01:35 INFO - mozinfo == 0.9 14:01:35 INFO - mozleak == 0.1 14:01:35 INFO - mozlog == 3.1 14:01:35 INFO - moznetwork == 0.27 14:01:35 INFO - mozprocess == 0.22 14:01:35 INFO - mozprofile == 0.28 14:01:35 INFO - mozrunner == 6.11 14:01:35 INFO - mozscreenshot == 0.1 14:01:35 INFO - mozsystemmonitor == 0.0 14:01:35 INFO - moztest == 0.7 14:01:35 INFO - mozversion == 1.4 14:01:35 INFO - psutil == 3.1.1 14:01:35 INFO - requests == 1.2.3 14:01:35 INFO - wsgiref == 0.1.2 14:01:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:35 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:35 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 14:01:35 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 14:01:35 INFO - Using env: {'DISPLAY': ':0', 14:01:35 INFO - 'HOME': '/home/cltbld', 14:01:35 INFO - 'LANG': 'en_US.UTF-8', 14:01:35 INFO - 'LANGUAGE': 'en_US:en', 14:01:35 INFO - 'LOGNAME': 'cltbld', 14:01:35 INFO - 'MAIL': '/var/mail/cltbld', 14:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:35 INFO - 'MOZ_NO_REMOTE': '1', 14:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:35 INFO - 'NO_EM_RESTART': '1', 14:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:35 INFO - 'PWD': '/builds/slave/test', 14:01:35 INFO - 'SHELL': '/bin/bash', 14:01:35 INFO - 'SHLVL': '1', 14:01:35 INFO - 'TERM': 'linux', 14:01:35 INFO - 'TMOUT': '86400', 14:01:35 INFO - 'USER': 'cltbld', 14:01:35 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:35 INFO - '_': '/tools/buildbot/bin/python'} 14:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 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)) 14:01:35 INFO - Cleaning up... 14:01:35 INFO - Return code: 0 14:01:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:35 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 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:35 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 14:01:35 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 14:01:35 INFO - Using env: {'DISPLAY': ':0', 14:01:35 INFO - 'HOME': '/home/cltbld', 14:01:35 INFO - 'LANG': 'en_US.UTF-8', 14:01:35 INFO - 'LANGUAGE': 'en_US:en', 14:01:35 INFO - 'LOGNAME': 'cltbld', 14:01:35 INFO - 'MAIL': '/var/mail/cltbld', 14:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:35 INFO - 'MOZ_NO_REMOTE': '1', 14:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:35 INFO - 'NO_EM_RESTART': '1', 14:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:35 INFO - 'PWD': '/builds/slave/test', 14:01:35 INFO - 'SHELL': '/bin/bash', 14:01:35 INFO - 'SHLVL': '1', 14:01:35 INFO - 'TERM': 'linux', 14:01:35 INFO - 'TMOUT': '86400', 14:01:35 INFO - 'USER': 'cltbld', 14:01:35 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:35 INFO - '_': '/tools/buildbot/bin/python'} 14:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:35 INFO - Downloading/unpacking jsonschema 14:01:35 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 14:01:35 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 14:01:35 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 14:01:35 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 14:01:35 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 14:01:35 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 14:01:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:37 INFO - Downloading/unpacking functools32 (from jsonschema) 14:01:37 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 14:01:37 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 14:01:37 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 14:01:37 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 14:01:37 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 14:01:37 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 14:01:38 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:38 INFO - warning: no files found matching '*.txt' 14:01:38 INFO - no previously-included directories found matching 'build' 14:01:38 INFO - no previously-included directories found matching 'dist' 14:01:38 INFO - no previously-included directories found matching '.git*' 14:01:38 INFO - Installing collected packages: jsonschema, functools32 14:01:38 INFO - Running setup.py install for functools32 14:01:38 INFO - warning: no files found matching '*.txt' 14:01:38 INFO - no previously-included directories found matching 'build' 14:01:38 INFO - no previously-included directories found matching 'dist' 14:01:38 INFO - no previously-included directories found matching '.git*' 14:01:38 INFO - Successfully installed jsonschema functools32 14:01:38 INFO - Cleaning up... 14:01:38 INFO - Return code: 0 14:01:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1042bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, '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': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', '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 14:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:01:38 INFO - Using env: {'DISPLAY': ':0', 14:01:38 INFO - 'HOME': '/home/cltbld', 14:01:38 INFO - 'LANG': 'en_US.UTF-8', 14:01:38 INFO - 'LANGUAGE': 'en_US:en', 14:01:38 INFO - 'LOGNAME': 'cltbld', 14:01:38 INFO - 'MAIL': '/var/mail/cltbld', 14:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:38 INFO - 'MOZ_NO_REMOTE': '1', 14:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:38 INFO - 'NO_EM_RESTART': '1', 14:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:38 INFO - 'PWD': '/builds/slave/test', 14:01:38 INFO - 'SHELL': '/bin/bash', 14:01:38 INFO - 'SHLVL': '1', 14:01:38 INFO - 'TERM': 'linux', 14:01:38 INFO - 'TMOUT': '86400', 14:01:38 INFO - 'USER': 'cltbld', 14:01:38 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:38 INFO - '_': '/tools/buildbot/bin/python'} 14:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:38 INFO - Downloading/unpacking flake8 14:01:38 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 14:01:38 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 14:01:38 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 14:01:38 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 14:01:38 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 14:01:38 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 14:01:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:01:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:01:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 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 14:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:01:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:01:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:01:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:01:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:01:40 INFO - no previously-included directories found matching 'docs/_build' 14:01:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:01:41 INFO - Running setup.py install for pep8 14:01:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:01:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:01:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:01:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:01:41 INFO - no previously-included directories found matching 'docs/_build' 14:01:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:01:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:01:41 INFO - Cleaning up... 14:01:41 INFO - Return code: 0 14:01:41 INFO - Running post-action listener: _resource_record_post_action 14:01:41 INFO - Running post-action listener: _start_resource_monitoring 14:01:41 INFO - Starting resource monitoring. 14:01:41 INFO - ##### 14:01:41 INFO - ##### Finished create-virtualenv step (success) 14:01:41 INFO - ##### 14:01:41 INFO - ##### 14:01:41 INFO - ##### Running install step. 14:01:41 INFO - ##### 14:01:41 INFO - Running pre-action listener: _resource_record_pre_action 14:01:41 INFO - Running main action method: install 14:01:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:41 INFO - Reading from file tmpfile_stdout 14:01:41 INFO - Detecting whether we're running mozinstall >=1.0... 14:01:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:01:41 INFO - Reading from file tmpfile_stdout 14:01:41 INFO - Output received: 14:01:41 INFO - Usage: mozinstall [options] installer 14:01:41 INFO - Options: 14:01:41 INFO - -h, --help show this help message and exit 14:01:41 INFO - -d DEST, --destination=DEST 14:01:41 INFO - Directory to install application into. [default: 14:01:41 INFO - "/builds/slave/test"] 14:01:41 INFO - --app=APP Application being installed. [default: firefox] 14:01:41 INFO - mkdir: /builds/slave/test/build/application 14:01:41 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'] 14:01:41 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 14:01:57 INFO - Reading from file tmpfile_stdout 14:01:57 INFO - Output received: 14:01:57 INFO - /builds/slave/test/build/application/firefox/firefox 14:01:57 INFO - Running post-action listener: _resource_record_post_action 14:01:57 INFO - ##### 14:01:57 INFO - ##### Finished install step (success) 14:01:57 INFO - ##### 14:01:57 INFO - ##### 14:01:57 INFO - ##### Running run-tests step. 14:01:57 INFO - ##### 14:01:57 INFO - Running pre-action listener: _resource_record_pre_action 14:01:57 INFO - Running main action method: run_tests 14:01:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:01:57 INFO - Python 2.7.3 14:01:57 INFO - Return code: 0 14:01:57 INFO - grabbing minidump binary from tooltool 14:01: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')]} 14:01:57 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 0x152e9c0>, '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 0x152f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152fd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:01:57 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 14:01:57 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 14:01:57 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 14:01:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:01:57 INFO - Return code: 0 14:01:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:01:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:01:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:01:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:01:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:01:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:01:58 INFO - Return code: 0 14:01:58 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:01:58 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:01:58 INFO - Using env: {'DISPLAY': ':0', 14:01:58 INFO - 'HOME': '/home/cltbld', 14:01:58 INFO - 'LANG': 'en_US.UTF-8', 14:01:58 INFO - 'LANGUAGE': 'en_US:en', 14:01:58 INFO - 'LOGNAME': 'cltbld', 14:01:58 INFO - 'MAIL': '/var/mail/cltbld', 14:01:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:01:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:58 INFO - 'MOZ_NO_REMOTE': '1', 14:01:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:58 INFO - 'NO_EM_RESTART': '1', 14:01:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:58 INFO - 'PWD': '/builds/slave/test', 14:01:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:01:58 INFO - 'SHELL': '/bin/bash', 14:01:58 INFO - 'SHLVL': '1', 14:01:58 INFO - 'TERM': 'linux', 14:01:58 INFO - 'TMOUT': '86400', 14:01:58 INFO - 'USER': 'cltbld', 14:01:58 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1463775315.315656-973653729', 14:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:58 INFO - '_': '/tools/buildbot/bin/python'} 14:01:58 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:01:58 INFO - mozversion application_buildid: 20160520134337 14:01:58 INFO - mozversion application_changeset: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b 14:01:58 INFO - mozversion application_display_name: Nightly 14:01:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:01:58 INFO - mozversion application_name: Firefox 14:01:58 INFO - mozversion application_remotingname: firefox 14:01:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:01:58 INFO - mozversion application_vendor: Mozilla 14:01:58 INFO - mozversion application_version: 49.0a1 14:01:58 INFO - mozversion platform_buildid: 20160520134337 14:01:58 INFO - mozversion platform_changeset: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b 14:01:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:01:58 INFO - mozversion platform_version: 49.0a1 14:01:58 INFO - using testdate: 1463778118 14:01:58 INFO - actual date: 1463778118 14:01:58 INFO - starting webserver on 'localhost:40755' 14:01:58 INFO - SUITE-START | Running 2 tests 14:01:58 INFO - TEST-START | dromaeo_css 14:01:58 INFO - operating with platform_type : linux_ 14:01:58 INFO - Initialising browser for dromaeo_css test... 14:01:58 INFO - TEST-INFO | started process 26001 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprwsTDk/profile http://localhost:40755/getInfo.html) 14:02:02 INFO - PROCESS | 26001 | 14:02:02 INFO - PROCESS | 26001 | (process:26068): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:02:03 INFO - PROCESS | 26001 | __metrics Screen width/height:1600/1200 14:02:03 INFO - PROCESS | 26001 | colorDepth:24 14:02:03 INFO - PROCESS | 26001 | Browser inner width/height: 1024/697 14:02:03 INFO - PROCESS | 26001 | __metrics 14:02:03 ERROR - PROCESS | 26001 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:02:04 INFO - TEST-INFO | 26001: exit 0 14:02:04 INFO - Browser initialized. 14:02:04 INFO - Running cycle 1/1 for dromaeo_css test... 14:02:04 INFO - TEST-INFO | started process 26119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprwsTDk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:06 INFO - PROCESS | 26119 | 14:02:06 INFO - PROCESS | 26119 | (process:26180): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:02:16 INFO - PROCESS | 26119 | RSS: Main: 164188160 14:02:16 INFO - PROCESS | 26119 | 14:03:05 INFO - PROCESS | 26119 | 1463778185715 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] 14:05:16 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40755/tests/dromaeo/cssquery-ext.html) 14:05:17 INFO - PROCESS | 26119 | RSS: Main: 166756352 14:05:17 INFO - PROCESS | 26119 | 14:08:19 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-ext.html (next: http://localhost:40755/tests/dromaeo/cssquery-jquery.html) 14:08:20 INFO - PROCESS | 26119 | RSS: Main: 166592512 14:08:20 INFO - PROCESS | 26119 | 14:11:22 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40755/tests/dromaeo/cssquery-mootools.html) 14:11:22 INFO - PROCESS | 26119 | RSS: Main: 167731200 14:11:22 INFO - PROCESS | 26119 | 14:14:24 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40755/tests/dromaeo/cssquery-prototype.html) 14:14:25 INFO - PROCESS | 26119 | RSS: Main: 165249024 14:14:25 INFO - PROCESS | 26119 | 14:17:27 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40755/tests/dromaeo/cssquery-yui.html) 14:17:28 INFO - PROCESS | 26119 | RSS: Main: 166658048 14:17:28 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | Cycle 1(1): loaded http://localhost:40755/tests/dromaeo/cssquery-yui.html (next: http://localhost:40755/tests/dromaeo/cssquery-dojo.html) 14:20:30 INFO - PROCESS | 26119 | RSS: Main: 168497152 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | __start_tp_report 14:20:30 INFO - PROCESS | 26119 | _x_x_mozilla_page_load 14:20:30 INFO - PROCESS | 26119 | _x_x_mozilla_page_load_details 14:20:30 INFO - PROCESS | 26119 | |i|pagename|runs| 14:20:30 INFO - PROCESS | 26119 | |0;dojo.html;2608.5304645163865;2629.3689514516514;2679.41052968347;2688.7580117789407;2689.1260340389376;4643.535646435359;4697.342372067913;4735.124002059617;4743;4827.975860120703;4605.332226827108;4648.418947631546;4675.345451636765;4727.101850648374;4824.565789078983;4258.254805409064;4318.611324980751;4379.75911324877;4381.627561657263;4386.254336762758;587.2847388349359;4331.805068771912;4339.544347843478;4349.217140914634;4369.606735393814;4367.519572847;4410.051838854662;4413.360062790909;4423.64610831136;4436.401085853417;4255.702100852942;4430.534793846632;4442.400275962736;4472.373867658531;4495.348174514677;4257.254980378421;4308.763018034007;4315.6763242756915;4333.501647310568;4378.299472084451;4575.336576196433;4606.516315786827;4637.652176086807;4674.32222327764;4710.246360582325;4945.9258111128365;4968.701877887338;5047.848564543069;5081.66969147008;5087.618428617869;4957.925631115536;4994.300797888262;4997.950020499785;5010.173321401964;5055.140626093573;4671.836485723017;4682.461516925598;4706.694064885772;4708.128996135726;4749.8575042748425;5056.241563765391;5117.641765076483;5125.513076257749;5137.40919794221;5165.1735722285;4934.037862616827;4952.08386448501;4954.355933728664;4969.229769385751;4998.6750861193905;5036.194208926554;5050.823221187241;5069.290299358093;5071.39143302806;5107.514786095389;4918.631102667243;4949.653524253267;4967.801287948441;4993.825216117419;5000.349954505964;4833.637477189225;4976.726280054631;5001.8499445016705;5014.247862820534;5056.949430505648;4204.117135401539;4261.190373828963;4296.720713153698;4340.717853339523;4357.520672725997;3629.6733294003666;3630.8729194478597;3719.739618226752;3723.590405055442;3762.1159027628487;4385.495667998157;4407.471103467605;4415.094905544356;4415.448068991376;4431.667624928144;4989.176785830297;5000.174971129795;5005.399352077701;5006.249062640633;5041.42023667283;4402.119576084732;4440.35614835847;4486.528914463999;4509.098180363941;4516.187086324428;4570.337301091323;4607.032523170172;4621.653375996813;4625.653076019312;4664.416947881515;4664.55686709762;4892.9235568174945;4901.166801643729;4921.409430868319;4947.554720075211;5055.6713813602;5138.229265610188;5174.223866420067;5190.273361729285;5194.454582268807;4893.779779909842;4926.655134140719;4929.556339929423;4932.901341973069;4999.0501804657;4937.308776771183;5052.646314757932;5123.897522049464;5145.819896303611;5154.974225129;5145.845624631267;5151.845444636667;5194.8961020778615;5219.2693022976055;5272;5022.171341728574;5028.597712182944;5055;5065.797368105234;5109.156989096758;4796.2805579163405;4797.2564252540915;4845.224764037737;5037.848864534069;5075.4417014127675;4679.50865159167;4692.131955588227;4693.108309421199;4708.905821883612;4895.143349913822;4859.4654587996;4880.121578115832;4910.631702622389;4918.040982008474;4924.729139897197;4813.4945830688675;4831.492693267156;4842.6368022397755;4843.007183527298;4900.852974410774 14:20:30 INFO - PROCESS | 26119 | |1;ext.html;523.947605239476;566.8582854286428;566.9404712505186;580.4630716587158;599.8740264544446;40226;41498.37752433716;41831.74504764858;41861.790691046575;45895.54104458955;42947;43412.56587434125;43505;43689.34465983013;43743.56256437435;7704.807379815507;7882.172371900954;7891.1714270001685;7895.8420831583335;7905.960470197641;7805.804854878616;7840.529568225925;7850.803729906741;7893.329067029309;7895.763127106167;7916.366690664733;7919.445638805286;7929.444938854283;7929.96910401647;7960.323372513343;7813.687452501893;7829.099653539859;7837.7648670539975;7851.921480785233;7872.921270787305;7794.532328060278;7856.685732570691;7887.684492620288;7907.44647874649;7907.644156012993;39312.21375572501;39322.80338598317;39374.60625393738;39375;39460.802695986626;2793.972060279412;2945.8085224460556;3120.2511397264493;3504.8773292934625;3538.539989801335;28350.574741378896;28429;29018.564721529194;29120.271993200127;29634;3525.5192819016074;3549.716022718176;3570.6429357064344;3640.508531348287;3721.1999420124534;173597;244819;247082;247155.76422117953;248042;33089.172770680925;55344;55372;55945.881082378124;56005.719971399885;33186;33225;33269.83365083159;33383.83308083444;33492.330153397284;12893.35533223335;13037.087403881827;13039.087263891437;13044.869551304366;13051.608451746462;10689.412082335546;10729.927007299208;10746.462676866126;10780.838287425695;10789.082927951213;7756.573388463688;7802.726904558313;7804;7837.255460731221;7882.605869706492;3077.6768439313996;3086.151308390166;3094.9381012379627;3101.953470697941;3122.2194451387154;971.5676523946776;987.5013118375318;1044.2742294105676;1054.615065501102;1068.273573969708;28028.71971280261;28549.286267843054;28866;29120.563191552144;29238;987.7925635616457;997.5311603546321;1027.445179603006;1032.3135115148493;1037.8547003419376;15274.083554986737;15359.385624575116;15474.84525154734;15542.177703394744;15767.29047192879;356631;359816;360157;361667;361869;51134.48865511372;51485.48514514807;51869;52049;52313.47686523162;1218.8354572132648;1234.7839128152716;1235.35144049373;1242.0560374115557;1245.725940293134;27339.726602733717;27950;28230.43539129247;28871;29031.27421814387;44959.32561011653;45378.319325209486;45451.31823022723;45457;45595.77202113968;245872;248655;250559;250715;250788;25878.22365329043;26255.60616590791;26397.73602263953;26457.735422645528;26548.33629159209;43056.35415468833;43151.784241078596;43489.78255108704;44173;44256.55743442653;35232.823835880656;37673.434898476;37972.05069873165;37982.81008594939;40546.797266013484;36823.44764828583;36938;36986.075348116516;37402.87791366263;38218.617813821504;34542.65457345395;36378.818105909304;37413.25173496569;38255.808720956215;38530.80734596309;32441;33716.66283337135;33723.3255334887;33783.66216337805;34107.65892341144;25439.74560254374;26016;26032.739672603788;26367.60448593234;26451.735482644926 14:20:30 INFO - PROCESS | 26119 | |2;jquery.html;383.4459206446685;384.9518810148731;387.62206848322904;402.37832548712237;403.1654475236262;14821.184834834094;16814;17489.475315740518;17494.300227990865;17524.824751752494;16092.75860862088;16130.596735081628;16393.016419014868;16577.41979030734;16593.921395109308;4855.975720121394;5006.674566153189;5058.974705126469;5065.670731402465;5090.0583392072585;3630.8547658093644;5482.753276102585;5822.592418530705;5823.3012038555435;5931.88136237275;5645.943540564604;5855.912161317584;5863.648181109126;5869.9706501467435;5893.204417403638;5833.591648584601;5842.269716285465;5847.912281315784;5849.64902105875;5869.354371019184;4568.588827005552;4900;5191.299174611417;5194.35070616173;5227.477252274742;11931.821022684666;12212.267263964191;12371.319577423239;12493.687657808536;12497.062720295924;1835.7338185963094;1943.931962381324;1964.6267209230343;1979.8416126709828;2009.9447789910414;9307.953460232724;9694.51527423626;10189.949050254701;12540.372981350987;12893.548725794646;1659.4109091272667;1692.2300353339201;1715.3395942562204;1766.4298445761385;1783.1351794379716;65332.3466765331;68048.3195168047;68620;69005.5848045316;69287;9812.165965892837;9922.751931201778;10141.2394070445;10151.492425378701;10293;13876.861231387556;13983.161010339412;14060.507882224074;14128.576142715736;14132;6690.665466726644;6714.280716309902;6742.393184613408;6826.24911259761;6827.180738311435;6226.40849119333;6461.321561236096;6523.347665233438;6533.052707357407;6580.539362244693;4348.869533913988;4407.625351845129;4408.294672852328;4424;4455.131249406335;1667.316400275881;1719.6732620802009;1838.8345048945391;1843.4374922067775;1891.2245979148483;812.9430939834155;828.5732847583499;868.3140319147928;907.2923119966437;939.9389039712397;16190.59523511898;16229.918850405673;16280.592985175464;16513.33946642121;16519.66960660804;874.5758307220992;898.60910503931;909.5997760985146;959.9712008639752;983.095552092077;9049.81900361989;9184.724458266262;9298.581563829644;9423.916249631244;9502.714918552316;392152;395194;399233;399287;404862;77037;78953;79132;79571.60214198891;80063;925.4539821505209;932.5803388475214;944.9102335278137;944.9841420472882;950.0879156010308;14289.714205716036;14627.126689484769;14692.971491995459;14707.41170353174;15022.62443438879;33508.32983340368;34386.484202737476;34959.475607866414;36519.26961460809;37595.436068459545;46582.30126548037;46869.29696054494;48341.75829120973;48840.755796220794;49353.50646493489;7624.504407213513;7632.580208088609;7662.693492260247;7789.454738168274;7847.411444141826;8576.699815506678;11369.31784092957;11623.6512904613;11626.767464650491;11661.6501504955;4469.731816090914;5779.624324419067;6020.608660437058;10073.438617014375;10222.437765922945;9831.606735730491;10075.34510256831;10198;10264.897351026395;10419.531121099417;10125.696229112844;10264.230182736474;10317;10344.844827327446;10420.32267902584;6590.703418346282;7756.92243077562;7831.415632841263;7987.40094492927;8052.557109358807;7834.0599128105;10003.799924001625;10253.48732563354;10373.118284945864;10708.3574985498 14:20:30 INFO - PROCESS | 26119 | |3;mootools.html;1464.7363474574577;1503.9924800375995;1653.4047742812586;1823.2251293200386;1826.296875702854;6824.761133360334;7624.313811756948;7684.807879803007;8180;8200.507969521825;7842.137364889858;8016.478928869619;8118.634661440235;8129.715459958903;8146.551939643317;2227.098025299756;2245.9887700561476;2252.121672547708;2252.3918541993653;2261.852979556332;2188.0481990334256;2189.419803751999;2195.089038049208;2198.120751699325;2202.735671719388;2204.5480676461366;2214.66779983003;2217.1796435318956;2222.0667319725753;2261.7172853393326;2177.68423578581;2184.453886528368;2184.4648061224943;2186.125549780085;2191.901364438596;2116.640171170889;2128.2019242783954;2129.4569884679463;2131.79747923947;2140.743110826695;7949.403794715362;8003.359731221488;8007.67969281228;8035;8050.838983220361;4460.264056430685;4578.336141259499;4587.541245875419;4604.217283061887;4606.216943119644;2904.985475072632;2911.199420159452;3012.819230846156;3020.7734419918593;3028.258076771183;932.5570354081825;969.5346233807812;987.4618333008456;996.4818294486827;998.8501724741346;10888.40113793741;10943.343399396063;10992.120630349553;11002.394868282161;11012.724681883017;4244.087521182961;4293.828246870153;4323.762193079411;4397.81259060052;4405.31717583775;9246.815063698688;9302.116298951589;9305.767355816024;9311.953440232755;9330.440173589606;4448.888777780581;4517.435320584927;4520.932186017212;4549.294859296814;4579.175748365326;3498.1954150545516;3974.9801250994137;4039.2527382434346;4075.3683179107284;4119.258533464005;3759.3984962405884;3795.1081495848453;3875.8255878485534;3920.725549211585;3922.9800251934703;1187.1511869013696;1225.338317308661;1228.9262644241196;1230.2864338683542;1236.8144778283329;817.8294765990615;818.643889907892;824.7031068815222;841.2260720137489;850.7362717557577;5105.33630628016;5556.861078473071;5562.471565201299;5622.775088996476;5684.09054551278;863.1282404771226;938.4041133880036;941.665708673411;942.3921570586933;945.7966537194399;3764.1342491226624;3780.6597406233573;3876.3991581304945;3912;3924.156306394139;9161.312901532412;9357.953210233905;9413.764655883657;9423.340366174301;9482.715518534455;9256.444613323221;9370.10983956523;9379.5310234488;9415.717528474157;9469.47917864524;7255.056842610426;7375.299346562068;7423.183449820407;7494.887576686354;7515.736949206752;5528.751206195811;5595.776168953196;5597.80407685729;5617.466340697627;5750.856228594152;9249.815003700023;9275.62897484093;9310.953445233;9422.340436169676;9532.85700714476;8707.216350528483;9172.541372931193;9287.396319239228;9376.437413755197;9432;5560.165975103767;6227.190465239671;6371.171747672773;6477.222733272038;6496.415322620986;7538.396928245617;7585.886211706718;7663.348615367756;7669.3864490841715;7712;6599.109120268702;6647.634380109141;6784.321567843176;6845.349691779273;6858.828529286808;6762.188537375349;6777;6804.897926531205;6920.273371295942;6975.197852247056;6030.819075427744;6419.935800641933;6429.7749578764515;6463.3213512580505;6513.609183449008;4369.104333611665;4479.3057076153245;4504.076664283749;4550.954490455053;4576.084783043471;4465.677847519337;4561.543845615412;4597.609203217764;4676.5323467654325;4690.132325519789 14:20:30 INFO - PROCESS | 26119 | |4;prototype.html;295.3428621317569;297.3769951950663;297.5744685100307;301.406229727437;303.24189526184523;4767.90464190716;4860.222364421693;4894.363732714746;4901.26481027846;4915.533024362684;4775.904481910368;4777.808887644499;4811.615070794337;4815.518448155182;4825.951740482594;3600.8559657613664;3651.0466900208394;3765.7175711821724;3777.16902281499;3783.1677031053264;3727.217284370272;3729.8321575529167;3775.1317197044696;3776.2825063238033;3784.432335149722;3756.3050835595363;3791.938257287964;3792.2506183017367;3802.3345914464994;3812.237552489499;3522.348365552381;3750.606186350421;3766.9623303766884;3817.179306449099;3821.617838216184;3282.3271229398156;3286.917827054319;3290.555774970372;3298.1589694627955;3302.70275675188;4699.5535424135;4704.341392205094;4704.835330763441;4712.835050773207;4714.929276060861;4317.676174286941;4318.3090705486975;4330.437043184366;4338.197433474817;4341.891452713676;4951.727654978975;4989.700617962933;4996.575291100261;5049.2173713074535;5060.569851562622;3563.7327200460068;3566.5720113586535;3567.4113771227717;3589.74871758977;3605.278944211142;5322.201669749568;5326.588454059677;5339.958708051892;5399.83800485986;5404.729763511808;3377.8648854046055;3555.1645363339594;3559.9644003559633;3674.063113905937;3700.8704695336073;3614.367485689994;3614.6746792788776;3637.3270944875285;3640.1263696713127;3642.7814331140216;4176.7076304658385;4207.873763787091;4208.601097374529;4219.303814870574;4232.6190642841675;4933.138779507877;5072.721000345016;5082.796688132434;5088.313077734532;5113.233015047698;1283.685497053228;1296.546208826903;1299.4802079168219;1308.4439113376777;1312.3635037007043;807.3500831830315;812.9227723366271;813.8128230506898;826.813522595078;830.0164305298241;4468.486124095705;4516.751578663205;4545.36364908913;4552.814648968513;4575.22221222393;5225.895482090413;5232.92150617741;5268.868278293074;5323.760430780624;5340.332458442695;4428.092241090569;4542.863714088583;4554.863354099382;4570.520095390003;4577.7482238477205;2752.3119220194953;2830.448062627809;2830.8818016883215;2843.8720257588457;2845.2175651695948;4938.8765280868265;5009.974950125226;5014.06958321049;5016.899662006739;5017.673851199734;5135;5317.73411329432;5318.760655770499;5323.387810401776;5350.91973620396;4830.516948305211;4922.4093108826455;4927.187014142698;4930.556249937523;4932.457429682732;5177.974110129425;5189.273501709839;5225.529702326809;5239.869003274948;5255.63210575256;4922.384701912261;5067.275552468859;5124.154514505214;5143.8456846294675;5163.4578369270685;4937.530934561211;4944.752762361795;4949.9257511138085;4956.231784073474;4961.925571116364;3671.1372827385007;3713.8143092844707;3728.96271037297;3768.4347347898033;3780.017195529127;5078.4921507850395;5121.667091639031;5211.592869925174;5247.449017853223;5252.632315737862;4971.726555039508;4989.077020751172;4995.375578052744;5000.349954505891;5054.342935418372;5013.924791128063;5053.166227572409;5063.797448102035;5064.366954130734;5074.974625126851;4683.508231635766;4921.778519966539;4954.256861470808;4976.651634385558;5010.348654674869;4936.679115857458;4944.678595891256;5071.54356107952;5080.974595127001;5133.897322053613;4984.277279794482;5006.574441172541;5023.874403139951;5037.974810125926;5039.596832253337 14:20:30 INFO - PROCESS | 26119 | |5;yui.html;764.1670579068816;792.2275781113415;794.6066696984993;796.410656114475;804.6258489802241;12972.351382430876;13453.798193027113;13476.59570212894;13484.730305393887;13504.18974861509;13463.730725385487;13509.932450337734;13518.121322114055;13611.863881361183;13646.931765341184;658.2561705273058;827.6978902700502;832.4797001873828;835.3275613131427;836.1011912194385;748.5396481164072;835.9414840961135;836.8159005018886;838.1618381618382;839.0686338164633;862.5816479007676;869.7869022089596;870.5125129927261;872.16708043818;872.7381785464368;857.5840717252128;859.282499113235;859.9011113721939;862.8662557303628;867.6225841758792;634.3402861024529;636.430394796659;638.5849198021277;639.3702203329716;641.035241960846;12023.879761202363;12117.93941030298;12128.332941688204;12131.332776697278;12143.089268304913;11790.646280611596;11793.882061179364;12048.45781939806;12152.57465988695;12424.53129965045;9212.170904618617;9547.379420337656;9557.856632150524;9582.90417095827;9831.90168098317;3287.882991795169;3305.2397948471744;3309.7683162178655;3355.697987181141;3435.553378060861;42845;42998.35502467465;43319.78340108311;43624.78187609073;44002.779986100184;9324.856492798568;9411.811763764686;9428;9538.52307384628;9631.133198012212;16842.32630694783;16864.915675421544;16872.578185545215;16892.324307027582;16923.915380423266;6541.47668186544;6557;6597.7690780823405;6617.239017512951;6709.463242940553;3690;3701.8704345348324;3704.759190652599;3751.6998640108727;3799.4870692456166;2740.945181096367;2834.971650283512;2842.275219818933;2842.545192769147;2858.1711303722104;510.0614868641643;511.910415677255;518.9429162792089;521.8643152780304;522.1228336394818;531.2880739808675;564.7317524175984;568.8253755993859;570.7631332996843;604.4710877981765;7289.599072051088;7376.651927352734;7409.147947985943;7531.209223031612;7590.848183036419;462.6599449404685;562.7748900439775;565.9021498293298;568.5565259097827;572.481903876992;3443.9311213775586;3615.1142969971997;3632.2190728992864;3959.148783011666;4169.583041695806;53335.73332133392;54611.180832287544;54974.72512637411;55007;55016;48779;49296.507034929906;49627.75186124046;50165.74917125391;51291.743541282056;45064.774676126406;45267.547324526335;45314.54685453169;45450;46419.5358046422;7880.684772609032;7907.525548467111;7958.806179073185;7974.322182614543;8050.919490805017;30060.24849378783;30236.69763302339;30246.54630180505;30373.088807335815;30876;37976.81011594924;39834;39952.2009559813;40409.39385909155;40807.59192408038;10369.585216591553;10668.146548275965;11092.112630989663;11098.389588572705;11150.776984460103;11580.131490138441;11661.76676466483;11758.647240582795;11768.646940591796;12531.060170487068;11476.483558240066;11533.308001519936;11818.645440636794;11884.246301479478;12121.995476125447;11456.19806613529;11537.653870383901;11630.302181869114;11666.183367164216;11950.701232469259;11297.096232301561;11367.658970230576;11397.373144477133;11710.414479275832;11798.469068892093;9487.525623718924;9540.189083927944;9706.223502119954;10029.197664186993;10175.440350780482;8860.778480538038;9241.306901982243;9284.257259419095;9327.533623318943;9339 14:20:30 INFO - PROCESS | 26119 | __end_tp_report 14:20:30 INFO - PROCESS | 26119 | __start_cc_report 14:20:30 INFO - PROCESS | 26119 | _x_x_mozilla_cycle_collect,330 14:20:30 INFO - PROCESS | 26119 | __end_cc_report 14:20:30 INFO - PROCESS | 26119 | __startTimestamp1463779230838__endTimestamp 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | ------- Summary: start ------- 14:20:30 INFO - PROCESS | 26119 | Number of tests: 6 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#0] dojo.html Cycles:165 Average:4660.91 Median:4831.49 stddev:584.02 (12.1%) stddev-sans-first:563.18 14:20:30 INFO - PROCESS | 26119 | Values: 2608.5 2629.4 2679.4 2688.8 2689.1 4643.5 4697.3 4735.1 4743.0 4828.0 4605.3 4648.4 4675.3 4727.1 4824.6 4258.3 4318.6 4379.8 4381.6 4386.3 587.3 4331.8 4339.5 4349.2 4369.6 4367.5 4410.1 4413.4 4423.6 4436.4 4255.7 4430.5 4442.4 4472.4 4495.3 4257.3 4308.8 4315.7 4333.5 4378.3 4575.3 4606.5 4637.7 4674.3 4710.2 4945.9 4968.7 5047.8 5081.7 5087.6 4957.9 4994.3 4998.0 5010.2 5055.1 4671.8 4682.5 4706.7 4708.1 4749.9 5056.2 5117.6 5125.5 5137.4 5165.2 4934.0 4952.1 4954.4 4969.2 4998.7 5036.2 5050.8 5069.3 5071.4 5107.5 4918.6 4949.7 4967.8 4993.8 5000.3 4833.6 4976.7 5001.8 5014.2 5056.9 4204.1 4261.2 4296.7 4340.7 4357.5 3629.7 3630.9 3719.7 3723.6 3762.1 4385.5 4407.5 4415.1 4415.4 4431.7 4989.2 5000.2 5005.4 5006.2 5041.4 4402.1 4440.4 4486.5 4509.1 4516.2 4570.3 4607.0 4621.7 4625.7 4664.4 4664.6 4892.9 4901.2 4921.4 4947.6 5055.7 5138.2 5174.2 5190.3 5194.5 4893.8 4926.7 4929.6 4932.9 4999.1 4937.3 5052.6 5123.9 5145.8 5155.0 5145.8 5151.8 5194.9 5219.3 5272.0 5022.2 5028.6 5055.0 5065.8 5109.2 4796.3 4797.3 4845.2 5037.8 5075.4 4679.5 4692.1 4693.1 4708.9 4895.1 4859.5 4880.1 4910.6 4918.0 4924.7 4813.5 4831.5 4842.6 4843.0 4900.9 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#1] ext.html Cycles:180 Average:43777.84 Median:361768.00 stddev:75359.78 (20.8%) stddev-sans-first:75501.21 14:20:30 INFO - PROCESS | 26119 | Values: 523.9 566.9 566.9 580.5 599.9 40226.0 41498.4 41831.7 41861.8 45895.5 42947.0 43412.6 43505.0 43689.3 43743.6 7704.8 7882.2 7891.2 7895.8 7906.0 7805.8 7840.5 7850.8 7893.3 7895.8 7916.4 7919.4 7929.4 7930.0 7960.3 7813.7 7829.1 7837.8 7851.9 7872.9 7794.5 7856.7 7887.7 7907.4 7907.6 39312.2 39322.8 39374.6 39375.0 39460.8 2794.0 2945.8 3120.3 3504.9 3538.5 28350.6 28429.0 29018.6 29120.3 29634.0 3525.5 3549.7 3570.6 3640.5 3721.2 173597.0 244819.0 247082.0 247155.8 248042.0 33089.2 55344.0 55372.0 55945.9 56005.7 33186.0 33225.0 33269.8 33383.8 33492.3 12893.4 13037.1 13039.1 13044.9 13051.6 10689.4 10729.9 10746.5 10780.8 10789.1 7756.6 7802.7 7804.0 7837.3 7882.6 3077.7 3086.2 3094.9 3102.0 3122.2 971.6 987.5 1044.3 1054.6 1068.3 28028.7 28549.3 28866.0 29120.6 29238.0 987.8 997.5 1027.4 1032.3 1037.9 15274.1 15359.4 15474.8 15542.2 15767.3 356631.0 359816.0 360157.0 361667.0 361869.0 51134.5 51485.5 51869.0 52049.0 52313.5 1218.8 1234.8 1235.4 1242.1 1245.7 27339.7 27950.0 28230.4 28871.0 29031.3 44959.3 45378.3 45451.3 45457.0 45595.8 245872.0 248655.0 250559.0 250715.0 250788.0 25878.2 26255.6 26397.7 26457.7 26548.3 43056.4 43151.8 43489.8 44173.0 44256.6 35232.8 37673.4 37972.1 37982.8 40546.8 36823.4 36938.0 36986.1 37402.9 38218.6 34542.7 36378.8 37413.3 38255.8 38530.8 32441.0 33716.7 33723.3 33783.7 34107.7 25439.7 26016.0 26032.7 26367.6 26451.7 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#2] jquery.html Cycles:180 Average:24071.18 Median:4462.43 stddev:65730.30 (1473.0%) stddev-sans-first:65890.63 14:20:30 INFO - PROCESS | 26119 | Values: 383.4 385.0 387.6 402.4 403.2 14821.2 16814.0 17489.5 17494.3 17524.8 16092.8 16130.6 16393.0 16577.4 16593.9 4856.0 5006.7 5059.0 5065.7 5090.1 3630.9 5482.8 5822.6 5823.3 5931.9 5645.9 5855.9 5863.6 5870.0 5893.2 5833.6 5842.3 5847.9 5849.6 5869.4 4568.6 4900.0 5191.3 5194.4 5227.5 11931.8 12212.3 12371.3 12493.7 12497.1 1835.7 1943.9 1964.6 1979.8 2009.9 9308.0 9694.5 10189.9 12540.4 12893.5 1659.4 1692.2 1715.3 1766.4 1783.1 65332.3 68048.3 68620.0 69005.6 69287.0 9812.2 9922.8 10141.2 10151.5 10293.0 13876.9 13983.2 14060.5 14128.6 14132.0 6690.7 6714.3 6742.4 6826.2 6827.2 6226.4 6461.3 6523.3 6533.1 6580.5 4348.9 4407.6 4408.3 4424.0 4455.1 1667.3 1719.7 1838.8 1843.4 1891.2 812.9 828.6 868.3 907.3 939.9 16190.6 16229.9 16280.6 16513.3 16519.7 874.6 898.6 909.6 960.0 983.1 9049.8 9184.7 9298.6 9423.9 9502.7 392152.0 395194.0 399233.0 399287.0 404862.0 77037.0 78953.0 79132.0 79571.6 80063.0 925.5 932.6 944.9 945.0 950.1 14289.7 14627.1 14693.0 14707.4 15022.6 33508.3 34386.5 34959.5 36519.3 37595.4 46582.3 46869.3 48341.8 48840.8 49353.5 7624.5 7632.6 7662.7 7789.5 7847.4 8576.7 11369.3 11623.7 11626.8 11661.7 4469.7 5779.6 6020.6 10073.4 10222.4 9831.6 10075.3 10198.0 10264.9 10419.5 10125.7 10264.2 10317.0 10344.8 10420.3 6590.7 7756.9 7831.4 7987.4 8052.6 7834.1 10003.8 10253.5 10373.1 10708.4 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#3] mootools.html Cycles:180 Average:5206.26 Median:5596.79 stddev:2900.11 (51.8%) stddev-sans-first:2894.61 14:20:30 INFO - PROCESS | 26119 | Values: 1464.7 1504.0 1653.4 1823.2 1826.3 6824.8 7624.3 7684.8 8180.0 8200.5 7842.1 8016.5 8118.6 8129.7 8146.6 2227.1 2246.0 2252.1 2252.4 2261.9 2188.0 2189.4 2195.1 2198.1 2202.7 2204.5 2214.7 2217.2 2222.1 2261.7 2177.7 2184.5 2184.5 2186.1 2191.9 2116.6 2128.2 2129.5 2131.8 2140.7 7949.4 8003.4 8007.7 8035.0 8050.8 4460.3 4578.3 4587.5 4604.2 4606.2 2905.0 2911.2 3012.8 3020.8 3028.3 932.6 969.5 987.5 996.5 998.9 10888.4 10943.3 10992.1 11002.4 11012.7 4244.1 4293.8 4323.8 4397.8 4405.3 9246.8 9302.1 9305.8 9312.0 9330.4 4448.9 4517.4 4520.9 4549.3 4579.2 3498.2 3975.0 4039.3 4075.4 4119.3 3759.4 3795.1 3875.8 3920.7 3923.0 1187.2 1225.3 1228.9 1230.3 1236.8 817.8 818.6 824.7 841.2 850.7 5105.3 5556.9 5562.5 5622.8 5684.1 863.1 938.4 941.7 942.4 945.8 3764.1 3780.7 3876.4 3912.0 3924.2 9161.3 9358.0 9413.8 9423.3 9482.7 9256.4 9370.1 9379.5 9415.7 9469.5 7255.1 7375.3 7423.2 7494.9 7515.7 5528.8 5595.8 5597.8 5617.5 5750.9 9249.8 9275.6 9311.0 9422.3 9532.9 8707.2 9172.5 9287.4 9376.4 9432.0 5560.2 6227.2 6371.2 6477.2 6496.4 7538.4 7585.9 7663.3 7669.4 7712.0 6599.1 6647.6 6784.3 6845.3 6858.8 6762.2 6777.0 6804.9 6920.3 6975.2 6030.8 6419.9 6429.8 6463.3 6513.6 4369.1 4479.3 4504.1 4551.0 4576.1 4465.7 4561.5 4597.6 4676.5 4690.1 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#4] prototype.html Cycles:180 Average:4205.01 Median:4820.74 stddev:1229.69 (25.5%) stddev-sans-first:1197.62 14:20:30 INFO - PROCESS | 26119 | Values: 295.3 297.4 297.6 301.4 303.2 4767.9 4860.2 4894.4 4901.3 4915.5 4775.9 4777.8 4811.6 4815.5 4826.0 3600.9 3651.0 3765.7 3777.2 3783.2 3727.2 3729.8 3775.1 3776.3 3784.4 3756.3 3791.9 3792.3 3802.3 3812.2 3522.3 3750.6 3767.0 3817.2 3821.6 3282.3 3286.9 3290.6 3298.2 3302.7 4699.6 4704.3 4704.8 4712.8 4714.9 4317.7 4318.3 4330.4 4338.2 4341.9 4951.7 4989.7 4996.6 5049.2 5060.6 3563.7 3566.6 3567.4 3589.7 3605.3 5322.2 5326.6 5340.0 5399.8 5404.7 3377.9 3555.2 3560.0 3674.1 3700.9 3614.4 3614.7 3637.3 3640.1 3642.8 4176.7 4207.9 4208.6 4219.3 4232.6 4933.1 5072.7 5082.8 5088.3 5113.2 1283.7 1296.5 1299.5 1308.4 1312.4 807.4 812.9 813.8 826.8 830.0 4468.5 4516.8 4545.4 4552.8 4575.2 5225.9 5232.9 5268.9 5323.8 5340.3 4428.1 4542.9 4554.9 4570.5 4577.7 2752.3 2830.4 2830.9 2843.9 2845.2 4938.9 5010.0 5014.1 5016.9 5017.7 5135.0 5317.7 5318.8 5323.4 5350.9 4830.5 4922.4 4927.2 4930.6 4932.5 5178.0 5189.3 5225.5 5239.9 5255.6 4922.4 5067.3 5124.2 5143.8 5163.5 4937.5 4944.8 4949.9 4956.2 4961.9 3671.1 3713.8 3729.0 3768.4 3780.0 5078.5 5121.7 5211.6 5247.4 5252.6 4971.7 4989.1 4995.4 5000.3 5054.3 5013.9 5053.2 5063.8 5064.4 5075.0 4683.5 4921.8 4954.3 4976.7 5010.3 4936.7 4944.7 5071.5 5081.0 5133.9 4984.3 5006.6 5023.9 5038.0 5039.6 14:20:30 INFO - PROCESS | 26119 | 14:20:30 INFO - PROCESS | 26119 | [#5] yui.html Cycles:180 Average:13031.55 Median:22956.33 stddev:14938.51 (65.1%) stddev-sans-first:14952.01 14:20:30 INFO - PROCESS | 26119 | Values: 764.2 792.2 794.6 796.4 804.6 12972.4 13453.8 13476.6 13484.7 13504.2 13463.7 13509.9 13518.1 13611.9 13646.9 658.3 827.7 832.5 835.3 836.1 748.5 835.9 836.8 838.2 839.1 862.6 869.8 870.5 872.2 872.7 857.6 859.3 859.9 862.9 867.6 634.3 636.4 638.6 639.4 641.0 12023.9 12117.9 12128.3 12131.3 12143.1 11790.6 11793.9 12048.5 12152.6 12424.5 9212.2 9547.4 9557.9 9582.9 9831.9 3287.9 3305.2 3309.8 3355.7 3435.6 42845.0 42998.4 43319.8 43624.8 44002.8 9324.9 9411.8 9428.0 9538.5 9631.1 16842.3 16864.9 16872.6 16892.3 16923.9 6541.5 6557.0 6597.8 6617.2 6709.5 3690.0 3701.9 3704.8 3751.7 3799.5 2740.9 2835.0 2842.3 2842.5 2858.2 510.1 511.9 518.9 521.9 522.1 531.3 564.7 568.8 570.8 604.5 7289.6 7376.7 7409.1 7531.2 7590.8 462.7 562.8 565.9 568.6 572.5 3443.9 3615.1 3632.2 3959.1 4169.6 53335.7 54611.2 54974.7 55007.0 55016.0 48779.0 49296.5 49627.8 50165.7 51291.7 45064.8 45267.5 45314.5 45450.0 46419.5 7880.7 7907.5 7958.8 7974.3 8050.9 30060.2 30236.7 30246.5 30373.1 30876.0 37976.8 39834.0 39952.2 40409.4 40807.6 10369.6 10668.1 11092.1 11098.4 11150.8 11580.1 11661.8 11758.6 11768.6 12531.1 11476.5 11533.3 11818.6 11884.2 12122.0 11456.2 11537.7 11630.3 11666.2 11950.7 11297.1 11367.7 11397.4 11710.4 11798.5 9487.5 9540.2 9706.2 10029.2 10175.4 8860.8 9241.3 9284.3 9327.5 9339.0 14:20:30 INFO - PROCESS | 26119 | -------- Summary: end -------- 14:20:30 INFO - PROCESS | 26119 | 14:20:31 INFO - TEST-INFO | 26119: exit 0 14:20:31 INFO - TEST-OK | dromaeo_css | took 1112884ms 14:20:31 INFO - TEST-START | kraken 14:20:31 INFO - operating with platform_type : linux_ 14:20:31 INFO - Initialising browser for kraken test... 14:20:31 INFO - TEST-INFO | started process 26359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc23VIj/profile http://localhost:40755/getInfo.html) 14:20:35 INFO - PROCESS | 26359 | 14:20:35 INFO - PROCESS | 26359 | (process:26426): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:20:35 INFO - PROCESS | 26359 | __metrics Screen width/height:1600/1200 14:20:35 INFO - PROCESS | 26359 | colorDepth:24 14:20:35 INFO - PROCESS | 26359 | Browser inner width/height: 1024/697 14:20:35 INFO - PROCESS | 26359 | __metrics 14:20:36 ERROR - PROCESS | 26359 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:20:37 INFO - TEST-INFO | 26359: exit 0 14:20:37 INFO - Browser initialized. 14:20:37 INFO - Running cycle 1/1 for kraken test... 14:20:37 INFO - TEST-INFO | started process 26477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc23VIj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:38 INFO - PROCESS | 26477 | 14:20:38 INFO - PROCESS | 26477 | (process:26540): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:20:48 INFO - PROCESS | 26477 | RSS: Main: 164007936 14:20:48 INFO - PROCESS | 26477 | 14:20:51 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40755/tests/kraken/driver.html?testName=audio-beat-detection) 14:20:52 INFO - PROCESS | 26477 | RSS: Main: 168341504 14:20:52 INFO - PROCESS | 26477 | 14:20:55 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40755/tests/kraken/driver.html?testName=audio-dft) 14:20:55 INFO - PROCESS | 26477 | RSS: Main: 168357888 14:20:55 INFO - PROCESS | 26477 | 14:20:58 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40755/tests/kraken/driver.html?testName=audio-fft) 14:20:59 INFO - PROCESS | 26477 | RSS: Main: 163299328 14:20:59 INFO - PROCESS | 26477 | 14:21:01 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40755/tests/kraken/driver.html?testName=audio-oscillator) 14:21:02 INFO - PROCESS | 26477 | RSS: Main: 163823616 14:21:02 INFO - PROCESS | 26477 | 14:21:04 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40755/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:05 INFO - PROCESS | 26477 | RSS: Main: 164057088 14:21:05 INFO - PROCESS | 26477 | 14:21:08 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40755/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:08 INFO - PROCESS | 26477 | RSS: Main: 164204544 14:21:08 INFO - PROCESS | 26477 | 14:21:12 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40755/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:13 INFO - PROCESS | 26477 | RSS: Main: 163209216 14:21:13 INFO - PROCESS | 26477 | 14:21:16 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40755/tests/kraken/driver.html?testName=json-parse-financial) 14:21:16 INFO - PROCESS | 26477 | RSS: Main: 163647488 14:21:16 INFO - PROCESS | 26477 | 14:21:18 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40755/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:21:19 INFO - PROCESS | 26477 | RSS: Main: 163794944 14:21:19 INFO - PROCESS | 26477 | 14:21:21 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:21:21 INFO - PROCESS | 26477 | RSS: Main: 163655680 14:21:21 INFO - PROCESS | 26477 | 14:21:23 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:24 INFO - PROCESS | 26477 | RSS: Main: 164057088 14:21:24 INFO - PROCESS | 26477 | 14:21:27 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:28 INFO - PROCESS | 26477 | RSS: Main: 164212736 14:21:28 INFO - PROCESS | 26477 | 14:21:31 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:31 INFO - PROCESS | 26477 | RSS: Main: 164016128 14:21:31 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | Cycle 1(1): loaded http://localhost:40755/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40755/tests/kraken/driver.html?testName=ai-astar) 14:21:33 INFO - PROCESS | 26477 | RSS: Main: 165601280 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | __start_tp_report 14:21:33 INFO - PROCESS | 26477 | _x_x_mozilla_page_load 14:21:33 INFO - PROCESS | 26477 | _x_x_mozilla_page_load_details 14:21:33 INFO - PROCESS | 26477 | |i|pagename|runs| 14:21:33 INFO - PROCESS | 26477 | |0;ai-astar;115;117;110;117;117;121;110;116;119;117 14:21:33 INFO - PROCESS | 26477 | |1;audio-beat-detection;160;144;158;216;139;159;164;135;178;136 14:21:33 INFO - PROCESS | 26477 | |2;audio-dft;162;166;166;164;169;172;166;164;163;163 14:21:33 INFO - PROCESS | 26477 | |3;audio-fft;78;78;78;154;78;78;77;78;157;80 14:21:33 INFO - PROCESS | 26477 | |4;audio-oscillator;97;95;168;94;95;94;136;94;94;137 14:21:33 INFO - PROCESS | 26477 | |5;imaging-gaussian-blur;118;119;118;118;119;119;121;119;120;119 14:21:33 INFO - PROCESS | 26477 | |6;imaging-darkroom;150;152;150;150;151;149;150;150;150;149 14:21:33 INFO - PROCESS | 26477 | |7;imaging-desaturate;96;97;97;99;97;97;97;98;98;97 14:21:33 INFO - PROCESS | 26477 | |8;json-parse-financial;69;68;68;69;68;69;68;68;68;67 14:21:33 INFO - PROCESS | 26477 | |9;json-stringify-tinderbox;66;65;66;64;65;65;64;65;69;64 14:21:33 INFO - PROCESS | 26477 | |10;stanford-crypto-aes;86;85;85;84;94;87;87;85;146;91 14:21:33 INFO - PROCESS | 26477 | |11;stanford-crypto-ccm;141;146;140;139;141;144;145;141;163;142 14:21:33 INFO - PROCESS | 26477 | |12;stanford-crypto-pbkdf2;183;182;190;186;179;178;178;180;181;178 14:21:33 INFO - PROCESS | 26477 | |13;stanford-crypto-sha256-iterative;73;72;71;71;72;71;72;71;71;73 14:21:33 INFO - PROCESS | 26477 | __end_tp_report 14:21:33 INFO - PROCESS | 26477 | __start_cc_report 14:21:33 INFO - PROCESS | 26477 | _x_x_mozilla_cycle_collect,821 14:21:33 INFO - PROCESS | 26477 | __end_cc_report 14:21:33 INFO - PROCESS | 26477 | __startTimestamp1463779293710__endTimestamp 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | ------- Summary: start ------- 14:21:33 INFO - PROCESS | 26477 | Number of tests: 14 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:3.51 (3.0%) stddev-sans-first:3.71 14:21:33 INFO - PROCESS | 26477 | Values: 115.0 117.0 110.0 117.0 117.0 121.0 110.0 116.0 119.0 117.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#1] audio-beat-detection Cycles:10 Average:158.90 Median:159.50 stddev:24.42 (15.3%) stddev-sans-first:25.90 14:21:33 INFO - PROCESS | 26477 | Values: 160.0 144.0 158.0 216.0 139.0 159.0 164.0 135.0 178.0 136.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#2] audio-dft Cycles:10 Average:165.50 Median:166.00 stddev:3.06 (1.8%) stddev-sans-first:2.98 14:21:33 INFO - PROCESS | 26477 | Values: 162.0 166.0 166.0 164.0 169.0 172.0 166.0 164.0 163.0 163.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#3] audio-fft Cycles:10 Average:93.60 Median:78.00 stddev:32.64 (41.8%) stddev-sans-first:34.13 14:21:33 INFO - PROCESS | 26477 | Values: 78.0 78.0 78.0 154.0 78.0 78.0 77.0 78.0 157.0 80.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:94.00 stddev:26.69 (28.4%) stddev-sans-first:27.86 14:21:33 INFO - PROCESS | 26477 | Values: 97.0 95.0 168.0 94.0 95.0 94.0 136.0 94.0 94.0 137.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#5] imaging-gaussian-blur Cycles:10 Average:119.00 Median:119.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 14:21:33 INFO - PROCESS | 26477 | Values: 118.0 119.0 118.0 118.0 119.0 119.0 121.0 119.0 120.0 119.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 14:21:33 INFO - PROCESS | 26477 | Values: 150.0 152.0 150.0 150.0 151.0 149.0 150.0 150.0 150.0 149.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:97.00 stddev:0.82 (0.8%) stddev-sans-first:0.73 14:21:33 INFO - PROCESS | 26477 | Values: 96.0 97.0 97.0 99.0 97.0 97.0 97.0 98.0 98.0 97.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 14:21:33 INFO - PROCESS | 26477 | Values: 69.0 68.0 68.0 69.0 68.0 69.0 68.0 68.0 68.0 67.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:1.49 (2.3%) stddev-sans-first:1.56 14:21:33 INFO - PROCESS | 26477 | Values: 66.0 65.0 66.0 64.0 65.0 65.0 64.0 65.0 69.0 64.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:86.50 stddev:18.88 (21.8%) stddev-sans-first:19.85 14:21:33 INFO - PROCESS | 26477 | Values: 86.0 85.0 85.0 84.0 94.0 87.0 87.0 85.0 146.0 91.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:143.00 stddev:6.97 (4.9%) stddev-sans-first:7.30 14:21:33 INFO - PROCESS | 26477 | Values: 141.0 146.0 140.0 139.0 141.0 144.0 145.0 141.0 163.0 142.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:181.50 stddev:3.95 (2.2%) stddev-sans-first:4.15 14:21:33 INFO - PROCESS | 26477 | Values: 183.0 182.0 190.0 186.0 179.0 178.0 178.0 180.0 181.0 178.0 14:21:33 INFO - PROCESS | 26477 | 14:21:33 INFO - PROCESS | 26477 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:0.82 (1.1%) stddev-sans-first:0.73 14:21:33 INFO - PROCESS | 26477 | Values: 73.0 72.0 71.0 71.0 72.0 71.0 72.0 71.0 71.0 73.0 14:21:33 INFO - PROCESS | 26477 | -------- Summary: end -------- 14:21:33 INFO - PROCESS | 26477 | 14:21:34 INFO - TEST-INFO | 26477: exit 0 14:21:34 INFO - TEST-OK | kraken | took 63162ms 14:21:34 INFO - SUITE-END | took 1176s 14:21:34 INFO - Completed test suite (00:19:36) 14:21:34 INFO - javascript benchmark 14:21:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2608.5304645163865, 2629.3689514516514, 2679.41052968347, 2688.7580117789407, 2689.1260340389376, 4643.535646435359, 4697.342372067913, 4735.124002059617, 4743.0, 4827.975860120703, 4605.332226827108, 4648.418947631546, 4675.345451636765, 4727.101850648374, 4824.565789078983, 4258.254805409064, 4318.611324980751, 4379.75911324877, 4381.627561657263, 4386.254336762758, 587.2847388349359, 4331.805068771912, 4339.544347843478, 4349.217140914634, 4369.606735393814, 4367.519572847, 4410.051838854662, 4413.360062790909, 4423.64610831136, 4436.401085853417, 4255.702100852942, 4430.534793846632, 4442.400275962736, 4472.373867658531, 4495.348174514677, 4257.254980378421, 4308.763018034007, 4315.6763242756915, 4333.501647310568, 4378.299472084451, 4575.336576196433, 4606.516315786827, 4637.652176086807, 4674.32222327764, 4710.246360582325, 4945.9258111128365, 4968.701877887338, 5047.848564543069, 5081.66969147008, 5087.618428617869, 4957.925631115536, 4994.300797888262, 4997.950020499785, 5010.173321401964, 5055.140626093573, 4671.836485723017, 4682.461516925598, 4706.694064885772, 4708.128996135726, 4749.8575042748425, 5056.241563765391, 5117.641765076483, 5125.513076257749, 5137.40919794221, 5165.1735722285, 4934.037862616827, 4952.08386448501, 4954.355933728664, 4969.229769385751, 4998.6750861193905, 5036.194208926554, 5050.823221187241, 5069.290299358093, 5071.39143302806, 5107.514786095389, 4918.631102667243, 4949.653524253267, 4967.801287948441, 4993.825216117419, 5000.349954505964, 4833.637477189225, 4976.726280054631, 5001.8499445016705, 5014.247862820534, 5056.949430505648, 4204.117135401539, 4261.190373828963, 4296.720713153698, 4340.717853339523, 4357.520672725997, 3629.6733294003666, 3630.8729194478597, 3719.739618226752, 3723.590405055442, 3762.1159027628487, 4385.495667998157, 4407.471103467605, 4415.094905544356, 4415.448068991376, 4431.667624928144, 4989.176785830297, 5000.174971129795, 5005.399352077701, 5006.249062640633, 5041.42023667283, 4402.119576084732, 4440.35614835847, 4486.528914463999, 4509.098180363941, 4516.187086324428, 4570.337301091323, 4607.032523170172, 4621.653375996813, 4625.653076019312, 4664.416947881515, 4664.55686709762, 4892.9235568174945, 4901.166801643729, 4921.409430868319, 4947.554720075211, 5055.6713813602, 5138.229265610188, 5174.223866420067, 5190.273361729285, 5194.454582268807, 4893.779779909842, 4926.655134140719, 4929.556339929423, 4932.901341973069, 4999.0501804657, 4937.308776771183, 5052.646314757932, 5123.897522049464, 5145.819896303611, 5154.974225129, 5145.845624631267, 5151.845444636667, 5194.8961020778615, 5219.2693022976055, 5272.0, 5022.171341728574, 5028.597712182944, 5055.0, 5065.797368105234, 5109.156989096758, 4796.2805579163405, 4797.2564252540915, 4845.224764037737, 5037.848864534069, 5075.4417014127675, 4679.50865159167, 4692.131955588227, 4693.108309421199, 4708.905821883612, 4895.143349913822, 4859.4654587996, 4880.121578115832, 4910.631702622389, 4918.040982008474, 4924.729139897197, 4813.4945830688675, 4831.492693267156, 4842.6368022397755, 4843.007183527298, 4900.852974410774], "value": 4625.870197300602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.947605239476, 566.8582854286428, 566.9404712505186, 580.4630716587158, 599.8740264544446, 40226.0, 41498.37752433716, 41831.74504764858, 41861.790691046575, 45895.54104458955, 42947.0, 43412.56587434125, 43505.0, 43689.34465983013, 43743.56256437435, 7704.807379815507, 7882.172371900954, 7891.1714270001685, 7895.8420831583335, 7905.960470197641, 7805.804854878616, 7840.529568225925, 7850.803729906741, 7893.329067029309, 7895.763127106167, 7916.366690664733, 7919.445638805286, 7929.444938854283, 7929.96910401647, 7960.323372513343, 7813.687452501893, 7829.099653539859, 7837.7648670539975, 7851.921480785233, 7872.921270787305, 7794.532328060278, 7856.685732570691, 7887.684492620288, 7907.44647874649, 7907.644156012993, 39312.21375572501, 39322.80338598317, 39374.60625393738, 39375.0, 39460.802695986626, 2793.972060279412, 2945.8085224460556, 3120.2511397264493, 3504.8773292934625, 3538.539989801335, 28350.574741378896, 28429.0, 29018.564721529194, 29120.271993200127, 29634.0, 3525.5192819016074, 3549.716022718176, 3570.6429357064344, 3640.508531348287, 3721.1999420124534, 173597.0, 244819.0, 247082.0, 247155.76422117953, 248042.0, 33089.172770680925, 55344.0, 55372.0, 55945.881082378124, 56005.719971399885, 33186.0, 33225.0, 33269.83365083159, 33383.83308083444, 33492.330153397284, 12893.35533223335, 13037.087403881827, 13039.087263891437, 13044.869551304366, 13051.608451746462, 10689.412082335546, 10729.927007299208, 10746.462676866126, 10780.838287425695, 10789.082927951213, 7756.573388463688, 7802.726904558313, 7804.0, 7837.255460731221, 7882.605869706492, 3077.6768439313996, 3086.151308390166, 3094.9381012379627, 3101.953470697941, 3122.2194451387154, 971.5676523946776, 987.5013118375318, 1044.2742294105676, 1054.615065501102, 1068.273573969708, 28028.71971280261, 28549.286267843054, 28866.0, 29120.563191552144, 29238.0, 987.7925635616457, 997.5311603546321, 1027.445179603006, 1032.3135115148493, 1037.8547003419376, 15274.083554986737, 15359.385624575116, 15474.84525154734, 15542.177703394744, 15767.29047192879, 356631.0, 359816.0, 360157.0, 361667.0, 361869.0, 51134.48865511372, 51485.48514514807, 51869.0, 52049.0, 52313.47686523162, 1218.8354572132648, 1234.7839128152716, 1235.35144049373, 1242.0560374115557, 1245.725940293134, 27339.726602733717, 27950.0, 28230.43539129247, 28871.0, 29031.27421814387, 44959.32561011653, 45378.319325209486, 45451.31823022723, 45457.0, 45595.77202113968, 245872.0, 248655.0, 250559.0, 250715.0, 250788.0, 25878.22365329043, 26255.60616590791, 26397.73602263953, 26457.735422645528, 26548.33629159209, 43056.35415468833, 43151.784241078596, 43489.78255108704, 44173.0, 44256.55743442653, 35232.823835880656, 37673.434898476, 37972.05069873165, 37982.81008594939, 40546.797266013484, 36823.44764828583, 36938.0, 36986.075348116516, 37402.87791366263, 38218.617813821504, 34542.65457345395, 36378.818105909304, 37413.25173496569, 38255.808720956215, 38530.80734596309, 32441.0, 33716.66283337135, 33723.3255334887, 33783.66216337805, 34107.65892341144, 25439.74560254374, 26016.0, 26032.739672603788, 26367.60448593234, 26451.735482644926], "value": 16676.904548353148, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.4459206446685, 384.9518810148731, 387.62206848322904, 402.37832548712237, 403.1654475236262, 14821.184834834094, 16814.0, 17489.475315740518, 17494.300227990865, 17524.824751752494, 16092.75860862088, 16130.596735081628, 16393.016419014868, 16577.41979030734, 16593.921395109308, 4855.975720121394, 5006.674566153189, 5058.974705126469, 5065.670731402465, 5090.0583392072585, 3630.8547658093644, 5482.753276102585, 5822.592418530705, 5823.3012038555435, 5931.88136237275, 5645.943540564604, 5855.912161317584, 5863.648181109126, 5869.9706501467435, 5893.204417403638, 5833.591648584601, 5842.269716285465, 5847.912281315784, 5849.64902105875, 5869.354371019184, 4568.588827005552, 4900.0, 5191.299174611417, 5194.35070616173, 5227.477252274742, 11931.821022684666, 12212.267263964191, 12371.319577423239, 12493.687657808536, 12497.062720295924, 1835.7338185963094, 1943.931962381324, 1964.6267209230343, 1979.8416126709828, 2009.9447789910414, 9307.953460232724, 9694.51527423626, 10189.949050254701, 12540.372981350987, 12893.548725794646, 1659.4109091272667, 1692.2300353339201, 1715.3395942562204, 1766.4298445761385, 1783.1351794379716, 65332.3466765331, 68048.3195168047, 68620.0, 69005.5848045316, 69287.0, 9812.165965892837, 9922.751931201778, 10141.2394070445, 10151.492425378701, 10293.0, 13876.861231387556, 13983.161010339412, 14060.507882224074, 14128.576142715736, 14132.0, 6690.665466726644, 6714.280716309902, 6742.393184613408, 6826.24911259761, 6827.180738311435, 6226.40849119333, 6461.321561236096, 6523.347665233438, 6533.052707357407, 6580.539362244693, 4348.869533913988, 4407.625351845129, 4408.294672852328, 4424.0, 4455.131249406335, 1667.316400275881, 1719.6732620802009, 1838.8345048945391, 1843.4374922067775, 1891.2245979148483, 812.9430939834155, 828.5732847583499, 868.3140319147928, 907.2923119966437, 939.9389039712397, 16190.59523511898, 16229.918850405673, 16280.592985175464, 16513.33946642121, 16519.66960660804, 874.5758307220992, 898.60910503931, 909.5997760985146, 959.9712008639752, 983.095552092077, 9049.81900361989, 9184.724458266262, 9298.581563829644, 9423.916249631244, 9502.714918552316, 392152.0, 395194.0, 399233.0, 399287.0, 404862.0, 77037.0, 78953.0, 79132.0, 79571.60214198891, 80063.0, 925.4539821505209, 932.5803388475214, 944.9102335278137, 944.9841420472882, 950.0879156010308, 14289.714205716036, 14627.126689484769, 14692.971491995459, 14707.41170353174, 15022.62443438879, 33508.32983340368, 34386.484202737476, 34959.475607866414, 36519.26961460809, 37595.436068459545, 46582.30126548037, 46869.29696054494, 48341.75829120973, 48840.755796220794, 49353.50646493489, 7624.504407213513, 7632.580208088609, 7662.693492260247, 7789.454738168274, 7847.411444141826, 8576.699815506678, 11369.31784092957, 11623.6512904613, 11626.767464650491, 11661.6501504955, 4469.731816090914, 5779.624324419067, 6020.608660437058, 10073.438617014375, 10222.437765922945, 9831.606735730491, 10075.34510256831, 10198.0, 10264.897351026395, 10419.531121099417, 10125.696229112844, 10264.230182736474, 10317.0, 10344.844827327446, 10420.32267902584, 6590.703418346282, 7756.92243077562, 7831.415632841263, 7987.40094492927, 8052.557109358807, 7834.0599128105, 10003.799924001625, 10253.48732563354, 10373.118284945864, 10708.3574985498], "value": 8001.164358318771, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.7363474574577, 1503.9924800375995, 1653.4047742812586, 1823.2251293200386, 1826.296875702854, 6824.761133360334, 7624.313811756948, 7684.807879803007, 8180.0, 8200.507969521825, 7842.137364889858, 8016.478928869619, 8118.634661440235, 8129.715459958903, 8146.551939643317, 2227.098025299756, 2245.9887700561476, 2252.121672547708, 2252.3918541993653, 2261.852979556332, 2188.0481990334256, 2189.419803751999, 2195.089038049208, 2198.120751699325, 2202.735671719388, 2204.5480676461366, 2214.66779983003, 2217.1796435318956, 2222.0667319725753, 2261.7172853393326, 2177.68423578581, 2184.453886528368, 2184.4648061224943, 2186.125549780085, 2191.901364438596, 2116.640171170889, 2128.2019242783954, 2129.4569884679463, 2131.79747923947, 2140.743110826695, 7949.403794715362, 8003.359731221488, 8007.67969281228, 8035.0, 8050.838983220361, 4460.264056430685, 4578.336141259499, 4587.541245875419, 4604.217283061887, 4606.216943119644, 2904.985475072632, 2911.199420159452, 3012.819230846156, 3020.7734419918593, 3028.258076771183, 932.5570354081825, 969.5346233807812, 987.4618333008456, 996.4818294486827, 998.8501724741346, 10888.40113793741, 10943.343399396063, 10992.120630349553, 11002.394868282161, 11012.724681883017, 4244.087521182961, 4293.828246870153, 4323.762193079411, 4397.81259060052, 4405.31717583775, 9246.815063698688, 9302.116298951589, 9305.767355816024, 9311.953440232755, 9330.440173589606, 4448.888777780581, 4517.435320584927, 4520.932186017212, 4549.294859296814, 4579.175748365326, 3498.1954150545516, 3974.9801250994137, 4039.2527382434346, 4075.3683179107284, 4119.258533464005, 3759.3984962405884, 3795.1081495848453, 3875.8255878485534, 3920.725549211585, 3922.9800251934703, 1187.1511869013696, 1225.338317308661, 1228.9262644241196, 1230.2864338683542, 1236.8144778283329, 817.8294765990615, 818.643889907892, 824.7031068815222, 841.2260720137489, 850.7362717557577, 5105.33630628016, 5556.861078473071, 5562.471565201299, 5622.775088996476, 5684.09054551278, 863.1282404771226, 938.4041133880036, 941.665708673411, 942.3921570586933, 945.7966537194399, 3764.1342491226624, 3780.6597406233573, 3876.3991581304945, 3912.0, 3924.156306394139, 9161.312901532412, 9357.953210233905, 9413.764655883657, 9423.340366174301, 9482.715518534455, 9256.444613323221, 9370.10983956523, 9379.5310234488, 9415.717528474157, 9469.47917864524, 7255.056842610426, 7375.299346562068, 7423.183449820407, 7494.887576686354, 7515.736949206752, 5528.751206195811, 5595.776168953196, 5597.80407685729, 5617.466340697627, 5750.856228594152, 9249.815003700023, 9275.62897484093, 9310.953445233, 9422.340436169676, 9532.85700714476, 8707.216350528483, 9172.541372931193, 9287.396319239228, 9376.437413755197, 9432.0, 5560.165975103767, 6227.190465239671, 6371.171747672773, 6477.222733272038, 6496.415322620986, 7538.396928245617, 7585.886211706718, 7663.348615367756, 7669.3864490841715, 7712.0, 6599.109120268702, 6647.634380109141, 6784.321567843176, 6845.349691779273, 6858.828529286808, 6762.188537375349, 6777.0, 6804.897926531205, 6920.273371295942, 6975.197852247056, 6030.819075427744, 6419.935800641933, 6429.7749578764515, 6463.3213512580505, 6513.609183449008, 4369.104333611665, 4479.3057076153245, 4504.076664283749, 4550.954490455053, 4576.084783043471, 4465.677847519337, 4561.543845615412, 4597.609203217764, 4676.5323467654325, 4690.132325519789], "value": 4213.870824678327, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.3428621317569, 297.3769951950663, 297.5744685100307, 301.406229727437, 303.24189526184523, 4767.90464190716, 4860.222364421693, 4894.363732714746, 4901.26481027846, 4915.533024362684, 4775.904481910368, 4777.808887644499, 4811.615070794337, 4815.518448155182, 4825.951740482594, 3600.8559657613664, 3651.0466900208394, 3765.7175711821724, 3777.16902281499, 3783.1677031053264, 3727.217284370272, 3729.8321575529167, 3775.1317197044696, 3776.2825063238033, 3784.432335149722, 3756.3050835595363, 3791.938257287964, 3792.2506183017367, 3802.3345914464994, 3812.237552489499, 3522.348365552381, 3750.606186350421, 3766.9623303766884, 3817.179306449099, 3821.617838216184, 3282.3271229398156, 3286.917827054319, 3290.555774970372, 3298.1589694627955, 3302.70275675188, 4699.5535424135, 4704.341392205094, 4704.835330763441, 4712.835050773207, 4714.929276060861, 4317.676174286941, 4318.3090705486975, 4330.437043184366, 4338.197433474817, 4341.891452713676, 4951.727654978975, 4989.700617962933, 4996.575291100261, 5049.2173713074535, 5060.569851562622, 3563.7327200460068, 3566.5720113586535, 3567.4113771227717, 3589.74871758977, 3605.278944211142, 5322.201669749568, 5326.588454059677, 5339.958708051892, 5399.83800485986, 5404.729763511808, 3377.8648854046055, 3555.1645363339594, 3559.9644003559633, 3674.063113905937, 3700.8704695336073, 3614.367485689994, 3614.6746792788776, 3637.3270944875285, 3640.1263696713127, 3642.7814331140216, 4176.7076304658385, 4207.873763787091, 4208.601097374529, 4219.303814870574, 4232.6190642841675, 4933.138779507877, 5072.721000345016, 5082.796688132434, 5088.313077734532, 5113.233015047698, 1283.685497053228, 1296.546208826903, 1299.4802079168219, 1308.4439113376777, 1312.3635037007043, 807.3500831830315, 812.9227723366271, 813.8128230506898, 826.813522595078, 830.0164305298241, 4468.486124095705, 4516.751578663205, 4545.36364908913, 4552.814648968513, 4575.22221222393, 5225.895482090413, 5232.92150617741, 5268.868278293074, 5323.760430780624, 5340.332458442695, 4428.092241090569, 4542.863714088583, 4554.863354099382, 4570.520095390003, 4577.7482238477205, 2752.3119220194953, 2830.448062627809, 2830.8818016883215, 2843.8720257588457, 2845.2175651695948, 4938.8765280868265, 5009.974950125226, 5014.06958321049, 5016.899662006739, 5017.673851199734, 5135.0, 5317.73411329432, 5318.760655770499, 5323.387810401776, 5350.91973620396, 4830.516948305211, 4922.4093108826455, 4927.187014142698, 4930.556249937523, 4932.457429682732, 5177.974110129425, 5189.273501709839, 5225.529702326809, 5239.869003274948, 5255.63210575256, 4922.384701912261, 5067.275552468859, 5124.154514505214, 5143.8456846294675, 5163.4578369270685, 4937.530934561211, 4944.752762361795, 4949.9257511138085, 4956.231784073474, 4961.925571116364, 3671.1372827385007, 3713.8143092844707, 3728.96271037297, 3768.4347347898033, 3780.017195529127, 5078.4921507850395, 5121.667091639031, 5211.592869925174, 5247.449017853223, 5252.632315737862, 4971.726555039508, 4989.077020751172, 4995.375578052744, 5000.349954505891, 5054.342935418372, 5013.924791128063, 5053.166227572409, 5063.797448102035, 5064.366954130734, 5074.974625126851, 4683.508231635766, 4921.778519966539, 4954.256861470808, 4976.651634385558, 5010.348654674869, 4936.679115857458, 4944.678595891256, 5071.54356107952, 5080.974595127001, 5133.897322053613, 4984.277279794482, 5006.574441172541, 5023.874403139951, 5037.974810125926, 5039.596832253337], "value": 3811.4528414531396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.1670579068816, 792.2275781113415, 794.6066696984993, 796.410656114475, 804.6258489802241, 12972.351382430876, 13453.798193027113, 13476.59570212894, 13484.730305393887, 13504.18974861509, 13463.730725385487, 13509.932450337734, 13518.121322114055, 13611.863881361183, 13646.931765341184, 658.2561705273058, 827.6978902700502, 832.4797001873828, 835.3275613131427, 836.1011912194385, 748.5396481164072, 835.9414840961135, 836.8159005018886, 838.1618381618382, 839.0686338164633, 862.5816479007676, 869.7869022089596, 870.5125129927261, 872.16708043818, 872.7381785464368, 857.5840717252128, 859.282499113235, 859.9011113721939, 862.8662557303628, 867.6225841758792, 634.3402861024529, 636.430394796659, 638.5849198021277, 639.3702203329716, 641.035241960846, 12023.879761202363, 12117.93941030298, 12128.332941688204, 12131.332776697278, 12143.089268304913, 11790.646280611596, 11793.882061179364, 12048.45781939806, 12152.57465988695, 12424.53129965045, 9212.170904618617, 9547.379420337656, 9557.856632150524, 9582.90417095827, 9831.90168098317, 3287.882991795169, 3305.2397948471744, 3309.7683162178655, 3355.697987181141, 3435.553378060861, 42845.0, 42998.35502467465, 43319.78340108311, 43624.78187609073, 44002.779986100184, 9324.856492798568, 9411.811763764686, 9428.0, 9538.52307384628, 9631.133198012212, 16842.32630694783, 16864.915675421544, 16872.578185545215, 16892.324307027582, 16923.915380423266, 6541.47668186544, 6557.0, 6597.7690780823405, 6617.239017512951, 6709.463242940553, 3690.0, 3701.8704345348324, 3704.759190652599, 3751.6998640108727, 3799.4870692456166, 2740.945181096367, 2834.971650283512, 2842.275219818933, 2842.545192769147, 2858.1711303722104, 510.0614868641643, 511.910415677255, 518.9429162792089, 521.8643152780304, 522.1228336394818, 531.2880739808675, 564.7317524175984, 568.8253755993859, 570.7631332996843, 604.4710877981765, 7289.599072051088, 7376.651927352734, 7409.147947985943, 7531.209223031612, 7590.848183036419, 462.6599449404685, 562.7748900439775, 565.9021498293298, 568.5565259097827, 572.481903876992, 3443.9311213775586, 3615.1142969971997, 3632.2190728992864, 3959.148783011666, 4169.583041695806, 53335.73332133392, 54611.180832287544, 54974.72512637411, 55007.0, 55016.0, 48779.0, 49296.507034929906, 49627.75186124046, 50165.74917125391, 51291.743541282056, 45064.774676126406, 45267.547324526335, 45314.54685453169, 45450.0, 46419.5358046422, 7880.684772609032, 7907.525548467111, 7958.806179073185, 7974.322182614543, 8050.919490805017, 30060.24849378783, 30236.69763302339, 30246.54630180505, 30373.088807335815, 30876.0, 37976.81011594924, 39834.0, 39952.2009559813, 40409.39385909155, 40807.59192408038, 10369.585216591553, 10668.146548275965, 11092.112630989663, 11098.389588572705, 11150.776984460103, 11580.131490138441, 11661.76676466483, 11758.647240582795, 11768.646940591796, 12531.060170487068, 11476.483558240066, 11533.308001519936, 11818.645440636794, 11884.246301479478, 12121.995476125447, 11456.19806613529, 11537.653870383901, 11630.302181869114, 11666.183367164216, 11950.701232469259, 11297.096232301561, 11367.658970230576, 11397.373144477133, 11710.414479275832, 11798.469068892093, 9487.525623718924, 9540.189083927944, 9706.223502119954, 10029.197664186993, 10175.440350780482, 8860.778480538038, 9241.306901982243, 9284.257259419095, 9327.533623318943, 9339.0], "value": 5993.8472258959755, "unit": "score"}], "name": "dromaeo_css", "value": 6246.896924144631, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 110.0, 117.0, 117.0, 121.0, 110.0, 116.0, 119.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 144.0, 158.0, 216.0, 139.0, 159.0, 164.0, 135.0, 178.0, 136.0], "value": 158.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 166.0, 164.0, 169.0, 172.0, 166.0, 164.0, 163.0, 163.0], "value": 165.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 154.0, 78.0, 78.0, 77.0, 78.0, 157.0, 80.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 168.0, 94.0, 95.0, 94.0, 136.0, 94.0, 94.0, 137.0], "value": 110.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 118.0, 118.0, 119.0, 119.0, 121.0, 119.0, 120.0, 119.0], "value": 119.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 150.0, 150.0, 151.0, 149.0, 150.0, 150.0, 150.0, 149.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 99.0, 97.0, 97.0, 97.0, 98.0, 98.0, 97.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 64.0, 65.0, 65.0, 64.0, 65.0, 69.0, 64.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 94.0, 87.0, 87.0, 85.0, 146.0, 91.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 140.0, 139.0, 141.0, 144.0, 145.0, 141.0, 163.0, 142.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 190.0, 186.0, 179.0, 178.0, 178.0, 180.0, 181.0, 178.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 71.0, 71.0, 72.0, 71.0, 72.0, 71.0, 71.0, 73.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1634.6, "alertThreshold": 2.0}]} 14:21:34 INFO - Return code: 0 14:21:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:21:34 ERROR - # TBPL SUCCESS # 14:21:34 INFO - Running post-action listener: _resource_record_post_action 14:21:34 INFO - ##### 14:21:34 INFO - ##### Finished run-tests step (success) 14:21:34 INFO - ##### 14:21:34 INFO - Running post-run listener: _resource_record_post_run 14:21:36 INFO - Total resource usage - Wall time: 1193s; CPU: 26.0%; Read bytes: 4096; Write bytes: 521998336; Read time: 12; Write time: 522564 14:21:36 INFO - install - Wall time: 16s; CPU: 26.0%; Read bytes: 0; Write bytes: 234487808; Read time: 0; Write time: 291828 14:21:36 INFO - run-tests - Wall time: 1178s; CPU: 26.0%; Read bytes: 4096; Write bytes: 286912512; Read time: 12; Write time: 230736 14:21:36 INFO - Running post-run listener: _upload_blobber_files 14:21:36 INFO - Blob upload gear active. 14:21:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:36 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 14:21:36 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'] 14:21:36 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 14:21:36 INFO - (blobuploader) - INFO - Open directory for files ... 14:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:37 INFO - (blobuploader) - INFO - Done attempting. 14:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:37 INFO - (blobuploader) - INFO - Done attempting. 14:21:37 INFO - (blobuploader) - INFO - Iteration through files over. 14:21:37 INFO - Return code: 0 14:21:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:21:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:21:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/57d0799c8d74601182ea858e345b74b305bc1e76f372eeb97273f2ea7d58ec8579f384e0a6f7bd6bb4ee497296de3ee92b37d7d87cf02e3d7b0bb76c68525492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4d588bb500345d944b30abd903070acceb47afd5b2a5f46f6dfae29e43c06fca384c1b6bab957a720f5bf64a2e88e68c0143bfa68c850057d8e74a97531af261"} 14:21:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:37 INFO - Contents: 14:21:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/57d0799c8d74601182ea858e345b74b305bc1e76f372eeb97273f2ea7d58ec8579f384e0a6f7bd6bb4ee497296de3ee92b37d7d87cf02e3d7b0bb76c68525492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4d588bb500345d944b30abd903070acceb47afd5b2a5f46f6dfae29e43c06fca384c1b6bab957a720f5bf64a2e88e68c0143bfa68c850057d8e74a97531af261"} 14:21:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:37 INFO - Copying logs to upload dir... 14:21:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.468795 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-20 14:21:37.105706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 14:21:37.109017) ========= 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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/57d0799c8d74601182ea858e345b74b305bc1e76f372eeb97273f2ea7d58ec8579f384e0a6f7bd6bb4ee497296de3ee92b37d7d87cf02e3d7b0bb76c68525492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4d588bb500345d944b30abd903070acceb47afd5b2a5f46f6dfae29e43c06fca384c1b6bab957a720f5bf64a2e88e68c0143bfa68c850057d8e74a97531af261"} build_url:https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005495 build_url: 'https://queue.taskcluster.net/v1/task/Bjn683KhQiy9Zol3VtYh2A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/57d0799c8d74601182ea858e345b74b305bc1e76f372eeb97273f2ea7d58ec8579f384e0a6f7bd6bb4ee497296de3ee92b37d7d87cf02e3d7b0bb76c68525492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4d588bb500345d944b30abd903070acceb47afd5b2a5f46f6dfae29e43c06fca384c1b6bab957a720f5bf64a2e88e68c0143bfa68c850057d8e74a97531af261"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 14:21:37.124433) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:21:37.124846) ========= 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=232498196343f89f466a4a9a000001c8-1463775315.315656-973653729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002109 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 14:21:37.205119) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 14:21:37.205553) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 14:21:37.205951) ========= ========= Total master_lag: 0.18 =========