builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1461708177.36 results: success (0) buildid: 20160426113024 builduid: c28578e63060433a82b8744816936f8c revision: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.358164) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.358672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.358962) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.376406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.376815) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 15:02:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2016-04-26 15:02:57 (13.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092349 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.483390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.483799) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020249 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:57.535485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 15:02:57.536135) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 --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=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 15:02:57,761 truncating revision to first 12 chars 2016-04-26 15:02:57,762 Setting DEBUG logging. 2016-04-26 15:02:57,762 attempt 1/10 2016-04-26 15:02:57,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5ffdd1bafd49?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 15:02:58,936 unpacking tar archive at: fx-team-5ffdd1bafd49/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456840 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 15:02:59.124681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:59.124988) ========= script_repo_revision: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:59.125389) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:59.128705) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 15:02:59.141972) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-04-26 15:02:59.142571) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:02:59 INFO - MultiFileLogger online at 20160426 15:02:59 in /builds/slave/test 15:02:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:02:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:02:59 INFO - {'append_to_log': False, 15:02:59 INFO - 'base_work_dir': '/builds/slave/test', 15:02:59 INFO - 'blob_upload_branch': 'Fx-Team', 15:02:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:02:59 INFO - 'branch': 'Fx-Team', 15:02:59 INFO - 'buildbot_json_path': 'buildprops.json', 15:02:59 INFO - 'config_files': ('talos/linux_config.py',), 15:02:59 INFO - 'default_actions': ('clobber', 15:02:59 INFO - 'read-buildbot-config', 15:02:59 INFO - 'download-and-extract', 15:02:59 INFO - 'populate-webroot', 15:02:59 INFO - 'create-virtualenv', 15:02:59 INFO - 'install', 15:02:59 INFO - 'run-tests'), 15:02:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:02:59 INFO - 'download_minidump_stackwalk': True, 15:02:59 INFO - 'download_symbols': 'ondemand', 15:02:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:02:59 INFO - 'tooltool.py': '/tools/tooltool.py', 15:02:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:02:59 INFO - '/tools/misc-python/virtualenv.py')}, 15:02:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:02:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:02:59 INFO - 'installer_path': 'installer.exe', 15:02:59 INFO - 'log_level': 'info', 15:02:59 INFO - 'log_name': 'talos', 15:02:59 INFO - 'log_to_console': True, 15:02:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:02:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:02:59 INFO - 'opt_config_files': (), 15:02:59 INFO - 'pip_index': False, 15:02:59 INFO - 'sps_profile': False, 15:02:59 INFO - 'sps_profile_interval': 0, 15:02:59 INFO - 'suite': 'dromaeojs', 15:02:59 INFO - 'system_bits': '32', 15:02:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:02:59 INFO - 'title': 'talos-linux64-ix-013', 15:02:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:02:59 INFO - 'use_talos_json': True, 15:02:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:02:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:02:59 INFO - 'work_dir': 'build'} 15:02:59 INFO - ##### 15:02:59 INFO - ##### Running clobber step. 15:02:59 INFO - ##### 15:02:59 INFO - Running pre-action listener: _resource_record_pre_action 15:02:59 INFO - Running main action method: clobber 15:02:59 INFO - rmtree: /builds/slave/test/build 15:02:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:03:00 INFO - Running post-action listener: _resource_record_post_action 15:03:00 INFO - ##### 15:03:00 INFO - ##### Running read-buildbot-config step. 15:03:00 INFO - ##### 15:03:00 INFO - Running pre-action listener: _resource_record_pre_action 15:03:00 INFO - Running main action method: read_buildbot_config 15:03:00 INFO - Using buildbot properties: 15:03:00 INFO - { 15:03:00 INFO - "project": "", 15:03:00 INFO - "product": "firefox", 15:03:00 INFO - "script_repo_revision": "production", 15:03:00 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:03:00 INFO - "repository": "", 15:03:00 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 15:03:00 INFO - "buildid": "20160426113024", 15:03:00 INFO - "pgo_build": "True", 15:03:00 INFO - "basedir": "/builds/slave/test", 15:03:00 INFO - "buildnumber": 64, 15:03:00 INFO - "slavename": "talos-linux64-ix-013", 15:03:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:03:00 INFO - "platform": "ubuntu64_hw", 15:03:00 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 15:03:00 INFO - "branch": "fx-team", 15:03:00 INFO - "repo_path": "integration/fx-team", 15:03:00 INFO - "revision": "5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4", 15:03:00 INFO - "stage_platform": "linux64-pgo", 15:03:00 INFO - "builduid": "c28578e63060433a82b8744816936f8c", 15:03:00 INFO - "slavebuilddir": "test" 15:03:00 INFO - } 15:03:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:03:00 INFO - Running post-action listener: _resource_record_post_action 15:03:00 INFO - ##### 15:03:00 INFO - ##### Running download-and-extract step. 15:03:00 INFO - ##### 15:03:00 INFO - Running pre-action listener: _resource_record_pre_action 15:03:00 INFO - Running main action method: download_and_extract 15:03:00 INFO - mkdir: /builds/slave/test/build/tests 15:03:00 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')]} 15:03:00 INFO - https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:03:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:03:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:03:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:01 INFO - Downloaded 1361 bytes. 15:03:01 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:03:01 INFO - Using the following test package requirements: 15:03:01 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:03:01 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:03:01 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:03:01 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'jsshell-linux-x86_64.zip'], 15:03:01 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:03:01 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:03:01 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:03:01 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:03:01 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:03:01 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:03:01 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:03:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:03:01 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')]} 15:03:01 INFO - https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:03:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:03:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:03:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:02 INFO - Downloaded 23863534 bytes. 15:03:02 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'] 15:03:02 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 15:03:04 INFO - Return code: 0 15:03:04 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 15:03:04 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')]} 15:03:04 INFO - https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:03:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:03:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:03:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:22 INFO - Downloaded 23863534 bytes. 15:03:22 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'] 15:03:22 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 15:03:24 INFO - Return code: 0 15:03:24 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')]} 15:03:24 INFO - https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:03:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:03:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:03:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:30 INFO - Downloaded 10840145 bytes. 15:03:30 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'] 15:03:30 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 15:03:30 INFO - Return code: 0 15:03:30 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')]} 15:03:30 INFO - https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:03:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:03:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:03:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fTsK_ZcXR-mICk1GILetLQ/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 15:03:31 INFO - Downloaded 58336934 bytes. 15:03:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:03:31 INFO - mkdir: /builds/slave/test/properties 15:03:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:03:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:03:31 INFO - Contents: 15:03:31 INFO - build_url:https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:03:32 INFO - Running post-action listener: _resource_record_post_action 15:03:32 INFO - Running post-action listener: set_extra_try_arguments 15:03:32 INFO - ##### 15:03:32 INFO - ##### Running populate-webroot step. 15:03:32 INFO - ##### 15:03:32 INFO - Running pre-action listener: _resource_record_pre_action 15:03:32 INFO - Running main action method: populate_webroot 15:03:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:03:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:03:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:03:32 INFO - '--tppagecycles', 15:03:32 INFO - '7'], 15:03:32 INFO - 'tests': ['tsvgm']}}, 15:03:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:03:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:03:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:03:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:03:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:03:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:03:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['damp', 'tps']}, 15:03:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'tests': ['damp', 'tps']}, 15:03:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['dromaeo_dom']}, 15:03:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:03:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['a11yr', 15:03:32 INFO - 'ts_paint', 15:03:32 INFO - 'tpaint', 15:03:32 INFO - 'sessionrestore', 15:03:32 INFO - 'sessionrestore_no_auto_restore', 15:03:32 INFO - 'tabpaint']}, 15:03:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['tsvgx', 15:03:32 INFO - 'tsvgr_opacity', 15:03:32 INFO - 'tart', 15:03:32 INFO - 'tscrollx', 15:03:32 INFO - 'cart']}, 15:03:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:03:32 INFO - 'tsvgr_opacity', 15:03:32 INFO - 'tart', 15:03:32 INFO - 'tscrollx', 15:03:32 INFO - 'cart']}, 15:03:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'talos_options': ['--disable-e10s'], 15:03:32 INFO - 'tests': ['tp5o']}, 15:03:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'tests': ['tp5o']}, 15:03:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'talos_options': ['--disable-e10s', 15:03:32 INFO - '--xperf_path', 15:03:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:03:32 INFO - 'tests': ['tp5n']}, 15:03:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:32 INFO - 'talos_options': ['--xperf_path', 15:03:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:03:32 INFO - 'tests': ['tp5n']}}, 15:03:32 INFO - 'talos.zip': {'path': '', 15:03:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:03:32 INFO - Running post-action listener: _resource_record_post_action 15:03:32 INFO - ##### 15:03:32 INFO - ##### Running create-virtualenv step. 15:03:32 INFO - ##### 15:03:32 INFO - Running pre-action listener: _resource_record_pre_action 15:03:32 INFO - Running main action method: create_virtualenv 15:03:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:03:32 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 15:03:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:03:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:03:32 INFO - Using real prefix '/usr' 15:03:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:03:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:03:35 INFO - Installing pip.................done. 15:03:35 INFO - Return code: 0 15:03:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:03: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')]} 15:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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')]} 15:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03: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', 'pip>=1.5'] in /builds/slave/test/build 15:03: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 pip>=1.5 15:03:35 INFO - Using env: {'DISPLAY': ':0', 15:03:35 INFO - 'HOME': '/home/cltbld', 15:03:35 INFO - 'LANG': 'en_US.UTF-8', 15:03:35 INFO - 'LANGUAGE': 'en_US:en', 15:03:35 INFO - 'LOGNAME': 'cltbld', 15:03:35 INFO - 'MAIL': '/var/mail/cltbld', 15:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:35 INFO - 'MOZ_NO_REMOTE': '1', 15:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:35 INFO - 'NO_EM_RESTART': '1', 15:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:35 INFO - 'PWD': '/builds/slave/test', 15:03:35 INFO - 'SHELL': '/bin/bash', 15:03:35 INFO - 'SHLVL': '1', 15:03:35 INFO - 'TERM': 'linux', 15:03:35 INFO - 'TMOUT': '86400', 15:03:35 INFO - 'USER': 'cltbld', 15:03:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:35 INFO - '_': '/tools/buildbot/bin/python'} 15:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:35 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 15:03:35 INFO - Cleaning up... 15:03:35 INFO - Return code: 0 15:03:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:03: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')]} 15:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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')]} 15:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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', '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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03: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', 'psutil>=0.7.1'] in /builds/slave/test/build 15:03: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 psutil>=0.7.1 15:03:35 INFO - Using env: {'DISPLAY': ':0', 15:03:35 INFO - 'HOME': '/home/cltbld', 15:03:35 INFO - 'LANG': 'en_US.UTF-8', 15:03:35 INFO - 'LANGUAGE': 'en_US:en', 15:03:35 INFO - 'LOGNAME': 'cltbld', 15:03:35 INFO - 'MAIL': '/var/mail/cltbld', 15:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:35 INFO - 'MOZ_NO_REMOTE': '1', 15:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:35 INFO - 'NO_EM_RESTART': '1', 15:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:35 INFO - 'PWD': '/builds/slave/test', 15:03:35 INFO - 'SHELL': '/bin/bash', 15:03:35 INFO - 'SHLVL': '1', 15:03:35 INFO - 'TERM': 'linux', 15:03:35 INFO - 'TMOUT': '86400', 15:03:35 INFO - 'USER': 'cltbld', 15:03:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:35 INFO - '_': '/tools/buildbot/bin/python'} 15:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:35 INFO - Downloading/unpacking psutil>=0.7.1 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:03:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:37 INFO - Installing collected packages: psutil 15:03:37 INFO - Running setup.py install for psutil 15:03:37 INFO - building 'psutil._psutil_linux' extension 15:03:37 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 15:03:37 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 15:03:37 INFO - building 'psutil._psutil_posix' extension 15:03:37 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 15:03:38 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 15:03:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:38 INFO - Successfully installed psutil 15:03:38 INFO - Cleaning up... 15:03:38 INFO - Return code: 0 15:03:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:03: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')]} 15:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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')]} 15:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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', '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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:03: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 mozsystemmonitor==0.0.0 15:03:38 INFO - Using env: {'DISPLAY': ':0', 15:03:38 INFO - 'HOME': '/home/cltbld', 15:03:38 INFO - 'LANG': 'en_US.UTF-8', 15:03:38 INFO - 'LANGUAGE': 'en_US:en', 15:03:38 INFO - 'LOGNAME': 'cltbld', 15:03:38 INFO - 'MAIL': '/var/mail/cltbld', 15:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:38 INFO - 'MOZ_NO_REMOTE': '1', 15:03:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:38 INFO - 'NO_EM_RESTART': '1', 15:03:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:38 INFO - 'PWD': '/builds/slave/test', 15:03:38 INFO - 'SHELL': '/bin/bash', 15:03:38 INFO - 'SHLVL': '1', 15:03:38 INFO - 'TERM': 'linux', 15:03:38 INFO - 'TMOUT': '86400', 15:03:38 INFO - 'USER': 'cltbld', 15:03:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:38 INFO - '_': '/tools/buildbot/bin/python'} 15:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:03:40 INFO - Installing collected packages: mozsystemmonitor 15:03:40 INFO - Running setup.py install for mozsystemmonitor 15:03:40 INFO - Successfully installed mozsystemmonitor 15:03:40 INFO - Cleaning up... 15:03:40 INFO - Return code: 0 15:03:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:40 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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:40 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 15:03:40 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 15:03:40 INFO - Using env: {'DISPLAY': ':0', 15:03:40 INFO - 'HOME': '/home/cltbld', 15:03:40 INFO - 'LANG': 'en_US.UTF-8', 15:03:40 INFO - 'LANGUAGE': 'en_US:en', 15:03:40 INFO - 'LOGNAME': 'cltbld', 15:03:40 INFO - 'MAIL': '/var/mail/cltbld', 15:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:40 INFO - 'MOZ_NO_REMOTE': '1', 15:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:40 INFO - 'NO_EM_RESTART': '1', 15:03:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:40 INFO - 'PWD': '/builds/slave/test', 15:03:40 INFO - 'SHELL': '/bin/bash', 15:03:40 INFO - 'SHLVL': '1', 15:03:40 INFO - 'TERM': 'linux', 15:03:40 INFO - 'TMOUT': '86400', 15:03:40 INFO - 'USER': 'cltbld', 15:03:40 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:40 INFO - '_': '/tools/buildbot/bin/python'} 15:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:03:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:03:42 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 15:03:42 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 15:03:42 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 15:03:42 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 15:03:42 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 15:03:42 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 15:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:03:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:03:43 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 15:03:43 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 15:03:43 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 15:03:43 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 15:03:43 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 15:03:43 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 15:03:43 INFO - Downloading docopt-0.6.1.tar.gz 15:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:03:43 INFO - Installing collected packages: blobuploader, requests, docopt 15:03:43 INFO - Running setup.py install for blobuploader 15:03:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:03:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:03:43 INFO - Running setup.py install for requests 15:03:43 INFO - Running setup.py install for docopt 15:03:43 INFO - Successfully installed blobuploader requests docopt 15:03:43 INFO - Cleaning up... 15:03:43 INFO - Return code: 0 15:03:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:43 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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:43 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 15:03:43 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 15:03:43 INFO - Using env: {'DISPLAY': ':0', 15:03:43 INFO - 'HOME': '/home/cltbld', 15:03:43 INFO - 'LANG': 'en_US.UTF-8', 15:03:43 INFO - 'LANGUAGE': 'en_US:en', 15:03:43 INFO - 'LOGNAME': 'cltbld', 15:03:43 INFO - 'MAIL': '/var/mail/cltbld', 15:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:43 INFO - 'MOZ_NO_REMOTE': '1', 15:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:43 INFO - 'NO_EM_RESTART': '1', 15:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:43 INFO - 'PWD': '/builds/slave/test', 15:03:43 INFO - 'SHELL': '/bin/bash', 15:03:43 INFO - 'SHLVL': '1', 15:03:43 INFO - 'TERM': 'linux', 15:03:43 INFO - 'TMOUT': '86400', 15:03:43 INFO - 'USER': 'cltbld', 15:03:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:43 INFO - '_': '/tools/buildbot/bin/python'} 15:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:44 INFO - Running setup.py (path:/tmp/pip-beVLmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:44 INFO - Running setup.py (path:/tmp/pip-2nguv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:44 INFO - Running setup.py (path:/tmp/pip-Zx7iJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:44 INFO - Running setup.py (path:/tmp/pip-IrVld6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:44 INFO - Running setup.py (path:/tmp/pip-VBFFkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:44 INFO - Running setup.py (path:/tmp/pip-a24o23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:44 INFO - Running setup.py (path:/tmp/pip-04nbTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:44 INFO - Running setup.py (path:/tmp/pip-UmV1lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:44 INFO - Running setup.py (path:/tmp/pip-ilOuSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:44 INFO - Running setup.py (path:/tmp/pip-tjPCOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:44 INFO - Running setup.py (path:/tmp/pip-lIwvu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:44 INFO - Running setup.py (path:/tmp/pip-tBE1F5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:45 INFO - Running setup.py (path:/tmp/pip-7nzjKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:45 INFO - Running setup.py (path:/tmp/pip-7rqfE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:45 INFO - Running setup.py (path:/tmp/pip-nQBhQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:45 INFO - Running setup.py (path:/tmp/pip-c9eAFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:45 INFO - Running setup.py (path:/tmp/pip-QTvt37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:03:45 INFO - Running setup.py install for manifestparser 15:03:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:03:45 INFO - Running setup.py install for mozcrash 15:03:45 INFO - Running setup.py install for mozdebug 15:03:45 INFO - Running setup.py install for mozdevice 15:03:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:03:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:03:45 INFO - Running setup.py install for mozfile 15:03:45 INFO - Running setup.py install for mozhttpd 15:03:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:03:45 INFO - Running setup.py install for mozinfo 15:03:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for mozInstall 15:03:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for mozleak 15:03:46 INFO - Running setup.py install for mozlog 15:03:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for moznetwork 15:03:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for mozprocess 15:03:46 INFO - Running setup.py install for mozprofile 15:03:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for mozrunner 15:03:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:03:46 INFO - Running setup.py install for mozscreenshot 15:03:46 INFO - Running setup.py install for moztest 15:03:47 INFO - Running setup.py install for mozversion 15:03:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:03:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:03:47 INFO - Cleaning up... 15:03:47 INFO - Return code: 0 15:03:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:47 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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:47 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 15:03:47 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 15:03:47 INFO - Using env: {'DISPLAY': ':0', 15:03:47 INFO - 'HOME': '/home/cltbld', 15:03:47 INFO - 'LANG': 'en_US.UTF-8', 15:03:47 INFO - 'LANGUAGE': 'en_US:en', 15:03:47 INFO - 'LOGNAME': 'cltbld', 15:03:47 INFO - 'MAIL': '/var/mail/cltbld', 15:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:47 INFO - 'MOZ_NO_REMOTE': '1', 15:03:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:47 INFO - 'NO_EM_RESTART': '1', 15:03:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:47 INFO - 'PWD': '/builds/slave/test', 15:03:47 INFO - 'SHELL': '/bin/bash', 15:03:47 INFO - 'SHLVL': '1', 15:03:47 INFO - 'TERM': 'linux', 15:03:47 INFO - 'TMOUT': '86400', 15:03:47 INFO - 'USER': 'cltbld', 15:03:47 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:47 INFO - '_': '/tools/buildbot/bin/python'} 15:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:47 INFO - Running setup.py (path:/tmp/pip-iQ795X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:47 INFO - Running setup.py (path:/tmp/pip-ESBfll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:47 INFO - Running setup.py (path:/tmp/pip-CE2MXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:47 INFO - Running setup.py (path:/tmp/pip-ur8jYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:47 INFO - Running setup.py (path:/tmp/pip-WrcM4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:47 INFO - Running setup.py (path:/tmp/pip-s5IxMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:47 INFO - Running setup.py (path:/tmp/pip-tn8mEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:47 INFO - Running setup.py (path:/tmp/pip-i_PbX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03:47 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)) 15:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:47 INFO - Running setup.py (path:/tmp/pip-zsVFdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:48 INFO - Running setup.py (path:/tmp/pip-8yfxuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:48 INFO - Running setup.py (path:/tmp/pip-TWfLI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:48 INFO - Running setup.py (path:/tmp/pip-eUvV9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:48 INFO - Running setup.py (path:/tmp/pip-AHVk3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:48 INFO - Running setup.py (path:/tmp/pip-50lWQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:48 INFO - Running setup.py (path:/tmp/pip-4gNQ7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:48 INFO - Running setup.py (path:/tmp/pip-kXYRUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:48 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)) 15:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:48 INFO - Running setup.py (path:/tmp/pip-q5vOSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03:48 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)) 15:03:48 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)) 15:03:48 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)) 15:03:48 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)) 15:03:48 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)) 15:03:48 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)) 15:03:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:50 INFO - Downloading blessings-1.6.tar.gz 15:03:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:03:50 INFO - Installing collected packages: blessings 15:03:50 INFO - Running setup.py install for blessings 15:03:50 INFO - Successfully installed blessings 15:03:50 INFO - Cleaning up... 15:03:50 INFO - Return code: 0 15:03:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:50 INFO - Reading from file tmpfile_stdout 15:03:50 INFO - Current package versions: 15:03:50 INFO - argparse == 1.2.1 15:03:50 INFO - blessings == 1.6 15:03:50 INFO - blobuploader == 1.2.4 15:03:50 INFO - docopt == 0.6.1 15:03:50 INFO - manifestparser == 1.1 15:03:50 INFO - mozInstall == 1.12 15:03:50 INFO - mozcrash == 0.17 15:03:50 INFO - mozdebug == 0.1 15:03:50 INFO - mozdevice == 0.48 15:03:50 INFO - mozfile == 1.2 15:03:50 INFO - mozhttpd == 0.7 15:03:50 INFO - mozinfo == 0.9 15:03:50 INFO - mozleak == 0.1 15:03:50 INFO - mozlog == 3.1 15:03:50 INFO - moznetwork == 0.27 15:03:50 INFO - mozprocess == 0.22 15:03:50 INFO - mozprofile == 0.28 15:03:50 INFO - mozrunner == 6.11 15:03:50 INFO - mozscreenshot == 0.1 15:03:50 INFO - mozsystemmonitor == 0.0 15:03:50 INFO - moztest == 0.7 15:03:50 INFO - mozversion == 1.4 15:03:50 INFO - psutil == 3.1.1 15:03:50 INFO - requests == 1.2.3 15:03:50 INFO - wsgiref == 0.1.2 15:03:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:50 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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:50 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 15:03:50 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 15:03:50 INFO - Using env: {'DISPLAY': ':0', 15:03:50 INFO - 'HOME': '/home/cltbld', 15:03:50 INFO - 'LANG': 'en_US.UTF-8', 15:03:50 INFO - 'LANGUAGE': 'en_US:en', 15:03:50 INFO - 'LOGNAME': 'cltbld', 15:03:50 INFO - 'MAIL': '/var/mail/cltbld', 15:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:50 INFO - 'MOZ_NO_REMOTE': '1', 15:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:50 INFO - 'NO_EM_RESTART': '1', 15:03:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:50 INFO - 'PWD': '/builds/slave/test', 15:03:50 INFO - 'SHELL': '/bin/bash', 15:03:50 INFO - 'SHLVL': '1', 15:03:50 INFO - 'TERM': 'linux', 15:03:50 INFO - 'TMOUT': '86400', 15:03:50 INFO - 'USER': 'cltbld', 15:03:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:50 INFO - '_': '/tools/buildbot/bin/python'} 15:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 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)) 15:03:51 INFO - Cleaning up... 15:03:51 INFO - Return code: 0 15:03:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:51 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 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:51 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 15:03:51 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 15:03:51 INFO - Using env: {'DISPLAY': ':0', 15:03:51 INFO - 'HOME': '/home/cltbld', 15:03:51 INFO - 'LANG': 'en_US.UTF-8', 15:03:51 INFO - 'LANGUAGE': 'en_US:en', 15:03:51 INFO - 'LOGNAME': 'cltbld', 15:03:51 INFO - 'MAIL': '/var/mail/cltbld', 15:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:51 INFO - 'MOZ_NO_REMOTE': '1', 15:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:51 INFO - 'NO_EM_RESTART': '1', 15:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:51 INFO - 'PWD': '/builds/slave/test', 15:03:51 INFO - 'SHELL': '/bin/bash', 15:03:51 INFO - 'SHLVL': '1', 15:03:51 INFO - 'TERM': 'linux', 15:03:51 INFO - 'TMOUT': '86400', 15:03:51 INFO - 'USER': 'cltbld', 15:03:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:51 INFO - '_': '/tools/buildbot/bin/python'} 15:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:51 INFO - Downloading/unpacking jsonschema 15:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:03:53 INFO - Downloading/unpacking functools32 (from jsonschema) 15:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:53 INFO - Downloading functools32-3.2.3-2.tar.gz 15:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:03:53 INFO - warning: no files found matching '*.txt' 15:03:53 INFO - no previously-included directories found matching 'build' 15:03:53 INFO - no previously-included directories found matching 'dist' 15:03:53 INFO - no previously-included directories found matching '.git*' 15:03:53 INFO - Installing collected packages: jsonschema, functools32 15:03:53 INFO - Running setup.py install for functools32 15:03:53 INFO - warning: no files found matching '*.txt' 15:03:53 INFO - no previously-included directories found matching 'build' 15:03:53 INFO - no previously-included directories found matching 'dist' 15:03:53 INFO - no previously-included directories found matching '.git*' 15:03:53 INFO - Successfully installed jsonschema functools32 15:03:53 INFO - Cleaning up... 15:03:53 INFO - Return code: 0 15:03:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3408c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c119a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', '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 15:03:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:03:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:03:53 INFO - Using env: {'DISPLAY': ':0', 15:03:53 INFO - 'HOME': '/home/cltbld', 15:03:53 INFO - 'LANG': 'en_US.UTF-8', 15:03:53 INFO - 'LANGUAGE': 'en_US:en', 15:03:53 INFO - 'LOGNAME': 'cltbld', 15:03:53 INFO - 'MAIL': '/var/mail/cltbld', 15:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:53 INFO - 'MOZ_NO_REMOTE': '1', 15:03:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:03:53 INFO - 'NO_EM_RESTART': '1', 15:03:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:53 INFO - 'PWD': '/builds/slave/test', 15:03:53 INFO - 'SHELL': '/bin/bash', 15:03:53 INFO - 'SHLVL': '1', 15:03:53 INFO - 'TERM': 'linux', 15:03:53 INFO - 'TMOUT': '86400', 15:03:54 INFO - 'USER': 'cltbld', 15:03:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:54 INFO - '_': '/tools/buildbot/bin/python'} 15:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:54 INFO - Downloading/unpacking flake8 15:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:03:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:03:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:03:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:56 INFO - no previously-included directories found matching 'docs/_build' 15:03:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:03:56 INFO - Running setup.py install for pep8 15:03:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:56 INFO - no previously-included directories found matching 'docs/_build' 15:03:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:03:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:03:56 INFO - Cleaning up... 15:03:56 INFO - Return code: 0 15:03:57 INFO - Running post-action listener: _resource_record_post_action 15:03:57 INFO - Running post-action listener: _start_resource_monitoring 15:03:57 INFO - Starting resource monitoring. 15:03:57 INFO - ##### 15:03:57 INFO - ##### Running install step. 15:03:57 INFO - ##### 15:03:57 INFO - Running pre-action listener: _resource_record_pre_action 15:03:57 INFO - Running main action method: install 15:03:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:57 INFO - Reading from file tmpfile_stdout 15:03:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:03:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:03:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:03:57 INFO - Reading from file tmpfile_stdout 15:03:57 INFO - Output received: 15:03:57 INFO - Usage: mozinstall [options] installer 15:03:57 INFO - Options: 15:03:57 INFO - -h, --help show this help message and exit 15:03:57 INFO - -d DEST, --destination=DEST 15:03:57 INFO - Directory to install application into. [default: 15:03:57 INFO - "/builds/slave/test"] 15:03:57 INFO - --app=APP Application being installed. [default: firefox] 15:03:57 INFO - mkdir: /builds/slave/test/build/application 15:03:57 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'] 15:03:57 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 15:04:13 INFO - Reading from file tmpfile_stdout 15:04:13 INFO - Output received: 15:04:13 INFO - /builds/slave/test/build/application/firefox/firefox 15:04:13 INFO - Running post-action listener: _resource_record_post_action 15:04:13 INFO - ##### 15:04:13 INFO - ##### Running run-tests step. 15:04:13 INFO - ##### 15:04:13 INFO - Running pre-action listener: _resource_record_pre_action 15:04:13 INFO - Running main action method: run_tests 15:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:04:13 INFO - Python 2.7.3 15:04:13 INFO - Return code: 0 15:04:13 INFO - grabbing minidump binary from tooltool 15:04: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')]} 15:04:13 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0810>, '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 0x1ca1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1ba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:04:13 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 15:04:13 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 15:04:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:04:13 INFO - Return code: 0 15:04:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:04:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:04:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:04:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:04:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:04:14 INFO - Return code: 0 15:04:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:04:14 INFO - Using env: {'DISPLAY': ':0', 15:04:14 INFO - 'HOME': '/home/cltbld', 15:04:14 INFO - 'LANG': 'en_US.UTF-8', 15:04:14 INFO - 'LANGUAGE': 'en_US:en', 15:04:14 INFO - 'LOGNAME': 'cltbld', 15:04:14 INFO - 'MAIL': '/var/mail/cltbld', 15:04:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:04:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:04:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:04:14 INFO - 'MOZ_NO_REMOTE': '1', 15:04:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:04:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:04:14 INFO - 'NO_EM_RESTART': '1', 15:04:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:04:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:04:14 INFO - 'PWD': '/builds/slave/test', 15:04:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:04:14 INFO - 'SHELL': '/bin/bash', 15:04:14 INFO - 'SHLVL': '1', 15:04:14 INFO - 'TERM': 'linux', 15:04:14 INFO - 'TMOUT': '86400', 15:04:14 INFO - 'USER': 'cltbld', 15:04:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302', 15:04:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:04:14 INFO - '_': '/tools/buildbot/bin/python'} 15:04:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:04:15 INFO - mozversion application_buildid: 20160426113024 15:04:15 INFO - mozversion application_changeset: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 15:04:15 INFO - mozversion application_display_name: Nightly 15:04:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:04:15 INFO - mozversion application_name: Firefox 15:04:15 INFO - mozversion application_remotingname: firefox 15:04:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:04:15 INFO - mozversion application_vendor: Mozilla 15:04:15 INFO - mozversion application_version: 49.0a1 15:04:15 INFO - mozversion platform_buildid: 20160426113024 15:04:15 INFO - mozversion platform_changeset: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 15:04:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:04:15 INFO - mozversion platform_version: 49.0a1 15:04:15 INFO - using testdate: 1461708255 15:04:15 INFO - actual date: 1461708255 15:04:15 INFO - starting webserver on 'localhost:49084' 15:04:15 INFO - SUITE-START | Running 2 tests 15:04:15 INFO - TEST-START | dromaeo_css 15:04:15 INFO - operating with platform_type : linux_ 15:04:15 INFO - Initialising browser for dromaeo_css test... 15:04:15 INFO - TEST-INFO | started process 30901 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBI27t7/profile http://localhost:49084/getInfo.html) 15:04:19 INFO - PROCESS | 30901 | __metrics Screen width/height:1600/1200 15:04:19 INFO - PROCESS | 30901 | colorDepth:24 15:04:19 INFO - PROCESS | 30901 | Browser inner width/height: 1024/697 15:04:19 INFO - PROCESS | 30901 | __metrics 15:04:20 ERROR - PROCESS | 30901 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:04:21 INFO - TEST-INFO | 30901: exit 0 15:04:21 INFO - Browser initialized. 15:04:21 INFO - Running cycle 1/1 for dromaeo_css test... 15:04:21 INFO - TEST-INFO | started process 30985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBI27t7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:04:32 INFO - PROCESS | 30985 | RSS: Main: 154693632 15:04:32 INFO - PROCESS | 30985 | 15:05:24 INFO - PROCESS | 30985 | 1461708324145 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] 15:07:31 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49084/tests/dromaeo/cssquery-ext.html) 15:07:32 INFO - PROCESS | 30985 | RSS: Main: 241651712 15:07:32 INFO - PROCESS | 30985 | 15:10:35 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-ext.html (next: http://localhost:49084/tests/dromaeo/cssquery-jquery.html) 15:10:35 INFO - PROCESS | 30985 | RSS: Main: 234475520 15:10:35 INFO - PROCESS | 30985 | 15:13:37 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49084/tests/dromaeo/cssquery-mootools.html) 15:13:38 INFO - PROCESS | 30985 | RSS: Main: 246636544 15:13:38 INFO - PROCESS | 30985 | 15:16:40 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49084/tests/dromaeo/cssquery-prototype.html) 15:16:40 INFO - PROCESS | 30985 | RSS: Main: 237297664 15:16:40 INFO - PROCESS | 30985 | 15:19:43 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49084/tests/dromaeo/cssquery-yui.html) 15:19:44 INFO - PROCESS | 30985 | RSS: Main: 230023168 15:19:44 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | Cycle 1(1): loaded http://localhost:49084/tests/dromaeo/cssquery-yui.html (next: http://localhost:49084/tests/dromaeo/cssquery-dojo.html) 15:22:46 INFO - PROCESS | 30985 | RSS: Main: 274649088 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | __start_tp_report 15:22:46 INFO - PROCESS | 30985 | _x_x_mozilla_page_load 15:22:46 INFO - PROCESS | 30985 | _x_x_mozilla_page_load_details 15:22:46 INFO - PROCESS | 30985 | |i|pagename|runs| 15:22:46 INFO - PROCESS | 30985 | |0;dojo.html;3136.1062097302265;3168.065420700893;3203.2152122729935;3206.647268800433;3242.4649932761095;5336.6531175473565;5360.865978350544;5364.624476286662;5448.073827449333;5467.972660136698;5346;5386.703731294777;5425.457454254572;5472.50747432731;5474.343078830546;4868.902621947568;4899.608031357483;4914.11545921734;4921.753912304371;5053.191489361703;4958.256261560777;5003.424606170301;5040.798368065273;5043.218301163325;5046.167382381903;4834.879128021792;4864.975675121619;4918.458969513351;4976.62675299354;4986.177280748672;4904.313396124546;4917.4344950330815;4924.630652701062;4941.209406494943;4946.703197808143;4863.635227357927;4890.535399137077;4899.069176856386;4905.2642103684375;4932.8520144395725;5224.843254702327;5341.759620817073;5420.268263784378;5430.239766432663;5461.781528738846;5798.797042103548;5835.649861008311;5836.416358364172;5855.619384739978;5859.384764599698;5902.232709747748;5908.113782932552;5925.7333419995775;5932.9406705932815;5932.970335148297;5357.303550538405;5474.343078830526;5544.861378465571;5594.552435805166;5598.608097433182;5888.234529511136;5888.617239879394;5892.852678683067;5898.911516327258;5955.791547295824;5638.464345887134;5653.236813030188;5660.349059858087;5660.433956604389;5679.914801277984;5755.59710820247;5828.242328497353;5844.6785426801935;5879.353271140171;5881.5000724938;5767.596268261182;5768.480836724715;5775.451332123442;5779.0464573344925;5787.768489260467;5698.857528561819;5755.424457554211;5788.218590490314;5795.623284486495;5827.388124246893;4397.406350142754;4400.713953593006;4416.53626369233;4438.844640437634;4443.00032492693;3911.02224443889;3914.099757055893;3921.3529767588598;3922.2547715933883;3963.3460479021214;5381.811636592701;5432.592555558349;5464.480874316933;5468.234447177399;5526.088195447706;5727.198192253088;5741.282339707536;5775.971120144456;5804.042333015005;5812.796552120655;5435.048866448435;5439.3200849893765;5449.209864569615;5459.071957767189;5459.481349271813;4869.95130048695;4940.160172770566;4972.776225069881;4990.8253211137435;5032.345795046621;6003.609765365237;6048.788292409832;6061.545384096122;6061.545384096122;6076.756929722762;5829.387914269026;5846.766129354778;5849.298084229919;5850.0932355484965;5861.238039054895;5869.618474799125;6042.818715438544;6058.636481811105;6104.428839602322;6135.417135372132;5657.547396208376;5706.343770466449;5710.4004079572705;5713.857153571194;5770.2498675171955;5796.913046304393;5798;5803.9419605803405;5807.970960145172;5818.272715910512;5498.5876059296515;5663.716814159193;5735.54115670737;5750.281214848144;5781.913271301018;5641.492265696107;5689.34572524165;5698.629589076697;5699.772009119589;5705.686187259741;5480.177973303877;5596.356419011866;5597.468240517144;5597.748101335531;5605.859853503695;5632.211490391266;5687.71561421919;5694.430556944438;5703.173039909107;5741;5555.333359996826;5555.388907220284;5558.082916318924;5560.443955604407;5609.523190528851 15:22:46 INFO - PROCESS | 30985 | |1;ext.html;643.5720246036386;648.6237981970455;649.4512137244028;654.7970129259927;667.5961043568642;29894.252643683893;30410.847945760266;37149.25701485972;38092.238155236875;38153.046173845665;38676;39171.60828391715;39208.80395598025;39259.80370098146;39275;8412.158784121584;8618.095100014501;8623.698170564032;8624.568771561428;8626.353023523261;8482.745517634481;8626.741197764077;8639.568021598927;8643.222110010098;8647.178518040777;8807.64769409226;8888.06675299094;8894.688685895995;8904.955475222614;8910.064443233465;8796.69211577595;8878.24534914533;8885.333599979962;8887.244584210383;8888.555572221363;8650.913490865136;8785.516796576187;8785.736427907173;8791.120887911222;8796.8680469793;39035.60964390348;39309;39501.60498395008;39592.802035989924;39630.80184599087;3665.6151104133946;3748.3253014457387;3885.747426417274;4059.7158198926086;4210.347396153601;29868.70131298681;30333.848330758206;30580;30783.384332313453;31950.840245798856;3729.9254014919547;3849.711271654637;3873.5545412277665;3920.7255492115564;3922.784246866421;261190;262347;262497;262761;262920;44532.3320150198;54794.726026369615;55558;55619.721901390236;55728.72135639377;53712.73143634257;55240.17139742907;55391;55424;55465.44534554603;15115.622109447351;15340.769888451681;15357.846421535642;15360.615984600474;15361.462348817739;10380.792384152275;11334.773304533863;12529.624111276677;12537;12539.122261441611;16709.498715038568;16878.662426751398;16913.492595222164;17061.40285090016;17157.142142892804;5907.455526683955;5911.822645320647;5943.197668314809;5953.583249172603;6107.4197951194565;1004.2618675273668;1038.4651904269308;1066.0618655583037;1075.6504136155781;1104.9558017679365;30862.537061944087;31065.844670776954;31135.377292454476;31137.5215609639;31929.201769955936;1139.2082502660571;1166.702490864824;1167.5563285951284;1199.1725709260581;1206.9598705694286;16476.176191190392;16558.503244902673;16613.3354665812;16651.751118666147;16913.577160571083;342124;346172;346948;347160;347259;53456;54202.72898635561;54552.727236363564;54834;54879;1262.1543565810928;1403.1931639307438;1438.7841823726314;1441.3802065112102;1517.8254500732337;29353.266168345963;30803.84598077085;31187.220319492193;31300.686993130686;31975.200619984687;45450.772746136056;45558.54441455543;45864.77067614641;45874.54125458703;45950.31074533951;241999;243367;243396;243817;244197;31237.219069523442;36502.45246321254;37040.25919481649;37236.255274894895;37480.625193747714;41608.791956041234;41768.58231417647;41792;42017.57982420137;42497.57502424936;35408.645913541564;35588.466173006906;35755.10612234715;36206;36543;34774.82612586921;35615.46576801298;36041.639583604876;36089;38361.808190958865;35888.64111358853;37122;37333.253334933695;38774.22451550897;38853.41719874261;31405;32298.677013230506;32414.351712965137;32808.179795505304;33212.833935831135;25292.620610690487;25844.870775646003;26962.191134266002;27162.592561111993;27274.09081364365 15:22:46 INFO - PROCESS | 30985 | |2;jquery.html;380.5452484281285;383.28332914579056;393.14687128930217;399.7002248313765;402.10732174572445;17119.743203851922;17352.30590776369;17709.734353984684;17768.733468997976;17845.464636060922;16564.503064908044;17546.82453175468;18137.637247255047;18357.173927173277;18664.16011279492;5552.944470555293;5614.073677843171;5648.971755141219;5685.374608793029;5730.7421166047625;5432.13085906255;5587.916181257285;5600.299962504687;5622.8313150605345;5624.409437009116;5514.117741161415;5552.11166213406;5571.637843540157;5572.58205634577;5657.773689052433;5478.91781623276;5600.4959553639965;5607.719614019283;5633.746481408347;5635.718214089279;4800.375951126366;4984.67599606028;4985.5513003829465;5020.874478138039;5045.041442125985;21698.457538561503;21764.564708705897;21919.342419727433;22220.333389998326;22272.443188920246;2187.2781981946077;2306.6078766609717;2361.740208577055;2921.4595299869593;3150.82670453125;9797.265205109645;10053.79892402148;12673.859352658214;12989.830915217675;13220.86779132206;1873.3443294846832;1938.176275083098;1995.1520603743495;2010.4672261850621;2115.9259425920172;66268;68175;69176;70981.99999999949;71463.28536714618;10392.844107338396;10445.11216546587;10457.529411176516;10575.298645474022;10941.398223097647;14154;14241.78637320441;14242.643933901736;14246.216458094905;14276.57170284893;7796.610169491502;8002.1997800219515;8067.39494537912;8128.431009829255;8193.549354785544;7821.178776228528;7868.291853733192;7871.921280787234;7968.362530997506;8005.119436861941;4410.051838854662;4472.552744725502;4506.977465112654;4593.563611456906;4661.254199328091;1771.7785276840395;1825.799162092169;1837.6508463391913;1845;1864.4499872537633;991.1822746234385;993.2500961773815;1005.1255407795119;1008.1078245752415;1136.772645470909;17570.648587028187;17973;18151.27394904189;18249.270029198684;18918.81081189198;1010.0959641121302;1012.4937531234383;1026.3328836256344;1042.921780866438;1045.0176833776227;10399.688009359732;10544.209184311207;10624.309419887682;10749.281698938164;10821.242513024014;434848;448172;462795;471567;473923;80595;81037.59481202556;81744.59127704323;82158;84091;1037.9896201037893;1040.490159821682;1083.9241253112364;1091.8908109189017;1101.719061639293;16282.51152465428;16499.752503712214;16540.917295413445;16598.75101873449;16757.245923933697;37284.62715372811;38084.42873356957;38805.80597096997;39494;39548.209035818545;52512.73743631257;52648;52913.735431322595;53038.46961530335;53150.734246328524;7892.816077588178;8023.398245131521;8188.181181881764;8243.505389676639;8267.131951145044;11459.31244125355;12309.93845030769;12415.732005503867;12527.31099789484;12896.548620798227;10644.946775266075;11193.608223712132;11351.829722554003;11501.367424791717;11525.88474115248;10581.947090264499;10742;10915.836262456229;10921.017108460277;10976.286541374786;10700.571977120828;11003.284786489174;11067.50196241155;11375.20373573842;11510.07919366432;8038.517688938682;8152.388570857091;8179.877301840358;8307.6676932922;8401.32789376836;10056.69829905104;10999.285046471954;11034.724131896766;11038.392888391216;11229 15:22:46 INFO - PROCESS | 30985 | |3;mootools.html;1346.670065833871;1446.3202294921387;1471.168789633857;1605.6547842213922;1750.2648887467255;7530.171681115073;7853.175416581263;7860.68557257709;7861.174576669446;7868.96065519673;7556.584387858666;7785.221477852212;7787.766367008985;7798.220177982199;7802.297793198611;1967.6851703727334;2200.185931205456;2235.8434909556336;2260.400993736661;2282.8858557072163;2272.8522646027955;2275.419767959176;2284.132029828671;2284.7029886114783;2286.9885650571805;2207.0399376271375;2249.640057590786;2255.075419078178;2258.2773512476015;2258.6386178211487;1919.7504324437873;2199.6260635691892;2256.0637335505744;2259.9322020339414;2265.728112626479;2103.3374487036535;2109.2301310021885;2113.545587698652;2115.862468939514;2193.484531135182;7702.576358300291;7820.765377038698;7832.608369581555;7832.647530861125;7893.605319734048;4764.523547645243;4901.803927842882;4934.55588996992;4939.604831613462;4969.378827646544;3019.064090132066;3048.634163900324;3089.459344614706;3151.637561680413;3172.127664892173;967.1489089601106;990.7225976726528;991.4051569058579;1000.6997900629782;1017.9389236645751;11000.504977276041;11003.009729124416;11013.118950483942;11019.83470247947;11024.283421577651;4248.723832950848;4269.082147338338;4331.960329520955;4340.110277393127;4342.869713908588;9317.44095354281;9399.060093990684;9400.482973436527;9406.106419890237;9425.340226184238;4375.562443755599;4854.101991131652;4874.073925954058;4884.731339776346;4884.8290309838985;4112.609302116294;4133.379993001074;4421.7789110544345;4540.137373898949;4581.931271030937;3635.309291234657;3710.7402481826553;3720.6837418819705;3763.7365384423374;3792.2984247914223;1221.5541327415428;1226.920250183735;1235.0119904076703;1245;1247.544646204147;789.5026133535836;921.8156368726281;942.5522876633544;950.2018304624011;986.659602437158;5356.143017117242;5491.395946445888;5499.890002199933;5621.971890140523;5711.143328500675;434.9543297953669;447.1191752248066;447.69780398231717;450.70929250633156;452.6243218128945;3762.6801721853403;3885.825137868803;3891.5330160380367;3893.396523538856;3914.197589494147;9423.905760942303;9835.901640983642;9863.556139973718;9928.305018648625;9950.054744799378;9679.516024198761;9719.708408747749;9811.31320807551;9823;9863.457509836884;7195.280471952868;7260.273972602803;7321.487495875238;7352.77941661751;7359.227281135511;5422.810201642924;5471.343438787371;5489.223289848066;5500.202470641654;5552.7778888843995;9000.414973026733;9126.315526335684;9127.08729127082;9128.49793261377;9221.49281789508;8908.777280567778;9128.726138215865;9149.542522873697;9181.908180918372;9183;6378.681065946591;6981.5112942093565;7019.087518622547;7182.60495672743;7185.065941427581;7672.61636918163;7681;7706.344960678405;7765.92234077652;7784.182660820758;6721.93278067213;6953.548019378724;6965.825854353479;7044.260352662896;7095.361417472453;6912.619805910522;6917.066196063467;6924.896126558207;7028.262032486516;7054.823629409306;6098.451139397475;6244.406781355764;6281.748730050746;6323.715432805443;6347.428731414195;4739.786709598007;4899.289603007615;4924.089043526959;4935.284383764262;4996.775145118551;4948.233023881304;4961.404631444249;4963.379577552806;5001.474845141208;5006.949930500648 15:22:46 INFO - PROCESS | 30985 | |4;prototype.html;314.32890778188573;322.4389562161839;325.6434204546021;326.2626464190931;330.9884154054608;5057.216131499615;5142.820001299955;5176.741162941852;5222.660527065743;5225.137852254378;5159.922601160985;5181;5205.817796377128;5210.505002024802;5232.398274198468;4116.938245926313;4123.628873401393;4124;4142.204012291706;4152.086540960984;3898.7660740355527;3920.411938209263;4109.260333140033;4118.814653340592;4128.153728485669;4043.595640435962;4084.65280451162;4104.897377565555;4117.732347397409;4123.855665051724;4056.8580099696665;4062.329715596923;4081.3061779497407;4082.101937573729;4095.9795201023803;3557.51973483579;3616.8553257869653;3639.2357604903004;3639.636036396366;3666.578343490506;4974.333598244592;4983.103041452537;5009.323741294915;5017.598592112622;5020.2720605511995;4634.142683603544;4703.272102180761;4707.435107787053;4725.810967561293;4767.809287628491;5264.236685680593;5433.673979561199;5464.3442786865435;5469.316335458067;5470.261514695505;4016.7389119707127;4022.9597704022876;4037.4455834503633;4041.5756345583586;4055.1078762672164;5655.717214139277;5685.346185188715;5688.175214593902;5702.657840529581;5737.88524229513;4125.628693417607;4136.896577585524;4148.460700108966;4157.937630935538;4173.332266837291;3871.709621778378;4036.737612055207;4040.474738284004;4083.387491876183;4086.141910198832;4612.7924243409125;4625.606823420047;4640.373549570832;4643.349931009661;4652.511486293958;5493.670379777226;5494.527462641827;5500.257465242221;5522.475364840334;5545;1381.3714759784275;1448.6161167290677;1483.7477628803128;1486.3608648281343;1489.1735087026805;974.65886939572;977.3402953006835;984.0897170117755;989.7674046599044;1003.9899601003897;5584.106542953108;5615.971920140373;5623.353314368818;5625.746841392065;5645.23789288449;5925.4074592541265;5929.932612129772;5943.375945525744;5958.970205148947;5977.551683623746;5457.972710136424;5464.781408743605;5559.360673522516;5560.666360018412;5639.605227634108;3146.6381366143187;3155.605549306337;3155.921101972423;3168.904932852018;3185.677943653358;5642.971785141048;6053.485453736482;6104.511639068864;6133.969330153321;6163.537734669918;5802.767889284381;5811.418858114154;5819.79630712923;5875.236219291465;5908.675022873783;5859.414058594191;5880.266031070216;5891.528677705774;5905.557083218776;5922.289325280994;5573.498385145356;5621.145022142765;5728.942710573007;5734.025215713255;5742.5118864895285;5653.434656534314;5684.431556844283;5700.978481404862;5705.885882282414;5721.713914304185;5623;5882.23530940975;5903.793367232126;5921.703914804157;5925.970370148121;4028.576999415019;4062.898427539335;4064.4148781974463;4068.8372465101065;4085.3667681509414;5476.835694929158;5634.985282873643;5724.542036636975;5725.885482290248;5769.9711501442225;5449.182622606482;5470.781168753365;5507.284053073075;5565.749541270734;5613.74738136793;5479.6164268500825;5492.560595152298;5499.2576002240785;5524.558035357242;5551.25058117149;5252.65857719247;5415.187721841755;5443.7822487100075;5466.453354664502;5474.919530578355;5541.5289700375915;5560.388357280777;5568.136938774496;5568.9443105568425;5581.916271255853;5264.763085661078;5461.863453413696;5474.686075341969;5485.259489968803;5488.231647569423 15:22:46 INFO - PROCESS | 30985 | |5;yui.html;732.5458215906139;793.9245771651694;797.0873350014234;814.6334149632663;847.3856454070796;13633;13659;13762.38069286882;13795.2412617306;13851.03042787004;12618.432170552323;12936.676583085427;12957.74084518309;12958;12975.351232438388;682.3858527325422;793.8293266947605;810.1210186947154;823.9217274358926;835.6573804740058;825.7770401991459;825.859603867344;826.2604968553171;829.2288172000037;829.5603330234985;862.874883141944;863.1153068105211;864.7578677970179;865.5758678247645;867.2108381372952;705.8411857332085;732.2969948849085;732.9633518324063;828.9212524810133;856.3192262151605;621.0745988477182;625.5214760708062;625.5433533520555;625.5933643131955;625.9624422534663;13649;13724.93137534316;13736.038477306593;13745.38145783442;13748.450061997508;13949.721005579933;14265.714685706227;14272.357743901446;14539.273036348246;14550.054246473945;10435.895641043568;10521.421321827296;10910.5090270938;10985.231033827635;11248.493817778219;3868.6905047596406;3870.5454761822043;3897.2205558888054;3951.6245956634075;3985.5217373915025;58757.41242587562;58997;59101.7044914777;59622;59956.40043599595;10012.549435275285;10286.639967601099;10372.377657340583;10411.167106631452;10422.374657520422;18846.81153188478;18978.90510547466;19194.424167274723;19240.23039078449;19252.807471925382;8061.798455038554;8065.153158918347;8074.838503229903;8086.237331164802;8234.464759790595;4101.710458115743;4417.359482874966;4432.423784907941;4437;4503.121891231179;3179.4277030134335;3225.774195806271;3233.757468189848;3242.4487837067754;3244.383567122239;598.0270051203039;601.173386593434;604.3835288006297;606.6057062909144;613.3345320327405;668.7057694614355;714.3499415531841;727.3635568877232;736.4255880413286;749.0861149397726;9137.77155571116;9208.677696280598;9244.399114057565;9286.396384235004;9358.672446464341;704.9471289653296;705.1960764728213;727.294524311417;756.2513112019061;764.1098120689446;4167.124903770242;4184.497860256789;4189.162167566499;4201.495820501498;4206.47419072616;68347.31652683509;69123;72869;72993;73119.26880731231;59238;60302.095468568004;60739;61383.69308153431;61701;51760;52336.73831630894;52386.21420678693;54602.72698636481;54649.45350546523;8098.51408915467;8214.708486277405;8277.917220827714;8453.915460845314;8577.142285771373;35225.47161792626;35337.46993795044;35385.82307088448;36275.63724362723;36586.26827463489;46533;49242.75378623084;49370.259446107615;49805;51513.22730158976;10631;10730.624428144978;10832.891671083189;10838.078763305208;11096.556137754722;11747.530098795953;11749.471273792871;11795.115366347387;12244.816327754912;12274.877251227374;11577.131715121232;11736.119791015879;11782;11923.105767067678;12189.69525761863;12237.326947018;12697.301648409426;12841.229526228457;13071.411786469442;13102.410391532594;12143.635690929286;12456.813147802972;12638.55765048219;12947.417366218355;12947.935260323638;9870.703878883645;10129.341592796445;10277.244742943974;10592.152627789605;10757.970623770681;9674.75813104678;10051.447170405698;10061.899381006097;10495.370277783357;10783.406912619881 15:22:46 INFO - PROCESS | 30985 | __end_tp_report 15:22:46 INFO - PROCESS | 30985 | __start_cc_report 15:22:46 INFO - PROCESS | 30985 | _x_x_mozilla_cycle_collect,761 15:22:46 INFO - PROCESS | 30985 | __end_cc_report 15:22:46 INFO - PROCESS | 30985 | __startTimestamp1461709366911__endTimestamp 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | ------- Summary: start ------- 15:22:46 INFO - PROCESS | 30985 | Number of tests: 6 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#0] dojo.html Cycles:165 Average:5392.22 Median:5597.75 stddev:613.99 (11.0%) stddev-sans-first:589.81 15:22:46 INFO - PROCESS | 30985 | Values: 3136.1 3168.1 3203.2 3206.6 3242.5 5336.7 5360.9 5364.6 5448.1 5468.0 5346.0 5386.7 5425.5 5472.5 5474.3 4868.9 4899.6 4914.1 4921.8 5053.2 4958.3 5003.4 5040.8 5043.2 5046.2 4834.9 4865.0 4918.5 4976.6 4986.2 4904.3 4917.4 4924.6 4941.2 4946.7 4863.6 4890.5 4899.1 4905.3 4932.9 5224.8 5341.8 5420.3 5430.2 5461.8 5798.8 5835.6 5836.4 5855.6 5859.4 5902.2 5908.1 5925.7 5932.9 5933.0 5357.3 5474.3 5544.9 5594.6 5598.6 5888.2 5888.6 5892.9 5898.9 5955.8 5638.5 5653.2 5660.3 5660.4 5679.9 5755.6 5828.2 5844.7 5879.4 5881.5 5767.6 5768.5 5775.5 5779.0 5787.8 5698.9 5755.4 5788.2 5795.6 5827.4 4397.4 4400.7 4416.5 4438.8 4443.0 3911.0 3914.1 3921.4 3922.3 3963.3 5381.8 5432.6 5464.5 5468.2 5526.1 5727.2 5741.3 5776.0 5804.0 5812.8 5435.0 5439.3 5449.2 5459.1 5459.5 4870.0 4940.2 4972.8 4990.8 5032.3 6003.6 6048.8 6061.5 6061.5 6076.8 5829.4 5846.8 5849.3 5850.1 5861.2 5869.6 6042.8 6058.6 6104.4 6135.4 5657.5 5706.3 5710.4 5713.9 5770.2 5796.9 5798.0 5803.9 5808.0 5818.3 5498.6 5663.7 5735.5 5750.3 5781.9 5641.5 5689.3 5698.6 5699.8 5705.7 5480.2 5596.4 5597.5 5597.7 5605.9 5632.2 5687.7 5694.4 5703.2 5741.0 5555.3 5555.4 5558.1 5560.4 5609.5 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#1] ext.html Cycles:180 Average:45419.77 Median:37081.13 stddev:75076.01 (202.5%) stddev-sans-first:75211.34 15:22:46 INFO - PROCESS | 30985 | Values: 643.6 648.6 649.5 654.8 667.6 29894.3 30410.8 37149.3 38092.2 38153.0 38676.0 39171.6 39208.8 39259.8 39275.0 8412.2 8618.1 8623.7 8624.6 8626.4 8482.7 8626.7 8639.6 8643.2 8647.2 8807.6 8888.1 8894.7 8905.0 8910.1 8796.7 8878.2 8885.3 8887.2 8888.6 8650.9 8785.5 8785.7 8791.1 8796.9 39035.6 39309.0 39501.6 39592.8 39630.8 3665.6 3748.3 3885.7 4059.7 4210.3 29868.7 30333.8 30580.0 30783.4 31950.8 3729.9 3849.7 3873.6 3920.7 3922.8 261190.0 262347.0 262497.0 262761.0 262920.0 44532.3 54794.7 55558.0 55619.7 55728.7 53712.7 55240.2 55391.0 55424.0 55465.4 15115.6 15340.8 15357.8 15360.6 15361.5 10380.8 11334.8 12529.6 12537.0 12539.1 16709.5 16878.7 16913.5 17061.4 17157.1 5907.5 5911.8 5943.2 5953.6 6107.4 1004.3 1038.5 1066.1 1075.7 1105.0 30862.5 31065.8 31135.4 31137.5 31929.2 1139.2 1166.7 1167.6 1199.2 1207.0 16476.2 16558.5 16613.3 16651.8 16913.6 342124.0 346172.0 346948.0 347160.0 347259.0 53456.0 54202.7 54552.7 54834.0 54879.0 1262.2 1403.2 1438.8 1441.4 1517.8 29353.3 30803.8 31187.2 31300.7 31975.2 45450.8 45558.5 45864.8 45874.5 45950.3 241999.0 243367.0 243396.0 243817.0 244197.0 31237.2 36502.5 37040.3 37236.3 37480.6 41608.8 41768.6 41792.0 42017.6 42497.6 35408.6 35588.5 35755.1 36206.0 36543.0 34774.8 35615.5 36041.6 36089.0 38361.8 35888.6 37122.0 37333.3 38774.2 38853.4 31405.0 32298.7 32414.4 32808.2 33212.8 25292.6 25844.9 26962.2 27162.6 27274.1 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#2] jquery.html Cycles:180 Average:26962.23 Median:22069.84 stddev:75341.80 (341.4%) stddev-sans-first:75526.72 15:22:46 INFO - PROCESS | 30985 | Values: 380.5 383.3 393.1 399.7 402.1 17119.7 17352.3 17709.7 17768.7 17845.5 16564.5 17546.8 18137.6 18357.2 18664.2 5552.9 5614.1 5649.0 5685.4 5730.7 5432.1 5587.9 5600.3 5622.8 5624.4 5514.1 5552.1 5571.6 5572.6 5657.8 5478.9 5600.5 5607.7 5633.7 5635.7 4800.4 4984.7 4985.6 5020.9 5045.0 21698.5 21764.6 21919.3 22220.3 22272.4 2187.3 2306.6 2361.7 2921.5 3150.8 9797.3 10053.8 12673.9 12989.8 13220.9 1873.3 1938.2 1995.2 2010.5 2115.9 66268.0 68175.0 69176.0 70982.0 71463.3 10392.8 10445.1 10457.5 10575.3 10941.4 14154.0 14241.8 14242.6 14246.2 14276.6 7796.6 8002.2 8067.4 8128.4 8193.5 7821.2 7868.3 7871.9 7968.4 8005.1 4410.1 4472.6 4507.0 4593.6 4661.3 1771.8 1825.8 1837.7 1845.0 1864.4 991.2 993.3 1005.1 1008.1 1136.8 17570.6 17973.0 18151.3 18249.3 18918.8 1010.1 1012.5 1026.3 1042.9 1045.0 10399.7 10544.2 10624.3 10749.3 10821.2 434848.0 448172.0 462795.0 471567.0 473923.0 80595.0 81037.6 81744.6 82158.0 84091.0 1038.0 1040.5 1083.9 1091.9 1101.7 16282.5 16499.8 16540.9 16598.8 16757.2 37284.6 38084.4 38805.8 39494.0 39548.2 52512.7 52648.0 52913.7 53038.5 53150.7 7892.8 8023.4 8188.2 8243.5 8267.1 11459.3 12309.9 12415.7 12527.3 12896.5 10644.9 11193.6 11351.8 11501.4 11525.9 10581.9 10742.0 10915.8 10921.0 10976.3 10700.6 11003.3 11067.5 11375.2 11510.1 8038.5 8152.4 8179.9 8307.7 8401.3 10056.7 10999.3 11034.7 11038.4 11229.0 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#3] mootools.html Cycles:180 Average:5272.03 Median:5004.21 stddev:2936.78 (58.7%) stddev-sans-first:2930.20 15:22:46 INFO - PROCESS | 30985 | Values: 1346.7 1446.3 1471.2 1605.7 1750.3 7530.2 7853.2 7860.7 7861.2 7869.0 7556.6 7785.2 7787.8 7798.2 7802.3 1967.7 2200.2 2235.8 2260.4 2282.9 2272.9 2275.4 2284.1 2284.7 2287.0 2207.0 2249.6 2255.1 2258.3 2258.6 1919.8 2199.6 2256.1 2259.9 2265.7 2103.3 2109.2 2113.5 2115.9 2193.5 7702.6 7820.8 7832.6 7832.6 7893.6 4764.5 4901.8 4934.6 4939.6 4969.4 3019.1 3048.6 3089.5 3151.6 3172.1 967.1 990.7 991.4 1000.7 1017.9 11000.5 11003.0 11013.1 11019.8 11024.3 4248.7 4269.1 4332.0 4340.1 4342.9 9317.4 9399.1 9400.5 9406.1 9425.3 4375.6 4854.1 4874.1 4884.7 4884.8 4112.6 4133.4 4421.8 4540.1 4581.9 3635.3 3710.7 3720.7 3763.7 3792.3 1221.6 1226.9 1235.0 1245.0 1247.5 789.5 921.8 942.6 950.2 986.7 5356.1 5491.4 5499.9 5622.0 5711.1 435.0 447.1 447.7 450.7 452.6 3762.7 3885.8 3891.5 3893.4 3914.2 9423.9 9835.9 9863.6 9928.3 9950.1 9679.5 9719.7 9811.3 9823.0 9863.5 7195.3 7260.3 7321.5 7352.8 7359.2 5422.8 5471.3 5489.2 5500.2 5552.8 9000.4 9126.3 9127.1 9128.5 9221.5 8908.8 9128.7 9149.5 9181.9 9183.0 6378.7 6981.5 7019.1 7182.6 7185.1 7672.6 7681.0 7706.3 7765.9 7784.2 6721.9 6953.5 6965.8 7044.3 7095.4 6912.6 6917.1 6924.9 7028.3 7054.8 6098.5 6244.4 6281.7 6323.7 6347.4 4739.8 4899.3 4924.1 4935.3 4996.8 4948.2 4961.4 4963.4 5001.5 5006.9 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#4] prototype.html Cycles:180 Average:4685.60 Median:5438.73 stddev:1376.70 (25.3%) stddev-sans-first:1340.89 15:22:46 INFO - PROCESS | 30985 | Values: 314.3 322.4 325.6 326.3 331.0 5057.2 5142.8 5176.7 5222.7 5225.1 5159.9 5181.0 5205.8 5210.5 5232.4 4116.9 4123.6 4124.0 4142.2 4152.1 3898.8 3920.4 4109.3 4118.8 4128.2 4043.6 4084.7 4104.9 4117.7 4123.9 4056.9 4062.3 4081.3 4082.1 4096.0 3557.5 3616.9 3639.2 3639.6 3666.6 4974.3 4983.1 5009.3 5017.6 5020.3 4634.1 4703.3 4707.4 4725.8 4767.8 5264.2 5433.7 5464.3 5469.3 5470.3 4016.7 4023.0 4037.4 4041.6 4055.1 5655.7 5685.3 5688.2 5702.7 5737.9 4125.6 4136.9 4148.5 4157.9 4173.3 3871.7 4036.7 4040.5 4083.4 4086.1 4612.8 4625.6 4640.4 4643.3 4652.5 5493.7 5494.5 5500.3 5522.5 5545.0 1381.4 1448.6 1483.7 1486.4 1489.2 974.7 977.3 984.1 989.8 1004.0 5584.1 5616.0 5623.4 5625.7 5645.2 5925.4 5929.9 5943.4 5959.0 5977.6 5458.0 5464.8 5559.4 5560.7 5639.6 3146.6 3155.6 3155.9 3168.9 3185.7 5643.0 6053.5 6104.5 6134.0 6163.5 5802.8 5811.4 5819.8 5875.2 5908.7 5859.4 5880.3 5891.5 5905.6 5922.3 5573.5 5621.1 5728.9 5734.0 5742.5 5653.4 5684.4 5701.0 5705.9 5721.7 5623.0 5882.2 5903.8 5921.7 5926.0 4028.6 4062.9 4064.4 4068.8 4085.4 5476.8 5635.0 5724.5 5725.9 5770.0 5449.2 5470.8 5507.3 5565.7 5613.7 5479.6 5492.6 5499.3 5524.6 5551.3 5252.7 5415.2 5443.8 5466.5 5474.9 5541.5 5560.4 5568.1 5568.9 5581.9 5264.8 5461.9 5474.7 5485.3 5488.2 15:22:46 INFO - PROCESS | 30985 | 15:22:46 INFO - PROCESS | 30985 | [#5] yui.html Cycles:180 Average:15362.25 Median:4434.71 stddev:18971.55 (427.8%) stddev-sans-first:18992.96 15:22:46 INFO - PROCESS | 30985 | Values: 732.5 793.9 797.1 814.6 847.4 13633.0 13659.0 13762.4 13795.2 13851.0 12618.4 12936.7 12957.7 12958.0 12975.4 682.4 793.8 810.1 823.9 835.7 825.8 825.9 826.3 829.2 829.6 862.9 863.1 864.8 865.6 867.2 705.8 732.3 733.0 828.9 856.3 621.1 625.5 625.5 625.6 626.0 13649.0 13724.9 13736.0 13745.4 13748.5 13949.7 14265.7 14272.4 14539.3 14550.1 10435.9 10521.4 10910.5 10985.2 11248.5 3868.7 3870.5 3897.2 3951.6 3985.5 58757.4 58997.0 59101.7 59622.0 59956.4 10012.5 10286.6 10372.4 10411.2 10422.4 18846.8 18978.9 19194.4 19240.2 19252.8 8061.8 8065.2 8074.8 8086.2 8234.5 4101.7 4417.4 4432.4 4437.0 4503.1 3179.4 3225.8 3233.8 3242.4 3244.4 598.0 601.2 604.4 606.6 613.3 668.7 714.3 727.4 736.4 749.1 9137.8 9208.7 9244.4 9286.4 9358.7 704.9 705.2 727.3 756.3 764.1 4167.1 4184.5 4189.2 4201.5 4206.5 68347.3 69123.0 72869.0 72993.0 73119.3 59238.0 60302.1 60739.0 61383.7 61701.0 51760.0 52336.7 52386.2 54602.7 54649.5 8098.5 8214.7 8277.9 8453.9 8577.1 35225.5 35337.5 35385.8 36275.6 36586.3 46533.0 49242.8 49370.3 49805.0 51513.2 10631.0 10730.6 10832.9 10838.1 11096.6 11747.5 11749.5 11795.1 12244.8 12274.9 11577.1 11736.1 11782.0 11923.1 12189.7 12237.3 12697.3 12841.2 13071.4 13102.4 12143.6 12456.8 12638.6 12947.4 12947.9 9870.7 10129.3 10277.2 10592.2 10758.0 9674.8 10051.4 10061.9 10495.4 10783.4 15:22:46 INFO - PROCESS | 30985 | -------- Summary: end -------- 15:22:46 INFO - PROCESS | 30985 | 15:22:47 INFO - TEST-INFO | 30985: exit 0 15:22:47 INFO - TEST-OK | dromaeo_css | took 1112535ms 15:22:47 INFO - TEST-START | kraken 15:22:47 INFO - operating with platform_type : linux_ 15:22:47 INFO - Initialising browser for kraken test... 15:22:47 INFO - TEST-INFO | started process 31172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD0ev52/profile http://localhost:49084/getInfo.html) 15:22:51 INFO - PROCESS | 31172 | __metrics Screen width/height:1600/1200 15:22:51 INFO - PROCESS | 31172 | colorDepth:24 15:22:51 INFO - PROCESS | 31172 | Browser inner width/height: 1024/697 15:22:51 INFO - PROCESS | 31172 | __metrics 15:22:52 ERROR - PROCESS | 31172 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:22:53 INFO - TEST-INFO | 31172: exit 0 15:22:53 INFO - Browser initialized. 15:22:53 INFO - Running cycle 1/1 for kraken test... 15:22:53 INFO - TEST-INFO | started process 31254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD0ev52/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:23:05 INFO - PROCESS | 31254 | RSS: Main: 154312704 15:23:05 INFO - PROCESS | 31254 | 15:23:07 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49084/tests/kraken/driver.html?testName=audio-beat-detection) 15:23:08 INFO - PROCESS | 31254 | RSS: Main: 209256448 15:23:08 INFO - PROCESS | 31254 | 15:23:11 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49084/tests/kraken/driver.html?testName=audio-dft) 15:23:12 INFO - PROCESS | 31254 | RSS: Main: 195690496 15:23:12 INFO - PROCESS | 31254 | 15:23:15 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49084/tests/kraken/driver.html?testName=audio-fft) 15:23:15 INFO - PROCESS | 31254 | RSS: Main: 266309632 15:23:15 INFO - PROCESS | 31254 | 15:23:17 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49084/tests/kraken/driver.html?testName=audio-oscillator) 15:23:18 INFO - PROCESS | 31254 | RSS: Main: 174149632 15:23:18 INFO - PROCESS | 31254 | 15:23:20 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49084/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:23:21 INFO - PROCESS | 31254 | RSS: Main: 178466816 15:23:21 INFO - PROCESS | 31254 | 15:23:24 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49084/tests/kraken/driver.html?testName=imaging-darkroom) 15:23:24 INFO - PROCESS | 31254 | RSS: Main: 214831104 15:23:24 INFO - PROCESS | 31254 | 15:23:28 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49084/tests/kraken/driver.html?testName=imaging-desaturate) 15:23:28 INFO - PROCESS | 31254 | RSS: Main: 215527424 15:23:28 INFO - PROCESS | 31254 | 15:23:31 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49084/tests/kraken/driver.html?testName=json-parse-financial) 15:23:32 INFO - PROCESS | 31254 | RSS: Main: 215642112 15:23:32 INFO - PROCESS | 31254 | 15:23:33 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49084/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:23:34 INFO - PROCESS | 31254 | RSS: Main: 202108928 15:23:34 INFO - PROCESS | 31254 | 15:23:35 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:23:36 INFO - PROCESS | 31254 | RSS: Main: 205279232 15:23:36 INFO - PROCESS | 31254 | 15:23:38 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:23:39 INFO - PROCESS | 31254 | RSS: Main: 216711168 15:23:39 INFO - PROCESS | 31254 | 15:23:41 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:23:42 INFO - PROCESS | 31254 | RSS: Main: 224464896 15:23:42 INFO - PROCESS | 31254 | 15:23:45 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:23:46 INFO - PROCESS | 31254 | RSS: Main: 221655040 15:23:46 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | Cycle 1(1): loaded http://localhost:49084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49084/tests/kraken/driver.html?testName=ai-astar) 15:23:47 INFO - PROCESS | 31254 | RSS: Main: 285040640 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | __start_tp_report 15:23:47 INFO - PROCESS | 31254 | _x_x_mozilla_page_load 15:23:47 INFO - PROCESS | 31254 | _x_x_mozilla_page_load_details 15:23:47 INFO - PROCESS | 31254 | |i|pagename|runs| 15:23:47 INFO - PROCESS | 31254 | |0;ai-astar;111;112;107;110;111;111;101;111;111;112 15:23:47 INFO - PROCESS | 31254 | |1;audio-beat-detection;162;128;160;193;126;161;152;137;190;134 15:23:47 INFO - PROCESS | 31254 | |2;audio-dft;161;160;161;161;160;160;162;161;160;160 15:23:47 INFO - PROCESS | 31254 | |3;audio-fft;76;77;103;117;79;76;77;124;98;75 15:23:47 INFO - PROCESS | 31254 | |4;audio-oscillator;93;93;150;93;92;92;151;93;92;154 15:23:47 INFO - PROCESS | 31254 | |5;imaging-gaussian-blur;117;117;117;117;118;116;117;117;116;117 15:23:47 INFO - PROCESS | 31254 | |6;imaging-darkroom;146;146;145;145;145;146;144;145;145;145 15:23:47 INFO - PROCESS | 31254 | |7;imaging-desaturate;94;94;95;94;94;94;94;94;94;94 15:23:47 INFO - PROCESS | 31254 | |8;json-parse-financial;55;57;57;56;56;55;56;71;54;54 15:23:47 INFO - PROCESS | 31254 | |9;json-stringify-tinderbox;61;61;62;61;62;61;60;61;61;62 15:23:47 INFO - PROCESS | 31254 | |10;stanford-crypto-aes;76;74;75;84;76;77;77;78;78;77 15:23:47 INFO - PROCESS | 31254 | |11;stanford-crypto-ccm;131;128;125;129;132;123;125;131;163;125 15:23:47 INFO - PROCESS | 31254 | |12;stanford-crypto-pbkdf2;200;198;208;204;196;197;196;203;194;200 15:23:47 INFO - PROCESS | 31254 | |13;stanford-crypto-sha256-iterative;70;68;69;69;69;69;68;69;69;69 15:23:47 INFO - PROCESS | 31254 | __end_tp_report 15:23:47 INFO - PROCESS | 31254 | __start_cc_report 15:23:47 INFO - PROCESS | 31254 | _x_x_mozilla_cycle_collect,1661 15:23:47 INFO - PROCESS | 31254 | __end_cc_report 15:23:47 INFO - PROCESS | 31254 | __startTimestamp1461709427978__endTimestamp 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | ------- Summary: start ------- 15:23:47 INFO - PROCESS | 31254 | Number of tests: 14 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.37 (3.0%) stddev-sans-first:3.54 15:23:47 INFO - PROCESS | 31254 | Values: 111.0 112.0 107.0 110.0 111.0 111.0 101.0 111.0 111.0 112.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#1] audio-beat-detection Cycles:10 Average:154.30 Median:160.50 stddev:23.85 (14.9%) stddev-sans-first:25.13 15:23:47 INFO - PROCESS | 31254 | Values: 162.0 128.0 160.0 193.0 126.0 161.0 152.0 137.0 190.0 134.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 15:23:47 INFO - PROCESS | 31254 | Values: 161.0 160.0 161.0 161.0 160.0 160.0 162.0 161.0 160.0 160.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#3] audio-fft Cycles:10 Average:90.20 Median:76.50 stddev:18.84 (24.6%) stddev-sans-first:19.27 15:23:47 INFO - PROCESS | 31254 | Values: 76.0 77.0 103.0 117.0 79.0 76.0 77.0 124.0 98.0 75.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:92.50 stddev:28.57 (30.9%) stddev-sans-first:29.60 15:23:47 INFO - PROCESS | 31254 | Values: 93.0 93.0 150.0 93.0 92.0 92.0 151.0 93.0 92.0 154.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:23:47 INFO - PROCESS | 31254 | Values: 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 116.0 117.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 15:23:47 INFO - PROCESS | 31254 | Values: 146.0 146.0 145.0 145.0 145.0 146.0 144.0 145.0 145.0 145.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:23:47 INFO - PROCESS | 31254 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:56.00 stddev:5.00 (8.9%) stddev-sans-first:5.24 15:23:47 INFO - PROCESS | 31254 | Values: 55.0 57.0 57.0 56.0 56.0 55.0 56.0 71.0 54.0 54.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 15:23:47 INFO - PROCESS | 31254 | Values: 61.0 61.0 62.0 61.0 62.0 61.0 60.0 61.0 61.0 62.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:77.00 stddev:2.70 (3.5%) stddev-sans-first:2.83 15:23:47 INFO - PROCESS | 31254 | Values: 76.0 74.0 75.0 84.0 76.0 77.0 77.0 78.0 78.0 77.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#11] stanford-crypto-ccm Cycles:10 Average:131.20 Median:130.00 stddev:11.59 (8.9%) stddev-sans-first:12.30 15:23:47 INFO - PROCESS | 31254 | Values: 131.0 128.0 125.0 129.0 132.0 123.0 125.0 131.0 163.0 125.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.60 Median:200.00 stddev:4.33 (2.2%) stddev-sans-first:4.59 15:23:47 INFO - PROCESS | 31254 | Values: 200.0 198.0 208.0 204.0 196.0 197.0 196.0 203.0 194.0 200.0 15:23:47 INFO - PROCESS | 31254 | 15:23:47 INFO - PROCESS | 31254 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.57 (0.8%) stddev-sans-first:0.44 15:23:47 INFO - PROCESS | 31254 | Values: 70.0 68.0 69.0 69.0 69.0 69.0 68.0 69.0 69.0 69.0 15:23:47 INFO - PROCESS | 31254 | -------- Summary: end -------- 15:23:47 INFO - PROCESS | 31254 | 15:23:48 INFO - TEST-INFO | 31254: exit 0 15:23:48 INFO - TEST-OK | kraken | took 61355ms 15:23:48 INFO - SUITE-END | took 1173s 15:23:49 INFO - Completed test suite (00:19:34) 15:23:49 INFO - javascript benchmark 15:23:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.1062097302265, 3168.065420700893, 3203.2152122729935, 3206.647268800433, 3242.4649932761095, 5336.6531175473565, 5360.865978350544, 5364.624476286662, 5448.073827449333, 5467.972660136698, 5346.0, 5386.703731294777, 5425.457454254572, 5472.50747432731, 5474.343078830546, 4868.902621947568, 4899.608031357483, 4914.11545921734, 4921.753912304371, 5053.191489361703, 4958.256261560777, 5003.424606170301, 5040.798368065273, 5043.218301163325, 5046.167382381903, 4834.879128021792, 4864.975675121619, 4918.458969513351, 4976.62675299354, 4986.177280748672, 4904.313396124546, 4917.4344950330815, 4924.630652701062, 4941.209406494943, 4946.703197808143, 4863.635227357927, 4890.535399137077, 4899.069176856386, 4905.2642103684375, 4932.8520144395725, 5224.843254702327, 5341.759620817073, 5420.268263784378, 5430.239766432663, 5461.781528738846, 5798.797042103548, 5835.649861008311, 5836.416358364172, 5855.619384739978, 5859.384764599698, 5902.232709747748, 5908.113782932552, 5925.7333419995775, 5932.9406705932815, 5932.970335148297, 5357.303550538405, 5474.343078830526, 5544.861378465571, 5594.552435805166, 5598.608097433182, 5888.234529511136, 5888.617239879394, 5892.852678683067, 5898.911516327258, 5955.791547295824, 5638.464345887134, 5653.236813030188, 5660.349059858087, 5660.433956604389, 5679.914801277984, 5755.59710820247, 5828.242328497353, 5844.6785426801935, 5879.353271140171, 5881.5000724938, 5767.596268261182, 5768.480836724715, 5775.451332123442, 5779.0464573344925, 5787.768489260467, 5698.857528561819, 5755.424457554211, 5788.218590490314, 5795.623284486495, 5827.388124246893, 4397.406350142754, 4400.713953593006, 4416.53626369233, 4438.844640437634, 4443.00032492693, 3911.02224443889, 3914.099757055893, 3921.3529767588598, 3922.2547715933883, 3963.3460479021214, 5381.811636592701, 5432.592555558349, 5464.480874316933, 5468.234447177399, 5526.088195447706, 5727.198192253088, 5741.282339707536, 5775.971120144456, 5804.042333015005, 5812.796552120655, 5435.048866448435, 5439.3200849893765, 5449.209864569615, 5459.071957767189, 5459.481349271813, 4869.95130048695, 4940.160172770566, 4972.776225069881, 4990.8253211137435, 5032.345795046621, 6003.609765365237, 6048.788292409832, 6061.545384096122, 6061.545384096122, 6076.756929722762, 5829.387914269026, 5846.766129354778, 5849.298084229919, 5850.0932355484965, 5861.238039054895, 5869.618474799125, 6042.818715438544, 6058.636481811105, 6104.428839602322, 6135.417135372132, 5657.547396208376, 5706.343770466449, 5710.4004079572705, 5713.857153571194, 5770.2498675171955, 5796.913046304393, 5798.0, 5803.9419605803405, 5807.970960145172, 5818.272715910512, 5498.5876059296515, 5663.716814159193, 5735.54115670737, 5750.281214848144, 5781.913271301018, 5641.492265696107, 5689.34572524165, 5698.629589076697, 5699.772009119589, 5705.686187259741, 5480.177973303877, 5596.356419011866, 5597.468240517144, 5597.748101335531, 5605.859853503695, 5632.211490391266, 5687.71561421919, 5694.430556944438, 5703.173039909107, 5741.0, 5555.333359996826, 5555.388907220284, 5558.082916318924, 5560.443955604407, 5609.523190528851], "value": 5350.966446142568, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.5720246036386, 648.6237981970455, 649.4512137244028, 654.7970129259927, 667.5961043568642, 29894.252643683893, 30410.847945760266, 37149.25701485972, 38092.238155236875, 38153.046173845665, 38676.0, 39171.60828391715, 39208.80395598025, 39259.80370098146, 39275.0, 8412.158784121584, 8618.095100014501, 8623.698170564032, 8624.568771561428, 8626.353023523261, 8482.745517634481, 8626.741197764077, 8639.568021598927, 8643.222110010098, 8647.178518040777, 8807.64769409226, 8888.06675299094, 8894.688685895995, 8904.955475222614, 8910.064443233465, 8796.69211577595, 8878.24534914533, 8885.333599979962, 8887.244584210383, 8888.555572221363, 8650.913490865136, 8785.516796576187, 8785.736427907173, 8791.120887911222, 8796.8680469793, 39035.60964390348, 39309.0, 39501.60498395008, 39592.802035989924, 39630.80184599087, 3665.6151104133946, 3748.3253014457387, 3885.747426417274, 4059.7158198926086, 4210.347396153601, 29868.70131298681, 30333.848330758206, 30580.0, 30783.384332313453, 31950.840245798856, 3729.9254014919547, 3849.711271654637, 3873.5545412277665, 3920.7255492115564, 3922.784246866421, 261190.0, 262347.0, 262497.0, 262761.0, 262920.0, 44532.3320150198, 54794.726026369615, 55558.0, 55619.721901390236, 55728.72135639377, 53712.73143634257, 55240.17139742907, 55391.0, 55424.0, 55465.44534554603, 15115.622109447351, 15340.769888451681, 15357.846421535642, 15360.615984600474, 15361.462348817739, 10380.792384152275, 11334.773304533863, 12529.624111276677, 12537.0, 12539.122261441611, 16709.498715038568, 16878.662426751398, 16913.492595222164, 17061.40285090016, 17157.142142892804, 5907.455526683955, 5911.822645320647, 5943.197668314809, 5953.583249172603, 6107.4197951194565, 1004.2618675273668, 1038.4651904269308, 1066.0618655583037, 1075.6504136155781, 1104.9558017679365, 30862.537061944087, 31065.844670776954, 31135.377292454476, 31137.5215609639, 31929.201769955936, 1139.2082502660571, 1166.702490864824, 1167.5563285951284, 1199.1725709260581, 1206.9598705694286, 16476.176191190392, 16558.503244902673, 16613.3354665812, 16651.751118666147, 16913.577160571083, 342124.0, 346172.0, 346948.0, 347160.0, 347259.0, 53456.0, 54202.72898635561, 54552.727236363564, 54834.0, 54879.0, 1262.1543565810928, 1403.1931639307438, 1438.7841823726314, 1441.3802065112102, 1517.8254500732337, 29353.266168345963, 30803.84598077085, 31187.220319492193, 31300.686993130686, 31975.200619984687, 45450.772746136056, 45558.54441455543, 45864.77067614641, 45874.54125458703, 45950.31074533951, 241999.0, 243367.0, 243396.0, 243817.0, 244197.0, 31237.219069523442, 36502.45246321254, 37040.25919481649, 37236.255274894895, 37480.625193747714, 41608.791956041234, 41768.58231417647, 41792.0, 42017.57982420137, 42497.57502424936, 35408.645913541564, 35588.466173006906, 35755.10612234715, 36206.0, 36543.0, 34774.82612586921, 35615.46576801298, 36041.639583604876, 36089.0, 38361.808190958865, 35888.64111358853, 37122.0, 37333.253334933695, 38774.22451550897, 38853.41719874261, 31405.0, 32298.677013230506, 32414.351712965137, 32808.179795505304, 33212.833935831135, 25292.620610690487, 25844.870775646003, 26962.191134266002, 27162.592561111993, 27274.09081364365], "value": 18527.215843077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5452484281285, 383.28332914579056, 393.14687128930217, 399.7002248313765, 402.10732174572445, 17119.743203851922, 17352.30590776369, 17709.734353984684, 17768.733468997976, 17845.464636060922, 16564.503064908044, 17546.82453175468, 18137.637247255047, 18357.173927173277, 18664.16011279492, 5552.944470555293, 5614.073677843171, 5648.971755141219, 5685.374608793029, 5730.7421166047625, 5432.13085906255, 5587.916181257285, 5600.299962504687, 5622.8313150605345, 5624.409437009116, 5514.117741161415, 5552.11166213406, 5571.637843540157, 5572.58205634577, 5657.773689052433, 5478.91781623276, 5600.4959553639965, 5607.719614019283, 5633.746481408347, 5635.718214089279, 4800.375951126366, 4984.67599606028, 4985.5513003829465, 5020.874478138039, 5045.041442125985, 21698.457538561503, 21764.564708705897, 21919.342419727433, 22220.333389998326, 22272.443188920246, 2187.2781981946077, 2306.6078766609717, 2361.740208577055, 2921.4595299869593, 3150.82670453125, 9797.265205109645, 10053.79892402148, 12673.859352658214, 12989.830915217675, 13220.86779132206, 1873.3443294846832, 1938.176275083098, 1995.1520603743495, 2010.4672261850621, 2115.9259425920172, 66268.0, 68175.0, 69176.0, 70981.99999999949, 71463.28536714618, 10392.844107338396, 10445.11216546587, 10457.529411176516, 10575.298645474022, 10941.398223097647, 14154.0, 14241.78637320441, 14242.643933901736, 14246.216458094905, 14276.57170284893, 7796.610169491502, 8002.1997800219515, 8067.39494537912, 8128.431009829255, 8193.549354785544, 7821.178776228528, 7868.291853733192, 7871.921280787234, 7968.362530997506, 8005.119436861941, 4410.051838854662, 4472.552744725502, 4506.977465112654, 4593.563611456906, 4661.254199328091, 1771.7785276840395, 1825.799162092169, 1837.6508463391913, 1845.0, 1864.4499872537633, 991.1822746234385, 993.2500961773815, 1005.1255407795119, 1008.1078245752415, 1136.772645470909, 17570.648587028187, 17973.0, 18151.27394904189, 18249.270029198684, 18918.81081189198, 1010.0959641121302, 1012.4937531234383, 1026.3328836256344, 1042.921780866438, 1045.0176833776227, 10399.688009359732, 10544.209184311207, 10624.309419887682, 10749.281698938164, 10821.242513024014, 434848.0, 448172.0, 462795.0, 471567.0, 473923.0, 80595.0, 81037.59481202556, 81744.59127704323, 82158.0, 84091.0, 1037.9896201037893, 1040.490159821682, 1083.9241253112364, 1091.8908109189017, 1101.719061639293, 16282.51152465428, 16499.752503712214, 16540.917295413445, 16598.75101873449, 16757.245923933697, 37284.62715372811, 38084.42873356957, 38805.80597096997, 39494.0, 39548.209035818545, 52512.73743631257, 52648.0, 52913.735431322595, 53038.46961530335, 53150.734246328524, 7892.816077588178, 8023.398245131521, 8188.181181881764, 8243.505389676639, 8267.131951145044, 11459.31244125355, 12309.93845030769, 12415.732005503867, 12527.31099789484, 12896.548620798227, 10644.946775266075, 11193.608223712132, 11351.829722554003, 11501.367424791717, 11525.88474115248, 10581.947090264499, 10742.0, 10915.836262456229, 10921.017108460277, 10976.286541374786, 10700.571977120828, 11003.284786489174, 11067.50196241155, 11375.20373573842, 11510.07919366432, 8038.517688938682, 8152.388570857091, 8179.877301840358, 8307.6676932922, 8401.32789376836, 10056.69829905104, 10999.285046471954, 11034.724131896766, 11038.392888391216, 11229.0], "value": 8893.059387824906, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.670065833871, 1446.3202294921387, 1471.168789633857, 1605.6547842213922, 1750.2648887467255, 7530.171681115073, 7853.175416581263, 7860.68557257709, 7861.174576669446, 7868.96065519673, 7556.584387858666, 7785.221477852212, 7787.766367008985, 7798.220177982199, 7802.297793198611, 1967.6851703727334, 2200.185931205456, 2235.8434909556336, 2260.400993736661, 2282.8858557072163, 2272.8522646027955, 2275.419767959176, 2284.132029828671, 2284.7029886114783, 2286.9885650571805, 2207.0399376271375, 2249.640057590786, 2255.075419078178, 2258.2773512476015, 2258.6386178211487, 1919.7504324437873, 2199.6260635691892, 2256.0637335505744, 2259.9322020339414, 2265.728112626479, 2103.3374487036535, 2109.2301310021885, 2113.545587698652, 2115.862468939514, 2193.484531135182, 7702.576358300291, 7820.765377038698, 7832.608369581555, 7832.647530861125, 7893.605319734048, 4764.523547645243, 4901.803927842882, 4934.55588996992, 4939.604831613462, 4969.378827646544, 3019.064090132066, 3048.634163900324, 3089.459344614706, 3151.637561680413, 3172.127664892173, 967.1489089601106, 990.7225976726528, 991.4051569058579, 1000.6997900629782, 1017.9389236645751, 11000.504977276041, 11003.009729124416, 11013.118950483942, 11019.83470247947, 11024.283421577651, 4248.723832950848, 4269.082147338338, 4331.960329520955, 4340.110277393127, 4342.869713908588, 9317.44095354281, 9399.060093990684, 9400.482973436527, 9406.106419890237, 9425.340226184238, 4375.562443755599, 4854.101991131652, 4874.073925954058, 4884.731339776346, 4884.8290309838985, 4112.609302116294, 4133.379993001074, 4421.7789110544345, 4540.137373898949, 4581.931271030937, 3635.309291234657, 3710.7402481826553, 3720.6837418819705, 3763.7365384423374, 3792.2984247914223, 1221.5541327415428, 1226.920250183735, 1235.0119904076703, 1245.0, 1247.544646204147, 789.5026133535836, 921.8156368726281, 942.5522876633544, 950.2018304624011, 986.659602437158, 5356.143017117242, 5491.395946445888, 5499.890002199933, 5621.971890140523, 5711.143328500675, 434.9543297953669, 447.1191752248066, 447.69780398231717, 450.70929250633156, 452.6243218128945, 3762.6801721853403, 3885.825137868803, 3891.5330160380367, 3893.396523538856, 3914.197589494147, 9423.905760942303, 9835.901640983642, 9863.556139973718, 9928.305018648625, 9950.054744799378, 9679.516024198761, 9719.708408747749, 9811.31320807551, 9823.0, 9863.457509836884, 7195.280471952868, 7260.273972602803, 7321.487495875238, 7352.77941661751, 7359.227281135511, 5422.810201642924, 5471.343438787371, 5489.223289848066, 5500.202470641654, 5552.7778888843995, 9000.414973026733, 9126.315526335684, 9127.08729127082, 9128.49793261377, 9221.49281789508, 8908.777280567778, 9128.726138215865, 9149.542522873697, 9181.908180918372, 9183.0, 6378.681065946591, 6981.5112942093565, 7019.087518622547, 7182.60495672743, 7185.065941427581, 7672.61636918163, 7681.0, 7706.344960678405, 7765.92234077652, 7784.182660820758, 6721.93278067213, 6953.548019378724, 6965.825854353479, 7044.260352662896, 7095.361417472453, 6912.619805910522, 6917.066196063467, 6924.896126558207, 7028.262032486516, 7054.823629409306, 6098.451139397475, 6244.406781355764, 6281.748730050746, 6323.715432805443, 6347.428731414195, 4739.786709598007, 4899.289603007615, 4924.089043526959, 4935.284383764262, 4996.775145118551, 4948.233023881304, 4961.404631444249, 4963.379577552806, 5001.474845141208, 5006.949930500648], "value": 4197.792268437255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.32890778188573, 322.4389562161839, 325.6434204546021, 326.2626464190931, 330.9884154054608, 5057.216131499615, 5142.820001299955, 5176.741162941852, 5222.660527065743, 5225.137852254378, 5159.922601160985, 5181.0, 5205.817796377128, 5210.505002024802, 5232.398274198468, 4116.938245926313, 4123.628873401393, 4124.0, 4142.204012291706, 4152.086540960984, 3898.7660740355527, 3920.411938209263, 4109.260333140033, 4118.814653340592, 4128.153728485669, 4043.595640435962, 4084.65280451162, 4104.897377565555, 4117.732347397409, 4123.855665051724, 4056.8580099696665, 4062.329715596923, 4081.3061779497407, 4082.101937573729, 4095.9795201023803, 3557.51973483579, 3616.8553257869653, 3639.2357604903004, 3639.636036396366, 3666.578343490506, 4974.333598244592, 4983.103041452537, 5009.323741294915, 5017.598592112622, 5020.2720605511995, 4634.142683603544, 4703.272102180761, 4707.435107787053, 4725.810967561293, 4767.809287628491, 5264.236685680593, 5433.673979561199, 5464.3442786865435, 5469.316335458067, 5470.261514695505, 4016.7389119707127, 4022.9597704022876, 4037.4455834503633, 4041.5756345583586, 4055.1078762672164, 5655.717214139277, 5685.346185188715, 5688.175214593902, 5702.657840529581, 5737.88524229513, 4125.628693417607, 4136.896577585524, 4148.460700108966, 4157.937630935538, 4173.332266837291, 3871.709621778378, 4036.737612055207, 4040.474738284004, 4083.387491876183, 4086.141910198832, 4612.7924243409125, 4625.606823420047, 4640.373549570832, 4643.349931009661, 4652.511486293958, 5493.670379777226, 5494.527462641827, 5500.257465242221, 5522.475364840334, 5545.0, 1381.3714759784275, 1448.6161167290677, 1483.7477628803128, 1486.3608648281343, 1489.1735087026805, 974.65886939572, 977.3402953006835, 984.0897170117755, 989.7674046599044, 1003.9899601003897, 5584.106542953108, 5615.971920140373, 5623.353314368818, 5625.746841392065, 5645.23789288449, 5925.4074592541265, 5929.932612129772, 5943.375945525744, 5958.970205148947, 5977.551683623746, 5457.972710136424, 5464.781408743605, 5559.360673522516, 5560.666360018412, 5639.605227634108, 3146.6381366143187, 3155.605549306337, 3155.921101972423, 3168.904932852018, 3185.677943653358, 5642.971785141048, 6053.485453736482, 6104.511639068864, 6133.969330153321, 6163.537734669918, 5802.767889284381, 5811.418858114154, 5819.79630712923, 5875.236219291465, 5908.675022873783, 5859.414058594191, 5880.266031070216, 5891.528677705774, 5905.557083218776, 5922.289325280994, 5573.498385145356, 5621.145022142765, 5728.942710573007, 5734.025215713255, 5742.5118864895285, 5653.434656534314, 5684.431556844283, 5700.978481404862, 5705.885882282414, 5721.713914304185, 5623.0, 5882.23530940975, 5903.793367232126, 5921.703914804157, 5925.970370148121, 4028.576999415019, 4062.898427539335, 4064.4148781974463, 4068.8372465101065, 4085.3667681509414, 5476.835694929158, 5634.985282873643, 5724.542036636975, 5725.885482290248, 5769.9711501442225, 5449.182622606482, 5470.781168753365, 5507.284053073075, 5565.749541270734, 5613.74738136793, 5479.6164268500825, 5492.560595152298, 5499.2576002240785, 5524.558035357242, 5551.25058117149, 5252.65857719247, 5415.187721841755, 5443.7822487100075, 5466.453354664502, 5474.919530578355, 5541.5289700375915, 5560.388357280777, 5568.136938774496, 5568.9443105568425, 5581.916271255853, 5264.763085661078, 5461.863453413696, 5474.686075341969, 5485.259489968803, 5488.231647569423], "value": 4249.059594288827, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.5458215906139, 793.9245771651694, 797.0873350014234, 814.6334149632663, 847.3856454070796, 13633.0, 13659.0, 13762.38069286882, 13795.2412617306, 13851.03042787004, 12618.432170552323, 12936.676583085427, 12957.74084518309, 12958.0, 12975.351232438388, 682.3858527325422, 793.8293266947605, 810.1210186947154, 823.9217274358926, 835.6573804740058, 825.7770401991459, 825.859603867344, 826.2604968553171, 829.2288172000037, 829.5603330234985, 862.874883141944, 863.1153068105211, 864.7578677970179, 865.5758678247645, 867.2108381372952, 705.8411857332085, 732.2969948849085, 732.9633518324063, 828.9212524810133, 856.3192262151605, 621.0745988477182, 625.5214760708062, 625.5433533520555, 625.5933643131955, 625.9624422534663, 13649.0, 13724.93137534316, 13736.038477306593, 13745.38145783442, 13748.450061997508, 13949.721005579933, 14265.714685706227, 14272.357743901446, 14539.273036348246, 14550.054246473945, 10435.895641043568, 10521.421321827296, 10910.5090270938, 10985.231033827635, 11248.493817778219, 3868.6905047596406, 3870.5454761822043, 3897.2205558888054, 3951.6245956634075, 3985.5217373915025, 58757.41242587562, 58997.0, 59101.7044914777, 59622.0, 59956.40043599595, 10012.549435275285, 10286.639967601099, 10372.377657340583, 10411.167106631452, 10422.374657520422, 18846.81153188478, 18978.90510547466, 19194.424167274723, 19240.23039078449, 19252.807471925382, 8061.798455038554, 8065.153158918347, 8074.838503229903, 8086.237331164802, 8234.464759790595, 4101.710458115743, 4417.359482874966, 4432.423784907941, 4437.0, 4503.121891231179, 3179.4277030134335, 3225.774195806271, 3233.757468189848, 3242.4487837067754, 3244.383567122239, 598.0270051203039, 601.173386593434, 604.3835288006297, 606.6057062909144, 613.3345320327405, 668.7057694614355, 714.3499415531841, 727.3635568877232, 736.4255880413286, 749.0861149397726, 9137.77155571116, 9208.677696280598, 9244.399114057565, 9286.396384235004, 9358.672446464341, 704.9471289653296, 705.1960764728213, 727.294524311417, 756.2513112019061, 764.1098120689446, 4167.124903770242, 4184.497860256789, 4189.162167566499, 4201.495820501498, 4206.47419072616, 68347.31652683509, 69123.0, 72869.0, 72993.0, 73119.26880731231, 59238.0, 60302.095468568004, 60739.0, 61383.69308153431, 61701.0, 51760.0, 52336.73831630894, 52386.21420678693, 54602.72698636481, 54649.45350546523, 8098.51408915467, 8214.708486277405, 8277.917220827714, 8453.915460845314, 8577.142285771373, 35225.47161792626, 35337.46993795044, 35385.82307088448, 36275.63724362723, 36586.26827463489, 46533.0, 49242.75378623084, 49370.259446107615, 49805.0, 51513.22730158976, 10631.0, 10730.624428144978, 10832.891671083189, 10838.078763305208, 11096.556137754722, 11747.530098795953, 11749.471273792871, 11795.115366347387, 12244.816327754912, 12274.877251227374, 11577.131715121232, 11736.119791015879, 11782.0, 11923.105767067678, 12189.69525761863, 12237.326947018, 12697.301648409426, 12841.229526228457, 13071.411786469442, 13102.410391532594, 12143.635690929286, 12456.813147802972, 12638.55765048219, 12947.417366218355, 12947.935260323638, 9870.703878883645, 10129.341592796445, 10277.244742943974, 10592.152627789605, 10757.970623770681, 9674.75813104678, 10051.447170405698, 10061.899381006097, 10495.370277783357, 10783.406912619881], "value": 6670.602345422595, "unit": "score"}], "name": "dromaeo_css", "value": 6867.566303718801, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 107.0, 110.0, 111.0, 111.0, 101.0, 111.0, 111.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 128.0, 160.0, 193.0, 126.0, 161.0, 152.0, 137.0, 190.0, 134.0], "value": 154.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 160.0, 160.0, 162.0, 161.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 103.0, 117.0, 79.0, 76.0, 77.0, 124.0, 98.0, 75.0], "value": 90.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 93.0, 92.0, 92.0, 151.0, 93.0, 92.0, 154.0], "value": 110.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 144.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 56.0, 56.0, 55.0, 56.0, 71.0, 54.0, 54.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 60.0, 61.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 84.0, 76.0, 77.0, 77.0, 78.0, 78.0, 77.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 128.0, 125.0, 129.0, 132.0, 123.0, 125.0, 131.0, 163.0, 125.0], "value": 131.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 208.0, 204.0, 196.0, 197.0, 196.0, 203.0, 194.0, 200.0], "value": 199.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 69.0, 69.0, 69.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1576.5000000000002, "alertThreshold": 2.0}]} 15:23:49 INFO - Return code: 0 15:23:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:23:49 ERROR - # TBPL SUCCESS # 15:23:49 INFO - Running post-action listener: _resource_record_post_action 15:23:49 INFO - Running post-run listener: _resource_record_post_run 15:23:50 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 492576768; Read time: 0; Write time: 289772 15:23:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 201650176; Read time: 0; Write time: 120232 15:23:50 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 282542080; Read time: 0; Write time: 168076 15:23:50 INFO - Running post-run listener: _upload_blobber_files 15:23:50 INFO - Blob upload gear active. 15:23:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:23:50 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 15:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:23:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:23:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:23:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:23:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:51 INFO - (blobuploader) - INFO - Done attempting. 15:23:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:23:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:23:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:52 INFO - (blobuploader) - INFO - Done attempting. 15:23:52 INFO - (blobuploader) - INFO - Iteration through files over. 15:23:52 INFO - Return code: 0 15:23:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:23:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:23:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/836e1f12a80b2420c090f2f0ecbbe65bb444be9ad0a6faa54e9cdb679aac9b66896f5f4a387f2f783bbeea97549dd299a8e4b12b8a5b73681bb8a1b9c7775804", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9974cfd95835429fa8cf89fe0fce8cfeee68e5522e94c5d18ce3adea271237a8bcf650204c02e9fe530b1812a64df4660312553b000006fd9825e99763b0064a"} 15:23:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:23:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:23:52 INFO - Contents: 15:23:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/836e1f12a80b2420c090f2f0ecbbe65bb444be9ad0a6faa54e9cdb679aac9b66896f5f4a387f2f783bbeea97549dd299a8e4b12b8a5b73681bb8a1b9c7775804", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9974cfd95835429fa8cf89fe0fce8cfeee68e5522e94c5d18ce3adea271237a8bcf650204c02e9fe530b1812a64df4660312553b000006fd9825e99763b0064a"} 15:23:52 INFO - Running post-run listener: copy_logs_to_upload_dir 15:23:52 INFO - Copying logs to upload dir... 15:23:52 INFO - mkdir: /builds/slave/test/build/upload/logs 15:23:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.054880 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-04-26 15:23:52.232350) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 15:23:52.236062) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/836e1f12a80b2420c090f2f0ecbbe65bb444be9ad0a6faa54e9cdb679aac9b66896f5f4a387f2f783bbeea97549dd299a8e4b12b8a5b73681bb8a1b9c7775804", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9974cfd95835429fa8cf89fe0fce8cfeee68e5522e94c5d18ce3adea271237a8bcf650204c02e9fe530b1812a64df4660312553b000006fd9825e99763b0064a"} build_url:https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/fTsK_ZcXR-mICk1GILetLQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/836e1f12a80b2420c090f2f0ecbbe65bb444be9ad0a6faa54e9cdb679aac9b66896f5f4a387f2f783bbeea97549dd299a8e4b12b8a5b73681bb8a1b9c7775804", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9974cfd95835429fa8cf89fe0fce8cfeee68e5522e94c5d18ce3adea271237a8bcf650204c02e9fe530b1812a64df4660312553b000006fd9825e99763b0064a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 15:23:52.261682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:23:52.262088) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461708165.354820-438918302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:23:52.347093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 15:23:52.347457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 15:23:52.347980) ========= ========= Total master_lag: 0.31 =========