builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-003 starttime: 1464150977.94 results: success (0) buildid: 20160524211736 builduid: 99f089bccea44beb85aa5d1bb4f26ca4 revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:17.937687) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:17.938171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:17.938458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:17.967029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:17.967308) ========= 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=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-24 21:36:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.7M=0s 2016-05-24 21:36:18 (45.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.183627 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.173690) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.174150) ========= 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=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018065 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.228279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.228633) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 242c458c4c61e94c8d12628612ef5e4e83992eaa --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 242c458c4c61e94c8d12628612ef5e4e83992eaa --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=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-24 21:36:18,548 truncating revision to first 12 chars 2016-05-24 21:36:18,548 Setting DEBUG logging. 2016-05-24 21:36:18,548 attempt 1/10 2016-05-24 21:36:18,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/242c458c4c61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 21:36:18,803 unpacking tar archive at: fx-team-242c458c4c61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558415 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.800479) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.800839) ========= script_repo_revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.801259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.801557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 21:36:18.810831) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-24 21:36:18.811136) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:36:19 INFO - MultiFileLogger online at 20160524 21:36:19 in /builds/slave/test 21:36:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:36:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:36:19 INFO - {'append_to_log': False, 21:36:19 INFO - 'base_work_dir': '/builds/slave/test', 21:36:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:36:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:36:19 INFO - 'branch': 'Fx-Team-Non-PGO', 21:36:19 INFO - 'buildbot_json_path': 'buildprops.json', 21:36:19 INFO - 'config_files': ('talos/linux_config.py',), 21:36:19 INFO - 'default_actions': ('clobber', 21:36:19 INFO - 'read-buildbot-config', 21:36:19 INFO - 'download-and-extract', 21:36:19 INFO - 'populate-webroot', 21:36:19 INFO - 'create-virtualenv', 21:36:19 INFO - 'install', 21:36:19 INFO - 'run-tests'), 21:36:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:36:19 INFO - 'download_minidump_stackwalk': True, 21:36:19 INFO - 'download_symbols': 'ondemand', 21:36:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:36:19 INFO - 'tooltool.py': '/tools/tooltool.py', 21:36:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:36:19 INFO - '/tools/misc-python/virtualenv.py')}, 21:36:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:36:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:36:19 INFO - 'installer_path': 'installer.exe', 21:36:19 INFO - 'log_level': 'info', 21:36:19 INFO - 'log_name': 'talos', 21:36:19 INFO - 'log_to_console': True, 21:36:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:36:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:36:19 INFO - 'opt_config_files': (), 21:36:19 INFO - 'pip_index': False, 21:36:19 INFO - 'sps_profile': False, 21:36:19 INFO - 'sps_profile_interval': 0, 21:36:19 INFO - 'suite': 'dromaeojs', 21:36:19 INFO - 'system_bits': '32', 21:36:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:36:19 INFO - 'title': 'talos-linux64-ix-003', 21:36:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:36:19 INFO - 'use_talos_json': True, 21:36:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:36:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:36:19 INFO - 'work_dir': 'build'} 21:36:19 INFO - ##### 21:36:19 INFO - ##### Running clobber step. 21:36:19 INFO - ##### 21:36:19 INFO - Running pre-action listener: _resource_record_pre_action 21:36:19 INFO - Running main action method: clobber 21:36:19 INFO - rmtree: /builds/slave/test/build 21:36:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:36:19 INFO - Running post-action listener: _resource_record_post_action 21:36:19 INFO - ##### 21:36:19 INFO - ##### Finished clobber step (success) 21:36:19 INFO - ##### 21:36:19 INFO - ##### 21:36:19 INFO - ##### Running read-buildbot-config step. 21:36:19 INFO - ##### 21:36:19 INFO - Running pre-action listener: _resource_record_pre_action 21:36:19 INFO - Running main action method: read_buildbot_config 21:36:19 INFO - Using buildbot properties: 21:36:19 INFO - { 21:36:19 INFO - "project": "", 21:36:19 INFO - "product": "firefox", 21:36:19 INFO - "script_repo_revision": "production", 21:36:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:36:19 INFO - "repository": "", 21:36:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:36:19 INFO - "buildid": "20160524211736", 21:36:19 INFO - "pgo_build": "False", 21:36:19 INFO - "basedir": "/builds/slave/test", 21:36:19 INFO - "buildnumber": 1781, 21:36:19 INFO - "slavename": "talos-linux64-ix-003", 21:36:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:36:19 INFO - "platform": "ubuntu64_hw", 21:36:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:36:19 INFO - "branch": "fx-team", 21:36:19 INFO - "repo_path": "integration/fx-team", 21:36:19 INFO - "revision": "242c458c4c61e94c8d12628612ef5e4e83992eaa", 21:36:19 INFO - "stage_platform": "linux64", 21:36:19 INFO - "builduid": "99f089bccea44beb85aa5d1bb4f26ca4", 21:36:19 INFO - "slavebuilddir": "test" 21:36:19 INFO - } 21:36:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:36:19 INFO - Running post-action listener: _resource_record_post_action 21:36:19 INFO - ##### 21:36:19 INFO - ##### Finished read-buildbot-config step (success) 21:36:19 INFO - ##### 21:36:19 INFO - ##### 21:36:19 INFO - ##### Running download-and-extract step. 21:36:19 INFO - ##### 21:36:19 INFO - Running pre-action listener: _resource_record_pre_action 21:36:19 INFO - Running main action method: download_and_extract 21:36:19 INFO - mkdir: /builds/slave/test/build/tests 21:36:19 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')]} 21:36:19 INFO - https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:36:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:36:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:36:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:20 INFO - Downloaded 1361 bytes. 21:36:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:36:20 INFO - Using the following test package requirements: 21:36:20 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:36:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:36:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:36:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'jsshell-linux-x86_64.zip'], 21:36:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:36:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:36:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:36:20 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:36:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:36:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:36:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:36:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:20 INFO - https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:36:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:36:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:36:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:21 INFO - Downloaded 22844861 bytes. 21:36:21 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'] 21:36:21 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 21:36:22 INFO - Return code: 0 21:36:22 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 21:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:22 INFO - https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:36:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:36:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:36:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:23 INFO - Downloaded 22844861 bytes. 21:36:23 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'] 21:36:23 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 21:36:25 INFO - Return code: 0 21:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:25 INFO - https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:36:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:36:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:36:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:26 INFO - Downloaded 10840359 bytes. 21:36:26 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'] 21:36:26 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 21:36:27 INFO - Return code: 0 21:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:27 INFO - https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:36:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpJFpBsATDaBXKg6vsHjwg/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 21:36:29 INFO - Downloaded 54828334 bytes. 21:36:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:29 INFO - mkdir: /builds/slave/test/properties 21:36:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:36:29 INFO - Writing to file /builds/slave/test/properties/build_url 21:36:29 INFO - Contents: 21:36:29 INFO - build_url:https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:30 INFO - Running post-action listener: _resource_record_post_action 21:36:30 INFO - Running post-action listener: set_extra_try_arguments 21:36:30 INFO - ##### 21:36:30 INFO - ##### Finished download-and-extract step (success) 21:36:30 INFO - ##### 21:36:30 INFO - ##### 21:36:30 INFO - ##### Running populate-webroot step. 21:36:30 INFO - ##### 21:36:30 INFO - Running pre-action listener: _resource_record_pre_action 21:36:30 INFO - Running main action method: populate_webroot 21:36:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:36:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:36:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:36:30 INFO - '--tppagecycles', 21:36:30 INFO - '7'], 21:36:30 INFO - 'tests': ['tsvgm']}}, 21:36:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:36:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:36:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:36:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:36:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:36:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:36:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['damp', 'tps']}, 21:36:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'tests': ['damp', 'tps']}, 21:36:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['dromaeo_dom']}, 21:36:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:36:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'other-e10s': {'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['a11yr', 21:36:30 INFO - 'ts_paint', 21:36:30 INFO - 'tpaint', 21:36:30 INFO - 'sessionrestore', 21:36:30 INFO - 'sessionrestore_no_auto_restore', 21:36:30 INFO - 'tabpaint']}, 21:36:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['tsvgx', 21:36:30 INFO - 'tsvgr_opacity', 21:36:30 INFO - 'tart', 21:36:30 INFO - 'tscrollx', 21:36:30 INFO - 'cart']}, 21:36:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:36:30 INFO - 'tsvgr_opacity', 21:36:30 INFO - 'tart', 21:36:30 INFO - 'tscrollx', 21:36:30 INFO - 'cart']}, 21:36:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'talos_options': ['--disable-e10s'], 21:36:30 INFO - 'tests': ['tp5o']}, 21:36:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'tests': ['tp5o']}, 21:36:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'talos_options': ['--disable-e10s', 21:36:30 INFO - '--xperf_path', 21:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:36:30 INFO - 'tests': ['tp5n']}, 21:36:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:30 INFO - 'talos_options': ['--xperf_path', 21:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:36:30 INFO - 'tests': ['tp5n']}}, 21:36:30 INFO - 'talos.zip': {'path': '', 21:36:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:36:30 INFO - Running post-action listener: _resource_record_post_action 21:36:30 INFO - ##### 21:36:30 INFO - ##### Finished populate-webroot step (success) 21:36:30 INFO - ##### 21:36:30 INFO - ##### 21:36:30 INFO - ##### Running create-virtualenv step. 21:36:30 INFO - ##### 21:36:30 INFO - Running pre-action listener: _resource_record_pre_action 21:36:30 INFO - Running main action method: create_virtualenv 21:36:30 INFO - Creating virtualenv /builds/slave/test/build/venv 21:36:30 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 21:36:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:36:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:36:30 INFO - Using real prefix '/usr' 21:36:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:36:30 INFO - Installing distribute.............................................................................................................................................................................................done. 21:36:32 INFO - Installing pip.................done. 21:36:32 INFO - Return code: 0 21:36:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:32 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:32 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 21:36:32 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 21:36:32 INFO - Using env: {'DISPLAY': ':0', 21:36:32 INFO - 'HOME': '/home/cltbld', 21:36:32 INFO - 'LANG': 'en_US.UTF-8', 21:36:32 INFO - 'LANGUAGE': 'en_US:en', 21:36:32 INFO - 'LOGNAME': 'cltbld', 21:36:32 INFO - 'MAIL': '/var/mail/cltbld', 21:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:32 INFO - 'MOZ_NO_REMOTE': '1', 21:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:32 INFO - 'NO_EM_RESTART': '1', 21:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:32 INFO - 'PWD': '/builds/slave/test', 21:36:32 INFO - 'SHELL': '/bin/bash', 21:36:32 INFO - 'SHLVL': '1', 21:36:32 INFO - 'TERM': 'linux', 21:36:32 INFO - 'TMOUT': '86400', 21:36:32 INFO - 'USER': 'cltbld', 21:36:32 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:32 INFO - '_': '/tools/buildbot/bin/python'} 21:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:32 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 21:36:32 INFO - Cleaning up... 21:36:32 INFO - Return code: 0 21:36:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:36:32 INFO - Using env: {'DISPLAY': ':0', 21:36:32 INFO - 'HOME': '/home/cltbld', 21:36:32 INFO - 'LANG': 'en_US.UTF-8', 21:36:32 INFO - 'LANGUAGE': 'en_US:en', 21:36:32 INFO - 'LOGNAME': 'cltbld', 21:36:32 INFO - 'MAIL': '/var/mail/cltbld', 21:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:32 INFO - 'MOZ_NO_REMOTE': '1', 21:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:32 INFO - 'NO_EM_RESTART': '1', 21:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:32 INFO - 'PWD': '/builds/slave/test', 21:36:32 INFO - 'SHELL': '/bin/bash', 21:36:32 INFO - 'SHLVL': '1', 21:36:32 INFO - 'TERM': 'linux', 21:36:32 INFO - 'TMOUT': '86400', 21:36:32 INFO - 'USER': 'cltbld', 21:36:32 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:32 INFO - '_': '/tools/buildbot/bin/python'} 21:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:33 INFO - Downloading/unpacking psutil>=3.1.1 21:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:36:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:36 INFO - Installing collected packages: psutil 21:36:36 INFO - Running setup.py install for psutil 21:36:36 INFO - building 'psutil._psutil_linux' extension 21:36:36 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 21:36:36 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 21:36:36 INFO - building 'psutil._psutil_posix' extension 21:36:36 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 21:36:36 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 21:36:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:36 INFO - Successfully installed psutil 21:36:36 INFO - Cleaning up... 21:36:36 INFO - Return code: 0 21:36:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:36:36 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')]} 21:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:36 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')]} 21:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:36:36 INFO - Using env: {'DISPLAY': ':0', 21:36:36 INFO - 'HOME': '/home/cltbld', 21:36:36 INFO - 'LANG': 'en_US.UTF-8', 21:36:36 INFO - 'LANGUAGE': 'en_US:en', 21:36:36 INFO - 'LOGNAME': 'cltbld', 21:36:36 INFO - 'MAIL': '/var/mail/cltbld', 21:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:36 INFO - 'MOZ_NO_REMOTE': '1', 21:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:36 INFO - 'NO_EM_RESTART': '1', 21:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:36 INFO - 'PWD': '/builds/slave/test', 21:36:36 INFO - 'SHELL': '/bin/bash', 21:36:36 INFO - 'SHLVL': '1', 21:36:36 INFO - 'TERM': 'linux', 21:36:36 INFO - 'TMOUT': '86400', 21:36:36 INFO - 'USER': 'cltbld', 21:36:36 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:36 INFO - '_': '/tools/buildbot/bin/python'} 21:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:36:36 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 21:36:36 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 21:36:36 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 21:36:36 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 21:36:36 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 21:36:36 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 21:36:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:36:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:36:38 INFO - Installing collected packages: mozsystemmonitor 21:36:38 INFO - Running setup.py install for mozsystemmonitor 21:36:38 INFO - Successfully installed mozsystemmonitor 21:36:38 INFO - Cleaning up... 21:36:39 INFO - Return code: 0 21:36:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:36:39 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')]} 21:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:39 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')]} 21:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:39 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:39 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 21:36:39 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 21:36:39 INFO - Using env: {'DISPLAY': ':0', 21:36:39 INFO - 'HOME': '/home/cltbld', 21:36:39 INFO - 'LANG': 'en_US.UTF-8', 21:36:39 INFO - 'LANGUAGE': 'en_US:en', 21:36:39 INFO - 'LOGNAME': 'cltbld', 21:36:39 INFO - 'MAIL': '/var/mail/cltbld', 21:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:39 INFO - 'MOZ_NO_REMOTE': '1', 21:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:39 INFO - 'NO_EM_RESTART': '1', 21:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:39 INFO - 'PWD': '/builds/slave/test', 21:36:39 INFO - 'SHELL': '/bin/bash', 21:36:39 INFO - 'SHLVL': '1', 21:36:39 INFO - 'TERM': 'linux', 21:36:39 INFO - 'TMOUT': '86400', 21:36:39 INFO - 'USER': 'cltbld', 21:36:39 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:39 INFO - '_': '/tools/buildbot/bin/python'} 21:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:39 INFO - Downloading/unpacking blobuploader==1.2.4 21:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:41 INFO - Downloading blobuploader-1.2.4.tar.gz 21:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:36:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:36:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:41 INFO - Downloading docopt-0.6.1.tar.gz 21:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:36:41 INFO - Installing collected packages: blobuploader, requests, docopt 21:36:41 INFO - Running setup.py install for blobuploader 21:36:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:36:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:36:41 INFO - Running setup.py install for requests 21:36:42 INFO - Running setup.py install for docopt 21:36:42 INFO - Successfully installed blobuploader requests docopt 21:36:42 INFO - Cleaning up... 21:36:42 INFO - Return code: 0 21:36:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:42 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:42 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 21:36:42 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 21:36:42 INFO - Using env: {'DISPLAY': ':0', 21:36:42 INFO - 'HOME': '/home/cltbld', 21:36:42 INFO - 'LANG': 'en_US.UTF-8', 21:36:42 INFO - 'LANGUAGE': 'en_US:en', 21:36:42 INFO - 'LOGNAME': 'cltbld', 21:36:42 INFO - 'MAIL': '/var/mail/cltbld', 21:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:42 INFO - 'MOZ_NO_REMOTE': '1', 21:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:42 INFO - 'NO_EM_RESTART': '1', 21:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:42 INFO - 'PWD': '/builds/slave/test', 21:36:42 INFO - 'SHELL': '/bin/bash', 21:36:42 INFO - 'SHLVL': '1', 21:36:42 INFO - 'TERM': 'linux', 21:36:42 INFO - 'TMOUT': '86400', 21:36:42 INFO - 'USER': 'cltbld', 21:36:42 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:42 INFO - '_': '/tools/buildbot/bin/python'} 21:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:42 INFO - Running setup.py (path:/tmp/pip-C8YF4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:42 INFO - Running setup.py (path:/tmp/pip-bWfz4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:42 INFO - Running setup.py (path:/tmp/pip-OS9dor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:42 INFO - Running setup.py (path:/tmp/pip-vH2P2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:42 INFO - Running setup.py (path:/tmp/pip-WU0Q6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:43 INFO - Running setup.py (path:/tmp/pip-bf09F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:43 INFO - Running setup.py (path:/tmp/pip-cNSck5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:43 INFO - Running setup.py (path:/tmp/pip-QRuVHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:43 INFO - Running setup.py (path:/tmp/pip-nEC4au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:43 INFO - Running setup.py (path:/tmp/pip-1TD7DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:43 INFO - Running setup.py (path:/tmp/pip-yAkG9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:43 INFO - Running setup.py (path:/tmp/pip-u6Xyn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:43 INFO - Running setup.py (path:/tmp/pip-Fpd2GM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:43 INFO - Running setup.py (path:/tmp/pip-NcSFRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:43 INFO - Running setup.py (path:/tmp/pip-XYkoXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:43 INFO - Running setup.py (path:/tmp/pip-_64LGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:43 INFO - Running setup.py (path:/tmp/pip-NXNvpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:36:43 INFO - Running setup.py install for manifestparser 21:36:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for mozcrash 21:36:44 INFO - Running setup.py install for mozdebug 21:36:44 INFO - Running setup.py install for mozdevice 21:36:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for mozfile 21:36:44 INFO - Running setup.py install for mozhttpd 21:36:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for mozinfo 21:36:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for mozInstall 21:36:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for mozleak 21:36:44 INFO - Running setup.py install for mozlog 21:36:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:36:44 INFO - Running setup.py install for moznetwork 21:36:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Running setup.py install for mozprocess 21:36:45 INFO - Running setup.py install for mozprofile 21:36:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Running setup.py install for mozrunner 21:36:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Running setup.py install for mozscreenshot 21:36:45 INFO - Running setup.py install for moztest 21:36:45 INFO - Running setup.py install for mozversion 21:36:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:36:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:36:45 INFO - Cleaning up... 21:36:45 INFO - Return code: 0 21:36:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:45 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')]} 21:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:45 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')]} 21:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:45 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:45 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 21:36:45 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 21:36:45 INFO - Using env: {'DISPLAY': ':0', 21:36:45 INFO - 'HOME': '/home/cltbld', 21:36:45 INFO - 'LANG': 'en_US.UTF-8', 21:36:45 INFO - 'LANGUAGE': 'en_US:en', 21:36:45 INFO - 'LOGNAME': 'cltbld', 21:36:45 INFO - 'MAIL': '/var/mail/cltbld', 21:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:45 INFO - 'MOZ_NO_REMOTE': '1', 21:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:45 INFO - 'NO_EM_RESTART': '1', 21:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:45 INFO - 'PWD': '/builds/slave/test', 21:36:45 INFO - 'SHELL': '/bin/bash', 21:36:45 INFO - 'SHLVL': '1', 21:36:45 INFO - 'TERM': 'linux', 21:36:45 INFO - 'TMOUT': '86400', 21:36:45 INFO - 'USER': 'cltbld', 21:36:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:45 INFO - '_': '/tools/buildbot/bin/python'} 21:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:45 INFO - Running setup.py (path:/tmp/pip-tskIAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:45 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)) 21:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:45 INFO - Running setup.py (path:/tmp/pip-cDTfj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:46 INFO - Running setup.py (path:/tmp/pip-4zC4Ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:46 INFO - Running setup.py (path:/tmp/pip-EjuxPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:46 INFO - Running setup.py (path:/tmp/pip-lr2Wa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:46 INFO - Running setup.py (path:/tmp/pip-r0sqwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:46 INFO - Running setup.py (path:/tmp/pip-xfnM23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:46 INFO - Running setup.py (path:/tmp/pip-vPbWGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:46 INFO - Running setup.py (path:/tmp/pip-1yi7_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:46 INFO - Running setup.py (path:/tmp/pip-ox5o_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:46 INFO - Running setup.py (path:/tmp/pip-1Emrly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:46 INFO - Running setup.py (path:/tmp/pip-yTxEc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:46 INFO - Running setup.py (path:/tmp/pip-5Eh44T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:46 INFO - Running setup.py (path:/tmp/pip-xdVtyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:46 INFO - Running setup.py (path:/tmp/pip-45GcHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:46 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)) 21:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:46 INFO - Running setup.py (path:/tmp/pip-TMrWoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:47 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)) 21:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:47 INFO - Running setup.py (path:/tmp/pip-QyuZ5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:47 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)) 21:36:47 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)) 21:36:47 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)) 21:36:47 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)) 21:36:47 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)) 21:36:47 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)) 21:36:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:47 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 21:36:47 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 21:36:47 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 21:36:47 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 21:36:47 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 21:36:47 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 21:36:48 INFO - Downloading blessings-1.6.tar.gz 21:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:36:49 INFO - Installing collected packages: blessings 21:36:49 INFO - Running setup.py install for blessings 21:36:49 INFO - Successfully installed blessings 21:36:49 INFO - Cleaning up... 21:36:49 INFO - Return code: 0 21:36:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:36:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:49 INFO - Reading from file tmpfile_stdout 21:36:49 INFO - Current package versions: 21:36:49 INFO - argparse == 1.2.1 21:36:49 INFO - blessings == 1.6 21:36:49 INFO - blobuploader == 1.2.4 21:36:49 INFO - docopt == 0.6.1 21:36:49 INFO - manifestparser == 1.1 21:36:49 INFO - mozInstall == 1.12 21:36:49 INFO - mozcrash == 0.17 21:36:49 INFO - mozdebug == 0.1 21:36:49 INFO - mozdevice == 0.48 21:36:49 INFO - mozfile == 1.2 21:36:49 INFO - mozhttpd == 0.7 21:36:49 INFO - mozinfo == 0.9 21:36:49 INFO - mozleak == 0.1 21:36:49 INFO - mozlog == 3.2 21:36:49 INFO - moznetwork == 0.27 21:36:49 INFO - mozprocess == 0.22 21:36:49 INFO - mozprofile == 0.28 21:36:49 INFO - mozrunner == 6.11 21:36:49 INFO - mozscreenshot == 0.1 21:36:49 INFO - mozsystemmonitor == 0.3 21:36:49 INFO - moztest == 0.7 21:36:49 INFO - mozversion == 1.4 21:36:49 INFO - psutil == 3.1.1 21:36:49 INFO - requests == 1.2.3 21:36:49 INFO - wsgiref == 0.1.2 21:36:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:49 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:49 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 21:36:49 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 21:36:49 INFO - Using env: {'DISPLAY': ':0', 21:36:49 INFO - 'HOME': '/home/cltbld', 21:36:49 INFO - 'LANG': 'en_US.UTF-8', 21:36:49 INFO - 'LANGUAGE': 'en_US:en', 21:36:49 INFO - 'LOGNAME': 'cltbld', 21:36:49 INFO - 'MAIL': '/var/mail/cltbld', 21:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:49 INFO - 'MOZ_NO_REMOTE': '1', 21:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:49 INFO - 'NO_EM_RESTART': '1', 21:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:49 INFO - 'PWD': '/builds/slave/test', 21:36:49 INFO - 'SHELL': '/bin/bash', 21:36:49 INFO - 'SHLVL': '1', 21:36:49 INFO - 'TERM': 'linux', 21:36:49 INFO - 'TMOUT': '86400', 21:36:49 INFO - 'USER': 'cltbld', 21:36:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:49 INFO - '_': '/tools/buildbot/bin/python'} 21:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 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)) 21:36:49 INFO - Cleaning up... 21:36:49 INFO - Return code: 0 21:36:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:49 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:49 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 21:36:49 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 21:36:49 INFO - Using env: {'DISPLAY': ':0', 21:36:49 INFO - 'HOME': '/home/cltbld', 21:36:49 INFO - 'LANG': 'en_US.UTF-8', 21:36:49 INFO - 'LANGUAGE': 'en_US:en', 21:36:49 INFO - 'LOGNAME': 'cltbld', 21:36:49 INFO - 'MAIL': '/var/mail/cltbld', 21:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:49 INFO - 'MOZ_NO_REMOTE': '1', 21:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:49 INFO - 'NO_EM_RESTART': '1', 21:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:49 INFO - 'PWD': '/builds/slave/test', 21:36:49 INFO - 'SHELL': '/bin/bash', 21:36:49 INFO - 'SHLVL': '1', 21:36:49 INFO - 'TERM': 'linux', 21:36:49 INFO - 'TMOUT': '86400', 21:36:49 INFO - 'USER': 'cltbld', 21:36:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:49 INFO - '_': '/tools/buildbot/bin/python'} 21:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:49 INFO - Downloading/unpacking jsonschema 21:36:49 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 21:36:49 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 21:36:49 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 21:36:49 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 21:36:49 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 21:36:49 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 21:36:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:36:51 INFO - Downloading/unpacking functools32 (from jsonschema) 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36:51 INFO - Downloading functools32-3.2.3-2.tar.gz 21:36:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:36:52 INFO - warning: no files found matching '*.txt' 21:36:52 INFO - no previously-included directories found matching 'build' 21:36:52 INFO - no previously-included directories found matching 'dist' 21:36:52 INFO - no previously-included directories found matching '.git*' 21:36:52 INFO - Installing collected packages: jsonschema, functools32 21:36:52 INFO - Running setup.py install for functools32 21:36:52 INFO - warning: no files found matching '*.txt' 21:36:52 INFO - no previously-included directories found matching 'build' 21:36:52 INFO - no previously-included directories found matching 'dist' 21:36:52 INFO - no previously-included directories found matching '.git*' 21:36:52 INFO - Successfully installed jsonschema functools32 21:36:52 INFO - Cleaning up... 21:36:52 INFO - Return code: 0 21:36:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:36:52 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')]} 21:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:52 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')]} 21:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:52 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 0x7f44126fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b968e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, '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': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', '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 21:36:52 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 21:36:52 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 21:36:52 INFO - Using env: {'DISPLAY': ':0', 21:36:52 INFO - 'HOME': '/home/cltbld', 21:36:52 INFO - 'LANG': 'en_US.UTF-8', 21:36:52 INFO - 'LANGUAGE': 'en_US:en', 21:36:52 INFO - 'LOGNAME': 'cltbld', 21:36:52 INFO - 'MAIL': '/var/mail/cltbld', 21:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:52 INFO - 'MOZ_NO_REMOTE': '1', 21:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:52 INFO - 'NO_EM_RESTART': '1', 21:36:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:52 INFO - 'PWD': '/builds/slave/test', 21:36:52 INFO - 'SHELL': '/bin/bash', 21:36:52 INFO - 'SHLVL': '1', 21:36:52 INFO - 'TERM': 'linux', 21:36:52 INFO - 'TMOUT': '86400', 21:36:52 INFO - 'USER': 'cltbld', 21:36:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:52 INFO - '_': '/tools/buildbot/bin/python'} 21:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:52 INFO - Downloading/unpacking flake8 21:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:36:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:36:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:36:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:36:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:36:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:36:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:36:55 INFO - no previously-included directories found matching 'docs/_build' 21:36:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:36:55 INFO - Running setup.py install for pep8 21:36:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:36:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:36:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:36:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:36:55 INFO - no previously-included directories found matching 'docs/_build' 21:36:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:36:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:36:55 INFO - Cleaning up... 21:36:55 INFO - Return code: 0 21:36:55 INFO - Running post-action listener: _resource_record_post_action 21:36:55 INFO - Running post-action listener: _start_resource_monitoring 21:36:55 INFO - Starting resource monitoring. 21:36:55 INFO - ##### 21:36:55 INFO - ##### Finished create-virtualenv step (success) 21:36:55 INFO - ##### 21:36:55 INFO - ##### 21:36:55 INFO - ##### Running install step. 21:36:55 INFO - ##### 21:36:55 INFO - Running pre-action listener: _resource_record_pre_action 21:36:55 INFO - Running main action method: install 21:36:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:55 INFO - Reading from file tmpfile_stdout 21:36:55 INFO - Detecting whether we're running mozinstall >=1.0... 21:36:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:36:55 INFO - Reading from file tmpfile_stdout 21:36:55 INFO - Output received: 21:36:55 INFO - Usage: mozinstall [options] installer 21:36:55 INFO - Options: 21:36:55 INFO - -h, --help show this help message and exit 21:36:55 INFO - -d DEST, --destination=DEST 21:36:55 INFO - Directory to install application into. [default: 21:36:55 INFO - "/builds/slave/test"] 21:36:55 INFO - --app=APP Application being installed. [default: firefox] 21:36:55 INFO - mkdir: /builds/slave/test/build/application 21:36:55 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'] 21:36:55 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 21:37:11 INFO - Reading from file tmpfile_stdout 21:37:11 INFO - Output received: 21:37:11 INFO - /builds/slave/test/build/application/firefox/firefox 21:37:11 INFO - Running post-action listener: _resource_record_post_action 21:37:11 INFO - ##### 21:37:11 INFO - ##### Finished install step (success) 21:37:11 INFO - ##### 21:37:11 INFO - ##### 21:37:11 INFO - ##### Running run-tests step. 21:37:11 INFO - ##### 21:37:11 INFO - Running pre-action listener: _resource_record_pre_action 21:37:11 INFO - Running main action method: run_tests 21:37:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:37:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:37:11 INFO - Python 2.7.3 21:37:11 INFO - Return code: 0 21:37:11 INFO - grabbing minidump binary from tooltool 21:37:11 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')]} 21:37:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c25b20>, '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 0x2c26a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c26eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:37:11 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 21:37:11 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 21:37:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:37:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:37:11 INFO - Return code: 0 21:37:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:37:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:37:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:37:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:37:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:37:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:37:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:37:12 INFO - Return code: 0 21:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 21:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 21:37:12 INFO - Using env: {'DISPLAY': ':0', 21:37:12 INFO - 'HOME': '/home/cltbld', 21:37:12 INFO - 'LANG': 'en_US.UTF-8', 21:37:12 INFO - 'LANGUAGE': 'en_US:en', 21:37:12 INFO - 'LOGNAME': 'cltbld', 21:37:12 INFO - 'MAIL': '/var/mail/cltbld', 21:37:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:37:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:12 INFO - 'MOZ_NO_REMOTE': '1', 21:37:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:37:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:12 INFO - 'NO_EM_RESTART': '1', 21:37:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:12 INFO - 'PWD': '/builds/slave/test', 21:37:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:37:12 INFO - 'SHELL': '/bin/bash', 21:37:12 INFO - 'SHLVL': '1', 21:37:12 INFO - 'TERM': 'linux', 21:37:12 INFO - 'TMOUT': '86400', 21:37:12 INFO - 'USER': 'cltbld', 21:37:12 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464148411.61822-720399212', 21:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:12 INFO - '_': '/tools/buildbot/bin/python'} 21:37:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 21:37:12 INFO - mozversion application_buildid: 20160524211736 21:37:12 INFO - mozversion application_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 21:37:12 INFO - mozversion application_display_name: Nightly 21:37:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:37:12 INFO - mozversion application_name: Firefox 21:37:12 INFO - mozversion application_remotingname: firefox 21:37:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:37:12 INFO - mozversion application_vendor: Mozilla 21:37:12 INFO - mozversion application_version: 49.0a1 21:37:12 INFO - mozversion platform_buildid: 20160524211736 21:37:12 INFO - mozversion platform_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 21:37:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:37:12 INFO - mozversion platform_version: 49.0a1 21:37:12 INFO - using testdate: 1464151032 21:37:12 INFO - actual date: 1464151032 21:37:12 INFO - starting webserver on 'localhost:41045' 21:37:12 INFO - SUITE-START | Running 2 tests 21:37:12 INFO - TEST-START | dromaeo_css 21:37:12 INFO - operating with platform_type : linux_ 21:37:12 INFO - Initialising browser for dromaeo_css test... 21:37:12 INFO - TEST-INFO | started process 21769 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_w8hPQ/profile http://localhost:41045/getInfo.html) 21:37:17 INFO - PROCESS | 21769 | __metrics Screen width/height:1600/1200 21:37:17 INFO - PROCESS | 21769 | colorDepth:24 21:37:17 INFO - PROCESS | 21769 | Browser inner width/height: 1024/697 21:37:17 INFO - PROCESS | 21769 | __metrics 21:37:18 ERROR - PROCESS | 21769 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:37:19 INFO - TEST-INFO | 21769: exit 0 21:37:19 INFO - Browser initialized. 21:37:19 INFO - Running cycle 1/1 for dromaeo_css test... 21:37:19 INFO - TEST-INFO | started process 21849 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_w8hPQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:37:31 INFO - PROCESS | 21849 | RSS: Main: 154951680 21:37:31 INFO - PROCESS | 21849 | 21:38:21 INFO - PROCESS | 21849 | 1464151101942 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] 21:40:34 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41045/tests/dromaeo/cssquery-ext.html) 21:40:35 INFO - PROCESS | 21849 | RSS: Main: 234090496 21:40:35 INFO - PROCESS | 21849 | 21:43:38 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-ext.html (next: http://localhost:41045/tests/dromaeo/cssquery-jquery.html) 21:43:38 INFO - PROCESS | 21849 | RSS: Main: 235487232 21:43:38 INFO - PROCESS | 21849 | 21:46:40 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41045/tests/dromaeo/cssquery-mootools.html) 21:46:41 INFO - PROCESS | 21849 | RSS: Main: 240467968 21:46:41 INFO - PROCESS | 21849 | 21:49:43 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41045/tests/dromaeo/cssquery-prototype.html) 21:49:44 INFO - PROCESS | 21849 | RSS: Main: 238559232 21:49:44 INFO - PROCESS | 21849 | 21:52:46 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41045/tests/dromaeo/cssquery-yui.html) 21:52:47 INFO - PROCESS | 21849 | RSS: Main: 220188672 21:52:47 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | Cycle 1(1): loaded http://localhost:41045/tests/dromaeo/cssquery-yui.html (next: http://localhost:41045/tests/dromaeo/cssquery-dojo.html) 21:55:50 INFO - PROCESS | 21849 | RSS: Main: 275054592 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | __start_tp_report 21:55:50 INFO - PROCESS | 21849 | _x_x_mozilla_page_load 21:55:50 INFO - PROCESS | 21849 | _x_x_mozilla_page_load_details 21:55:50 INFO - PROCESS | 21849 | |i|pagename|runs| 21:55:50 INFO - PROCESS | 21849 | |0;dojo.html;2687.8387296762194;2701.0951331304013;2722.4418994106204;2728.236093893709;2747.2445077603657;4618.05329907369;4686.55477729616;4733.313669517922;4806.567408933191;4850.951490485094;4622.491525932145;4639.65202609804;4770.165221086313;4805.135075686375;4817.951820481794;4263.829446822124;4271.63691086258;4290.97854510727;4332.7833608319615;4527.456705195381;4442.289233722605;4464.508904020555;4472.82108715651;4501.887452813673;4523.570260825233;4265;4314.956850431487;4315.611594956437;4330.220560299145;4377.62790162835;4308.353746937984;4313.805878735465;4317.050248945227;4403.669724770655;4422.756748378838;4355.5208927018;4358.302671572533;4375.890602734957;4405.493368262659;4423.557644235583;4551.68138230324;4640.69835460694;4654.953450465519;4664.5102264262105;4729.763511824395;5094.796208151706;5148.639595228336;5160.7419629018395;5172.353455818023;5212;5160.354955630546;5174.327337446108;5217.269582258449;5238.13570760824;5239.5546378557865;4673.182193116191;4806.639502037361;4852.4662287148385;4858.58702010326;4876.878078048077;5321.228421878806;5359.919601205985;5388.784448622011;5393.7572809223675;5398.163284690879;5108.66793658411;5114.5652619527755;5156.432792392834;5164;5182.792688292501;4997.875053123701;5114.232865070269;5174.508421699932;5179.119549676564;5194.6104042196985;5119.948800511947;5145.073886700355;5148.279240906276;5151.201563757623;5204.635675502678;4926.3349447824085;5112.437631860566;5130.563902068291;5135.281060651511;5139.12634852076;4327.82688692455;4348.956510434855;4364.432623758891;4366.410534577855;4370.322599997005;3772.4529943158086;3789.204267103884;3790.0714324990554;3824.694024478035;3830.7701537907815;4363.3018717005125;4394.406755088086;4443.400140980926;4445.2887537993765;4462.531434199427;5134.640575159778;5185.196294574347;5221.817236396708;5229.869253268699;5231.084560202025;4472.709273897186;4502.752348620792;4510.458744950562;4511.684182107221;4560.042391097906;4464.196444639996;4623.768811559408;4720.291956206596;4724.929126063112;4757;4819.132556139999;4845.927311090266;4870.269459581091;4870.829520966749;4911.115999120193;5154.948450515447;5168.767405466688;5170.43125256214;5170.508801663836;5219.582433405242;4688.226442636927;4785.090832741768;4800.927986080282;4840.83057093;4864.756762161948;5033.672811267256;5047.949520504748;5050.823221187241;5057.241413787961;5096.439391666988;5094.032133894548;5098.821541246039;5099.031184075162;5108.3103780989095;5135.435102138687;4879;4950.084234416644;4954.108260513142;4993.076280888047;5000.2499625056535;4982.676126051795;4999.45006049327;5010.048090862724;5013.348264725708;5033.496650334937;4882.316475693477;4933.309336692794;4935.333729946411;4985.252212168204;4987.226979818134;4866.270059491105;4974.154393752999;5037.168867136881;5042.84871453857;5066.59467242627;4945.035718035093;4972.403311602631;4972.726500042388;4975.7512124392915;5023.974880125576 21:55:50 INFO - PROCESS | 21849 | |1;ext.html;567.6338761498833;582.3099626942071;583.4923616453686;595.0598055072984;610.0787810406287;41346.79326603367;42453.78773106138;42481.57518424811;42597;46793.29810052852;43711.344329834996;44437.55562444375;44515.554844451624;44547.33179002318;44572.108557828906;7769.611519424035;7939.920600794005;7945.4438189326775;7953.323967462773;7955.443118981673;7785.493942893722;7841.019872515935;7880.960595197016;7881.7241396551135;7888.329491993159;7883.054033515986;7888.723894663688;7910.525368477881;7919.445638805286;7926.24700653437;7854.096778870446;7896.328812050925;7915.52506849588;7920.16838231986;7922.168172341936;7769.3784497240085;7842.372610191228;7850.764477065697;7854.057513098408;7855.567943763091;40178.39732403987;40682.18635627301;40804.79597602023;40947.590524094674;40997;3143.5599016137758;3293.8682452701864;3385.5768028996376;3580.7493475456727;3852.210296889131;27403.45193096147;27738.722612774018;28574.285642858886;31742.841285793653;32412;3571.375009373375;3587.336342776595;3588.3002814451193;3621;3649.251903359805;268454;269909;272693;273003;273316;58266;58332.4166758327;58580.7070964651;58754.412455874895;58757.70621146867;52284.738576306874;61068.38931610716;61814;62410.68794655998;68060.3193968054;14597.343119559646;14795.482158124414;14807.407703691948;14826.851731482762;14831.5550533482;10190.541425635864;11165.888341116484;11221;11226.607068752555;11237.775244495228;18755;19183.232670693294;19456.610867782565;19487.415377538695;20065.17063628696;6989.89515157273;7009.9299007010295;7071.25751796054;7107.609081500464;7115.71537138519;878.4202426398547;952.2572393533055;971.7099007880271;1024.5543188712934;1041.9739506512399;28575.857120714678;29032.12903612895;29111.708882910898;29621.25946851346;29813.552796708067;979.7697541077841;1043.1498328861953;1054.330500132406;1084.2356138922041;1100.6918062942389;15167.620809479851;15396;15474.303656335493;15505.457308994131;15689.137097459521;395220;396639;398244;399806;400044;51866.48133518617;53224.46775532272;53269.733651331495;53432.198517022276;53502.46497535053;1074.484247561175;1184.1000839362148;1216.8357271768198;1224.2899118511243;1237.0845574274972;28648.997285095757;29716.85141574278;29732;30242.24394390158;30422.39155216839;43442;43679.12641747211;44065.339019914085;44183.33724994192;44250.55749442464;284116;284521;285168;286005;286134;23420.414489637897;25118.497630047663;25939.35151621149;26259;27167.86416067907;43652.345214821165;44136;45354.31968520541;45399;49942.25086623631;38190.6180938187;38579.61420385873;39538.40692389559;39928.201435970535;40161.1967760649;36809.31906809298;38474.422883657324;38622.227555449295;38684.226315474094;39007.80496097615;37086.44370334393;37176.81411593033;37405.62594374021;38056.42915356217;38067.80966095152;32518.34963300768;34200.82899585486;34798.30403391867;34869.302613948086;34975.12562185965;26372.20883373425;27187.86406067957;27764.583531247452;27919.302017449725;28072.719272807826 21:55:50 INFO - PROCESS | 21849 | |2;jquery.html;355.49697178492437;357.7191904355081;362.7406404420839;374.36171327885955;379.36077709060237;15990.760138597916;17216.655666886654;17367.218475168615;17656.205470753815;17843.821561784378;16585.41951031714;16853.157342132876;17442;17460;17488.912555437204;4368.213721530124;5097.643164978453;5099.515546023141;5102.438731739506;5103.846884593449;3707.6663100320966;3834.789086600236;5088.847334579968;5619.831405057855;5968.2838059432925;6016.217891674098;6028.4272994065495;6033.7888173913925;6047.5766696331275;6048.062550304688;5859.6777177255235;5896.204012458306;6041.124037014608;6065.150878876939;6070.6661133637635;3342.782719123249;4883.12103821312;5176.792928282902;5177.171652535576;5245.134552798783;20043;20370.388888333375;20435.284765033153;20919.05864236113;20958.05688743995;1909.5417099896065;1933.58427937994;2028.868123571963;2042.79572042796;2546.987265063663;13111.803322950163;13204;13273.137246078973;13341.466341346335;13552.119112257671;1674.790651168604;1710.298777501231;1761.2954818072744;1803.323753592403;1804.440623406735;64101.35898641001;64515.67742161306;65743.67128164376;66116.33883661198;66773.66613166951;8383;8938.24024957886;9097.270119251076;9227.492487913229;9340.673076442292;12191.756164876653;12200.816987745191;12214.328211948428;12264.141510094387;12291.877081229251;7005.334493223136;7070;7151.82120446993;7227.74702885407;7265.309795569412;6867.965660171767;6997.265287144878;7042.507024508338;7084.326988936043;7174.928250717426;3886.4947556817997;3908.335582950905;3913.6477717005605;3967.543732470745;4013;1737.7504067394054;1772.6100257943233;1800.918958646864;1828.4254282876943;1846.5291350705484;752.7102065704634;852.9914700853036;871.7733389318696;894.3873446689039;989.3074847606704;16335.428260010842;16639.25123369451;17004.149792510325;17020.744688829676;17323.740143897852;847.2290215903621;851.4252879306443;871.263782104121;889.3241136736127;901.4275934781462;9377.812443751087;9540.284478664127;9567.712968610953;9620.326577139533;9701.563429645683;385921;388306;391842;391993;393023;70227.64886175639;72520;73064.63467682734;83029.16970830335;84422.5778871114;943.7310366545502;958.6357184269933;960.8078384323163;974.7124598243536;984.1683777208248;15553.84446155524;15706.371745130067;15714.564242542425;15732.449364272195;16109.758353624939;34874.65125348714;36542.634573654985;37039.444408334435;37220.25559488849;37481.437778433894;49448.25827612517;50862.23706644477;50931;51519.48480515297;52090.73954630203;7348.742794002184;7372.889406659012;7553.395728341829;7688.115866675404;7723.729669461542;10362.689119326433;10636.255462117888;10762.461876905967;11153.107751380032;11402.315861048364;8959.835221421434;9246.260299175916;9368.4378937264;9409.48247846375;9677.274204434554;9487.620495180116;9632.614695412105;9708.854367184356;9929.205663547044;10037.749056273651;9503.422526446795;10089.344192627455;10237.897621023694;10309.845352319571;10390.272680912263;6373.107764913008;6735.595864248161;6982.406495447944;7072.2220555737795;7620.237976202336;7469.365103966224;8542.234943874753;9506.38208516472;9510.809783804423;9764.170045545923 21:55:50 INFO - PROCESS | 21849 | |3;mootools.html;1200.1658847101269;1398.699279654874;1433.3048471491327;1556.6575353422243;1796.6406718656272;6965.268646792084;7743.496672716276;7974.122846486882;7985.081715602715;8246.876296855537;7973.162817904124;8079.474834135777;8136.877946830802;8138.715144969912;8142.267195952363;2188.8796116213603;2221.577900198957;2222.944426389337;2223.5108276179217;2224.1214720185517;2173.0981642618294;2184.1918490158664;2191.024993877725;2202.9889850550726;2209.3482422685265;2119.1841141160685;2133.434639820449;2134.7758485359045;2135.4020874155185;2162.4053385319007;2137.4335801012667;2142.1324363632752;2145.1526646974435;2146.12009076278;2146.6350720377573;2106.8103870651717;2153.5800518898823;2158.57907707997;2161.0923412166926;2162.5458653682745;7764.844703105963;7930.444868859125;8007.439479236456;8015.318697910685;8058.1538938411795;4668.323093151508;4784.712917224942;4789.521047895218;4790;4819.349387832633;2862.169970708492;2865.7134286571386;2887.725666061721;2901.419716056797;2940.5442156465783;896.4890012692767;897.5422534507383;900.6757567275776;912.1562554636935;927.3879239701832;10959.287646302964;11087.889121108767;11120.388378639173;11122.388268645222;11141.387223702694;4069.2878746219294;4147.709660323749;4210.5578914213565;4217.746935183899;4228.450301460852;8724.43291186071;8794.956025219833;8801.559922003875;8810.911890881138;8813.162749538784;4698.365720627671;4759.833405830779;4761.381020467386;4762.523747625279;4764.14245435818;3736.551613806361;4074.409210664437;4275.444192254987;4288.313869780883;4330;3399.6940275375337;3444.4144495435835;3464.8787292444645;3486.337595856779;3525.7884526928465;1004.0762498501397;1006.4866917871938;1010.4846528270635;1019.6431249062769;1022.4223313827664;674.2078058281597;699.7795694356262;727.8835386338267;785.3834739729285;819.0539926385035;5442.809501667422;5549.944500554942;5608.579356548275;5778.595498315078;5778.711064446761;861.3539845116163;886.1227384888914;936.7704912296394;940.5673390240429;942.9104235097655;3704.129529560551;3822.7132965027736;3833.639058134378;3931.5478719947;3947.289487892207;9670.661526846527;9993.450360230112;10038.749031274276;10056.094951454405;10058.34620749649;9966.352187107816;10013.249006324555;10129.341592796445;10131.64539241123;10131.645392411378;7408;7555.320021198118;7721.65252563636;7733.4973226740085;7735.61321933901;5444.074507333842;5453.700046497322;5474.178873169057;5479.150731636602;5600.4959553640365;9368.437893726126;9406.623735050522;9460.716178514656;9488;9523;8573.442726222776;8847.20375166238;8899.911000889908;9115.908840911507;9145.085491450802;6730.091437655854;6813.69338379784;6852.794416167523;6854.040434339297;6860.897086543606;7303.123625165014;7390.7413240536325;7506.211847755907;7510.62446877643;7518.398528117847;5890.9116363253725;6245.96877015612;6933.445324374139;7120.786376408716;7170.318819712119;6611.801645950629;6883.380495755406;6901.689423975833;7035.613041282779;7090.255523170199;6362.331955144643;6372.0760489729655;6396.840078998062;6413.390727880844;6452.12896258999;4618.099470603334;4749.738764367993;4750.548697873696;4772.880677983078;4850.6362022847725;4818.566329030404;4852.781624826821;4880.511948805233;4897.632677549127;4903.436104847988 21:55:50 INFO - PROCESS | 21849 | |4;prototype.html;295.8757321924791;300.5626812987103;302.83192827980463;306.4560405280627;311.2281541776394;4773.591790421825;4774.42706875175;4775.188218002939;4800.927986080212;4817.662763606549;4695.8356457524;4698.238160689742;4761.285807128932;4766.690165139264;4777.450593181777;3535.752497325182;3657.213699054703;3661.6521430464063;3748.6438788315068;3765.9623403766022;3713.3687273163628;3729.9254014919684;3739.420389839566;3739.6821270192067;3745.307118183131;3574.696150827183;3696.3716168251462;3706.8517259309597;3717.869874554391;3726.7018638508853;3621.275744851027;3717.182219911615;3724.087598538348;3724.571674257468;3734.2344819311975;3289.9342013159844;3292.851821668031;3302.6862448067395;3333.749968752353;3343.498475228711;4519.757066806622;4530.1392735380505;4553.362529245908;4558.817647294104;4571.1771881061395;3972.8609498667697;4183.372494125875;4188.979055104735;4297.3983642289795;4304.160688665712;4936.568395165397;4938.135826230396;4944.233643785183;4960.454350021495;4970.204767237261;3513.2973405318785;3531.2407832316176;3547.308274886398;3558.857645694169;3562.6259242779392;5038.168702164138;5160.303359046518;5193.324867767204;5216.556592689626;5222.608304377149;3563.8396272167297;3574.999000279926;3577.928441431157;3589.138606734417;3604.098975256186;3636.654517820803;3651.087228192952;3659;3670.2109046554583;3672.192117734094;4187.183499217685;4223.1975924574235;4235.174141042528;4238.491381034234;4252.277112890816;4877.3903262092235;4951.702897826142;4967.776450059756;4987.202047672355;5023.648344615842;1263.690395853022;1275.7193417448148;1280.8472374862697;1286.3825363825417;1289.9742005160033;809.1786836361098;831.264331066998;861.3927181337267;877.2630989968458;880.4365206267994;4454.086912182995;4460.397846290774;4467.955320446819;4469.575390337913;4474.418325617649;5025.4974502550185;5041.521055499722;5048.192289233705;5092.185250359925;5119.385673719177;4264.978675106605;4353.804078816461;4437.844675436344;4444.888877778081;4453.532379100213;2670.8798104085363;2673.9036994832413;2681.43689825135;2708.9051883183993;2727.2409296003966;4808.374911261583;4838.637102217348;4863.419388698687;4863.465018847924;4878.463284065508;5017.648764586517;5110.36120484932;5172.663776854492;5195.6622819516615;5207.453217412193;4670.1360248353485;4730.526947305242;4799.160146974265;4800.159972004885;4815.4590531030435;5013.423456302426;5018.272350509229;5045.142325804695;5049.899002019865;5118.462561430996;4795.832145874878;4919.778609962489;4922.440331027917;4991.276264941491;4996.400431948044;4796.25658023007;4808.35087263229;4814;4820.807167713392;4827.227643577011;3613.5483064616924;3627.383344831332;3629.5100161478917;3632.9101269619537;3634.3639863024396;4878.317035615088;5047.217681259411;5136.897262054663;5143.897122057613;5154.433012368561;4845.988914600402;4846.903061938812;4881.096997055556;4899.142650036159;4904.2643603459765;4897.363342765418;4911.666958194829;4919.188333925005;4927.260910863399;4931.580815630711;4680.836170734008;4836.347093142522;4857.611391088775;4858.5384388483035;4860.854174374775;4919.401194611694;4928.778204980713;4951;4958.652894297365;4960.925586116139;4814.975925120352;4828.782704778364;4875.926861097016;4886.438059623047;4888.533439968067 21:55:50 INFO - PROCESS | 21849 | |5;yui.html;652.9510286728494;721.3399739238597;733.684515658267;734.966926488308;813.60133534568;13771.793423098661;14272.857271427296;14275.571732848019;14917.776233356508;14943.178125203109;13382.330883455812;13440.932795336033;13465.326733663302;13465.394057267422;13480.393382297796;634.3624657219488;767.6277005652254;822.8148666550038;823.0247157118804;829.2909562324216;817.7996390884211;823.0247157118804;823.3618945317367;828.6022709099636;829.1045670675655;850.5747126436781;855.8288342331558;857.9790049840669;858.6436628799041;859.3726579596898;655.5509476008949;822.0792712162356;842.9873551896726;844.0926004545108;855.8331125430543;631.356016862802;631.8578319878037;632.7880160146357;633.1041576169714;633.3729607688401;12842.807357889638;12857.164284321489;12878.87121128786;12882.742345153138;12892.226466412045;12153.635390938285;12312.753744925141;12414;12677.619671409873;12686.682832929158;11218.495167717472;11349.829752553718;11432.428378581038;11536.769264614744;11851.525938962432;4067.6542493888055;4141.585841415865;4158.064435501988;4168.874933751962;4318.892027699333;47416.5258347419;47612.76193619044;47731.52268477305;47776.522234777556;47845.5215447848;9749.9025009749;9997.550110245056;10496.422696751599;10588.788224235472;10604.575816967235;17905.7314140288;17907;17984.640307194044;17998.550036248937;18043.909780451013;6752.696128674222;6753.628550429774;6812.761553345708;6871.725130994704;6916.446684265347;3551.4317709166407;3574.857005719742;3617.909552261162;3636.836342364547;3697.9445308320396;2760.530709779302;2845.260232339607;2845.5873898285045;2848.273690208983;2933.4133173365412;535.6143576624823;566.2073097663305;570.2700543304577;590.3063899917665;597.1788790413182;489.6107594462411;500.1147968096449;560.2548610348308;593.3295376224838;602.2381687165739;8738.606762695694;8788.871273153674;8903.243224326005;8942.910570894208;8986.685466008788;485.10256026351675;567.2257368691706;613.8557439001831;621.8787336469345;623.314354210365;4038.818253178614;4058.1072164123843;4115.1975364803575;4134.813933373005;4149.647279981175;58201.417985820444;58973.70513147407;59547;59795.40204597898;59876.70061649664;39014.6098539011;41002;44540.66378008665;53337.73331133397;54331.45668543264;49171.26243106428;49718.25422618663;50448.74775626172;50462.74768626207;51038;7150.67821948024;7208.315210055036;7243.601601911945;7292.671829767567;7484.176740558425;29238.26904327409;32449.675503244664;32717.345653087283;33880.15299617529;34520.48219276763;37795.81102094582;38675;39721.80139099286;39919.60080399159;40738.1852362957;10596.8940310599;10728.356298622108;10960.068394186583;11165.330080195214;11438.885611143782;11614.128940329678;11993.040556755614;12067.155299129328;12846.614601561969;12902.96776257916;8170.550619715973;9429.292803039936;11001.559937602406;11137.498812553293;11659.291044218917;11272.943635281772;11419.14356423255;11683.88316116862;12402.379881005732;12714.87285127137;10394.532246049097;10972.506237219504;11142.387168705798;11569.247998880046;11578.305301681927;9571.952140239255;9577.808443830945;9649.662261820802;10073.69778906634;10224.13094886943;9896.356736812084;9905.405675659484;9932.602695892083;10064.899351006396;10071 21:55:50 INFO - PROCESS | 21849 | __end_tp_report 21:55:50 INFO - PROCESS | 21849 | __start_cc_report 21:55:50 INFO - PROCESS | 21849 | _x_x_mozilla_cycle_collect,1047 21:55:50 INFO - PROCESS | 21849 | __end_cc_report 21:55:50 INFO - PROCESS | 21849 | __startTimestamp1464152150530__endTimestamp 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | ------- Summary: start ------- 21:55:50 INFO - PROCESS | 21849 | Number of tests: 6 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#0] dojo.html Cycles:165 Average:4745.88 Median:4870.27 stddev:506.12 (10.4%) stddev-sans-first:481.23 21:55:50 INFO - PROCESS | 21849 | Values: 2687.8 2701.1 2722.4 2728.2 2747.2 4618.1 4686.6 4733.3 4806.6 4851.0 4622.5 4639.7 4770.2 4805.1 4818.0 4263.8 4271.6 4291.0 4332.8 4527.5 4442.3 4464.5 4472.8 4501.9 4523.6 4265.0 4315.0 4315.6 4330.2 4377.6 4308.4 4313.8 4317.1 4403.7 4422.8 4355.5 4358.3 4375.9 4405.5 4423.6 4551.7 4640.7 4655.0 4664.5 4729.8 5094.8 5148.6 5160.7 5172.4 5212.0 5160.4 5174.3 5217.3 5238.1 5239.6 4673.2 4806.6 4852.5 4858.6 4876.9 5321.2 5359.9 5388.8 5393.8 5398.2 5108.7 5114.6 5156.4 5164.0 5182.8 4997.9 5114.2 5174.5 5179.1 5194.6 5119.9 5145.1 5148.3 5151.2 5204.6 4926.3 5112.4 5130.6 5135.3 5139.1 4327.8 4349.0 4364.4 4366.4 4370.3 3772.5 3789.2 3790.1 3824.7 3830.8 4363.3 4394.4 4443.4 4445.3 4462.5 5134.6 5185.2 5221.8 5229.9 5231.1 4472.7 4502.8 4510.5 4511.7 4560.0 4464.2 4623.8 4720.3 4724.9 4757.0 4819.1 4845.9 4870.3 4870.8 4911.1 5154.9 5168.8 5170.4 5170.5 5219.6 4688.2 4785.1 4800.9 4840.8 4864.8 5033.7 5047.9 5050.8 5057.2 5096.4 5094.0 5098.8 5099.0 5108.3 5135.4 4879.0 4950.1 4954.1 4993.1 5000.2 4982.7 4999.5 5010.0 5013.3 5033.5 4882.3 4933.3 4935.3 4985.3 4987.2 4866.3 4974.2 5037.2 5042.8 5066.6 4945.0 4972.4 4972.7 4975.8 5024.0 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#1] ext.html Cycles:180 Average:48727.40 Median:38653.23 stddev:84966.02 (219.8%) stddev-sans-first:85127.43 21:55:50 INFO - PROCESS | 21849 | Values: 567.6 582.3 583.5 595.1 610.1 41346.8 42453.8 42481.6 42597.0 46793.3 43711.3 44437.6 44515.6 44547.3 44572.1 7769.6 7939.9 7945.4 7953.3 7955.4 7785.5 7841.0 7881.0 7881.7 7888.3 7883.1 7888.7 7910.5 7919.4 7926.2 7854.1 7896.3 7915.5 7920.2 7922.2 7769.4 7842.4 7850.8 7854.1 7855.6 40178.4 40682.2 40804.8 40947.6 40997.0 3143.6 3293.9 3385.6 3580.7 3852.2 27403.5 27738.7 28574.3 31742.8 32412.0 3571.4 3587.3 3588.3 3621.0 3649.3 268454.0 269909.0 272693.0 273003.0 273316.0 58266.0 58332.4 58580.7 58754.4 58757.7 52284.7 61068.4 61814.0 62410.7 68060.3 14597.3 14795.5 14807.4 14826.9 14831.6 10190.5 11165.9 11221.0 11226.6 11237.8 18755.0 19183.2 19456.6 19487.4 20065.2 6989.9 7009.9 7071.3 7107.6 7115.7 878.4 952.3 971.7 1024.6 1042.0 28575.9 29032.1 29111.7 29621.3 29813.6 979.8 1043.1 1054.3 1084.2 1100.7 15167.6 15396.0 15474.3 15505.5 15689.1 395220.0 396639.0 398244.0 399806.0 400044.0 51866.5 53224.5 53269.7 53432.2 53502.5 1074.5 1184.1 1216.8 1224.3 1237.1 28649.0 29716.9 29732.0 30242.2 30422.4 43442.0 43679.1 44065.3 44183.3 44250.6 284116.0 284521.0 285168.0 286005.0 286134.0 23420.4 25118.5 25939.4 26259.0 27167.9 43652.3 44136.0 45354.3 45399.0 49942.3 38190.6 38579.6 39538.4 39928.2 40161.2 36809.3 38474.4 38622.2 38684.2 39007.8 37086.4 37176.8 37405.6 38056.4 38067.8 32518.3 34200.8 34798.3 34869.3 34975.1 26372.2 27187.9 27764.6 27919.3 28072.7 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#2] jquery.html Cycles:180 Average:24124.80 Median:5100.98 stddev:64376.99 (1262.1%) stddev-sans-first:64532.84 21:55:50 INFO - PROCESS | 21849 | Values: 355.5 357.7 362.7 374.4 379.4 15990.8 17216.7 17367.2 17656.2 17843.8 16585.4 16853.2 17442.0 17460.0 17488.9 4368.2 5097.6 5099.5 5102.4 5103.8 3707.7 3834.8 5088.8 5619.8 5968.3 6016.2 6028.4 6033.8 6047.6 6048.1 5859.7 5896.2 6041.1 6065.2 6070.7 3342.8 4883.1 5176.8 5177.2 5245.1 20043.0 20370.4 20435.3 20919.1 20958.1 1909.5 1933.6 2028.9 2042.8 2547.0 13111.8 13204.0 13273.1 13341.5 13552.1 1674.8 1710.3 1761.3 1803.3 1804.4 64101.4 64515.7 65743.7 66116.3 66773.7 8383.0 8938.2 9097.3 9227.5 9340.7 12191.8 12200.8 12214.3 12264.1 12291.9 7005.3 7070.0 7151.8 7227.7 7265.3 6868.0 6997.3 7042.5 7084.3 7174.9 3886.5 3908.3 3913.6 3967.5 4013.0 1737.8 1772.6 1800.9 1828.4 1846.5 752.7 853.0 871.8 894.4 989.3 16335.4 16639.3 17004.1 17020.7 17323.7 847.2 851.4 871.3 889.3 901.4 9377.8 9540.3 9567.7 9620.3 9701.6 385921.0 388306.0 391842.0 391993.0 393023.0 70227.6 72520.0 73064.6 83029.2 84422.6 943.7 958.6 960.8 974.7 984.2 15553.8 15706.4 15714.6 15732.4 16109.8 34874.7 36542.6 37039.4 37220.3 37481.4 49448.3 50862.2 50931.0 51519.5 52090.7 7348.7 7372.9 7553.4 7688.1 7723.7 10362.7 10636.3 10762.5 11153.1 11402.3 8959.8 9246.3 9368.4 9409.5 9677.3 9487.6 9632.6 9708.9 9929.2 10037.7 9503.4 10089.3 10237.9 10309.8 10390.3 6373.1 6735.6 6982.4 7072.2 7620.2 7469.4 8542.2 9506.4 9510.8 9764.2 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#3] mootools.html Cycles:180 Average:5240.42 Median:4900.53 stddev:2974.02 (60.7%) stddev-sans-first:2966.86 21:55:50 INFO - PROCESS | 21849 | Values: 1200.2 1398.7 1433.3 1556.7 1796.6 6965.3 7743.5 7974.1 7985.1 8246.9 7973.2 8079.5 8136.9 8138.7 8142.3 2188.9 2221.6 2222.9 2223.5 2224.1 2173.1 2184.2 2191.0 2203.0 2209.3 2119.2 2133.4 2134.8 2135.4 2162.4 2137.4 2142.1 2145.2 2146.1 2146.6 2106.8 2153.6 2158.6 2161.1 2162.5 7764.8 7930.4 8007.4 8015.3 8058.2 4668.3 4784.7 4789.5 4790.0 4819.3 2862.2 2865.7 2887.7 2901.4 2940.5 896.5 897.5 900.7 912.2 927.4 10959.3 11087.9 11120.4 11122.4 11141.4 4069.3 4147.7 4210.6 4217.7 4228.5 8724.4 8795.0 8801.6 8810.9 8813.2 4698.4 4759.8 4761.4 4762.5 4764.1 3736.6 4074.4 4275.4 4288.3 4330.0 3399.7 3444.4 3464.9 3486.3 3525.8 1004.1 1006.5 1010.5 1019.6 1022.4 674.2 699.8 727.9 785.4 819.1 5442.8 5549.9 5608.6 5778.6 5778.7 861.4 886.1 936.8 940.6 942.9 3704.1 3822.7 3833.6 3931.5 3947.3 9670.7 9993.5 10038.7 10056.1 10058.3 9966.4 10013.2 10129.3 10131.6 10131.6 7408.0 7555.3 7721.7 7733.5 7735.6 5444.1 5453.7 5474.2 5479.2 5600.5 9368.4 9406.6 9460.7 9488.0 9523.0 8573.4 8847.2 8899.9 9115.9 9145.1 6730.1 6813.7 6852.8 6854.0 6860.9 7303.1 7390.7 7506.2 7510.6 7518.4 5890.9 6246.0 6933.4 7120.8 7170.3 6611.8 6883.4 6901.7 7035.6 7090.3 6362.3 6372.1 6396.8 6413.4 6452.1 4618.1 4749.7 4750.5 4772.9 4850.6 4818.6 4852.8 4880.5 4897.6 4903.4 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#4] prototype.html Cycles:180 Average:4115.70 Median:4763.99 stddev:1185.19 (24.9%) stddev-sans-first:1153.32 21:55:50 INFO - PROCESS | 21849 | Values: 295.9 300.6 302.8 306.5 311.2 4773.6 4774.4 4775.2 4800.9 4817.7 4695.8 4698.2 4761.3 4766.7 4777.5 3535.8 3657.2 3661.7 3748.6 3766.0 3713.4 3729.9 3739.4 3739.7 3745.3 3574.7 3696.4 3706.9 3717.9 3726.7 3621.3 3717.2 3724.1 3724.6 3734.2 3289.9 3292.9 3302.7 3333.7 3343.5 4519.8 4530.1 4553.4 4558.8 4571.2 3972.9 4183.4 4189.0 4297.4 4304.2 4936.6 4938.1 4944.2 4960.5 4970.2 3513.3 3531.2 3547.3 3558.9 3562.6 5038.2 5160.3 5193.3 5216.6 5222.6 3563.8 3575.0 3577.9 3589.1 3604.1 3636.7 3651.1 3659.0 3670.2 3672.2 4187.2 4223.2 4235.2 4238.5 4252.3 4877.4 4951.7 4967.8 4987.2 5023.6 1263.7 1275.7 1280.8 1286.4 1290.0 809.2 831.3 861.4 877.3 880.4 4454.1 4460.4 4468.0 4469.6 4474.4 5025.5 5041.5 5048.2 5092.2 5119.4 4265.0 4353.8 4437.8 4444.9 4453.5 2670.9 2673.9 2681.4 2708.9 2727.2 4808.4 4838.6 4863.4 4863.5 4878.5 5017.6 5110.4 5172.7 5195.7 5207.5 4670.1 4730.5 4799.2 4800.2 4815.5 5013.4 5018.3 5045.1 5049.9 5118.5 4795.8 4919.8 4922.4 4991.3 4996.4 4796.3 4808.4 4814.0 4820.8 4827.2 3613.5 3627.4 3629.5 3632.9 3634.4 4878.3 5047.2 5136.9 5143.9 5154.4 4846.0 4846.9 4881.1 4899.1 4904.3 4897.4 4911.7 4919.2 4927.3 4931.6 4680.8 4836.3 4857.6 4858.5 4860.9 4919.4 4928.8 4951.0 4958.7 4960.9 4815.0 4828.8 4875.9 4886.4 4888.5 21:55:50 INFO - PROCESS | 21849 | 21:55:50 INFO - PROCESS | 21849 | [#5] yui.html Cycles:180 Average:13578.65 Median:4243.88 stddev:15703.61 (370.0%) stddev-sans-first:15717.66 21:55:50 INFO - PROCESS | 21849 | Values: 653.0 721.3 733.7 735.0 813.6 13771.8 14272.9 14275.6 14917.8 14943.2 13382.3 13440.9 13465.3 13465.4 13480.4 634.4 767.6 822.8 823.0 829.3 817.8 823.0 823.4 828.6 829.1 850.6 855.8 858.0 858.6 859.4 655.6 822.1 843.0 844.1 855.8 631.4 631.9 632.8 633.1 633.4 12842.8 12857.2 12878.9 12882.7 12892.2 12153.6 12312.8 12414.0 12677.6 12686.7 11218.5 11349.8 11432.4 11536.8 11851.5 4067.7 4141.6 4158.1 4168.9 4318.9 47416.5 47612.8 47731.5 47776.5 47845.5 9749.9 9997.6 10496.4 10588.8 10604.6 17905.7 17907.0 17984.6 17998.6 18043.9 6752.7 6753.6 6812.8 6871.7 6916.4 3551.4 3574.9 3617.9 3636.8 3697.9 2760.5 2845.3 2845.6 2848.3 2933.4 535.6 566.2 570.3 590.3 597.2 489.6 500.1 560.3 593.3 602.2 8738.6 8788.9 8903.2 8942.9 8986.7 485.1 567.2 613.9 621.9 623.3 4038.8 4058.1 4115.2 4134.8 4149.6 58201.4 58973.7 59547.0 59795.4 59876.7 39014.6 41002.0 44540.7 53337.7 54331.5 49171.3 49718.3 50448.7 50462.7 51038.0 7150.7 7208.3 7243.6 7292.7 7484.2 29238.3 32449.7 32717.3 33880.2 34520.5 37795.8 38675.0 39721.8 39919.6 40738.2 10596.9 10728.4 10960.1 11165.3 11438.9 11614.1 11993.0 12067.2 12846.6 12903.0 8170.6 9429.3 11001.6 11137.5 11659.3 11272.9 11419.1 11683.9 12402.4 12714.9 10394.5 10972.5 11142.4 11569.2 11578.3 9572.0 9577.8 9649.7 10073.7 10224.1 9896.4 9905.4 9932.6 10064.9 10071.0 21:55:50 INFO - PROCESS | 21849 | -------- Summary: end -------- 21:55:50 INFO - PROCESS | 21849 | 21:55:51 INFO - TEST-INFO | 21849: exit 0 21:55:51 INFO - TEST-OK | dromaeo_css | took 1118572ms 21:55:51 INFO - TEST-START | kraken 21:55:51 INFO - operating with platform_type : linux_ 21:55:51 INFO - Initialising browser for kraken test... 21:55:51 INFO - TEST-INFO | started process 22024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2rHqJR/profile http://localhost:41045/getInfo.html) 21:55:55 INFO - PROCESS | 22024 | __metrics Screen width/height:1600/1200 21:55:55 INFO - PROCESS | 22024 | colorDepth:24 21:55:55 INFO - PROCESS | 22024 | Browser inner width/height: 1024/697 21:55:55 INFO - PROCESS | 22024 | __metrics 21:55:56 ERROR - PROCESS | 22024 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:55:57 INFO - TEST-INFO | 22024: exit 0 21:55:57 INFO - Browser initialized. 21:55:57 INFO - Running cycle 1/1 for kraken test... 21:55:57 INFO - TEST-INFO | started process 22105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2rHqJR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:09 INFO - PROCESS | 22105 | RSS: Main: 154968064 21:56:09 INFO - PROCESS | 22105 | 21:56:12 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41045/tests/kraken/driver.html?testName=audio-beat-detection) 21:56:13 INFO - PROCESS | 22105 | RSS: Main: 210931712 21:56:13 INFO - PROCESS | 22105 | 21:56:15 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41045/tests/kraken/driver.html?testName=audio-dft) 21:56:16 INFO - PROCESS | 22105 | RSS: Main: 199192576 21:56:16 INFO - PROCESS | 22105 | 21:56:19 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41045/tests/kraken/driver.html?testName=audio-fft) 21:56:20 INFO - PROCESS | 22105 | RSS: Main: 268058624 21:56:20 INFO - PROCESS | 22105 | 21:56:22 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41045/tests/kraken/driver.html?testName=audio-oscillator) 21:56:23 INFO - PROCESS | 22105 | RSS: Main: 176533504 21:56:23 INFO - PROCESS | 22105 | 21:56:25 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41045/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:56:25 INFO - PROCESS | 22105 | RSS: Main: 179900416 21:56:25 INFO - PROCESS | 22105 | 21:56:29 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41045/tests/kraken/driver.html?testName=imaging-darkroom) 21:56:29 INFO - PROCESS | 22105 | RSS: Main: 215998464 21:56:29 INFO - PROCESS | 22105 | 21:56:33 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41045/tests/kraken/driver.html?testName=imaging-desaturate) 21:56:33 INFO - PROCESS | 22105 | RSS: Main: 214339584 21:56:33 INFO - PROCESS | 22105 | 21:56:36 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41045/tests/kraken/driver.html?testName=json-parse-financial) 21:56:37 INFO - PROCESS | 22105 | RSS: Main: 215875584 21:56:37 INFO - PROCESS | 22105 | 21:56:39 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41045/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:56:39 INFO - PROCESS | 22105 | RSS: Main: 203984896 21:56:39 INFO - PROCESS | 22105 | 21:56:41 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:56:42 INFO - PROCESS | 22105 | RSS: Main: 207384576 21:56:42 INFO - PROCESS | 22105 | 21:56:44 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:56:45 INFO - PROCESS | 22105 | RSS: Main: 218791936 21:56:45 INFO - PROCESS | 22105 | 21:56:47 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:56:48 INFO - PROCESS | 22105 | RSS: Main: 225202176 21:56:48 INFO - PROCESS | 22105 | 21:56:51 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:56:52 INFO - PROCESS | 22105 | RSS: Main: 221966336 21:56:52 INFO - PROCESS | 22105 | 21:56:53 INFO - PROCESS | 22105 | Cycle 1(1): loaded http://localhost:41045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41045/tests/kraken/driver.html?testName=ai-astar) 21:56:54 INFO - PROCESS | 22105 | RSS: Main: 266895360 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | __start_tp_report 21:56:54 INFO - PROCESS | 22105 | _x_x_mozilla_page_load 21:56:54 INFO - PROCESS | 22105 | _x_x_mozilla_page_load_details 21:56:54 INFO - PROCESS | 22105 | |i|pagename|runs| 21:56:54 INFO - PROCESS | 22105 | |0;ai-astar;113;107;111;112;112;113;111;113;111;112 21:56:54 INFO - PROCESS | 22105 | |1;audio-beat-detection;179;132;133;158;137;154;162;137;195;139 21:56:54 INFO - PROCESS | 22105 | |2;audio-dft;164;161;162;161;164;162;162;162;160;161 21:56:54 INFO - PROCESS | 22105 | |3;audio-fft;75;76;77;107;76;78;75;76;102;77 21:56:54 INFO - PROCESS | 22105 | |4;audio-oscillator;86;83;128;84;84;83;121;84;85;132 21:56:54 INFO - PROCESS | 22105 | |5;imaging-gaussian-blur;116;116;117;117;116;116;117;117;117;116 21:56:54 INFO - PROCESS | 22105 | |6;imaging-darkroom;145;146;145;146;146;146;146;146;146;147 21:56:54 INFO - PROCESS | 22105 | |7;imaging-desaturate;94;94;95;94;94;95;94;96;95;94 21:56:54 INFO - PROCESS | 22105 | |8;json-parse-financial;64;64;64;63;64;64;64;78;64;61 21:56:54 INFO - PROCESS | 22105 | |9;json-stringify-tinderbox;65;66;65;65;66;66;66;66;65;65 21:56:54 INFO - PROCESS | 22105 | |10;stanford-crypto-aes;82;82;83;82;83;84;83;83;83;83 21:56:54 INFO - PROCESS | 22105 | |11;stanford-crypto-ccm;131;132;128;129;130;134;127;128;177;132 21:56:54 INFO - PROCESS | 22105 | |12;stanford-crypto-pbkdf2;168;170;172;172;173;169;168;170;178;167 21:56:54 INFO - PROCESS | 22105 | |13;stanford-crypto-sha256-iterative;65;68;66;65;66;73;64;65;65;65 21:56:54 INFO - PROCESS | 22105 | __end_tp_report 21:56:54 INFO - PROCESS | 22105 | __start_cc_report 21:56:54 INFO - PROCESS | 22105 | _x_x_mozilla_cycle_collect,1904 21:56:54 INFO - PROCESS | 22105 | __end_cc_report 21:56:54 INFO - PROCESS | 22105 | __startTimestamp1464152214064__endTimestamp 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | ------- Summary: start ------- 21:56:54 INFO - PROCESS | 22105 | Number of tests: 14 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.78 (1.6%) stddev-sans-first:1.80 21:56:54 INFO - PROCESS | 22105 | Values: 113.0 107.0 111.0 112.0 112.0 113.0 111.0 113.0 111.0 112.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:156.00 stddev:21.33 (13.7%) stddev-sans-first:20.37 21:56:54 INFO - PROCESS | 22105 | Values: 179.0 132.0 133.0 158.0 137.0 154.0 162.0 137.0 195.0 139.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.12 21:56:54 INFO - PROCESS | 22105 | Values: 164.0 161.0 162.0 161.0 164.0 162.0 162.0 162.0 160.0 161.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:12.00 (15.8%) stddev-sans-first:12.47 21:56:54 INFO - PROCESS | 22105 | Values: 75.0 76.0 77.0 107.0 76.0 78.0 75.0 76.0 102.0 77.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:84.00 stddev:20.89 (24.9%) stddev-sans-first:21.77 21:56:54 INFO - PROCESS | 22105 | Values: 86.0 83.0 128.0 84.0 84.0 83.0 121.0 84.0 85.0 132.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:56:54 INFO - PROCESS | 22105 | Values: 116.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 21:56:54 INFO - PROCESS | 22105 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 21:56:54 INFO - PROCESS | 22105 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 94.0 96.0 95.0 94.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:64.00 stddev:4.67 (7.3%) stddev-sans-first:4.94 21:56:54 INFO - PROCESS | 22105 | Values: 64.0 64.0 64.0 63.0 64.0 64.0 64.0 78.0 64.0 61.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 21:56:54 INFO - PROCESS | 22105 | Values: 65.0 66.0 65.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:0.63 (0.8%) stddev-sans-first:0.60 21:56:54 INFO - PROCESS | 22105 | Values: 82.0 82.0 83.0 82.0 83.0 84.0 83.0 83.0 83.0 83.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:131.50 stddev:14.99 (11.4%) stddev-sans-first:15.83 21:56:54 INFO - PROCESS | 22105 | Values: 131.0 132.0 128.0 129.0 130.0 134.0 127.0 128.0 177.0 132.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:171.00 stddev:3.23 (1.9%) stddev-sans-first:3.28 21:56:54 INFO - PROCESS | 22105 | Values: 168.0 170.0 172.0 172.0 173.0 169.0 168.0 170.0 178.0 167.0 21:56:54 INFO - PROCESS | 22105 | 21:56:54 INFO - PROCESS | 22105 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:65.50 stddev:2.62 (4.0%) stddev-sans-first:2.74 21:56:54 INFO - PROCESS | 22105 | Values: 65.0 68.0 66.0 65.0 66.0 73.0 64.0 65.0 65.0 65.0 21:56:54 INFO - PROCESS | 22105 | -------- Summary: end -------- 21:56:54 INFO - PROCESS | 22105 | 21:56:55 INFO - TEST-INFO | 22105: exit 0 21:56:55 INFO - TEST-OK | kraken | took 63893ms 21:56:55 INFO - SUITE-END | took 1182s 21:56:55 INFO - Completed test suite (00:19:42) 21:56:55 INFO - javascript benchmark 21:56:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2687.8387296762194, 2701.0951331304013, 2722.4418994106204, 2728.236093893709, 2747.2445077603657, 4618.05329907369, 4686.55477729616, 4733.313669517922, 4806.567408933191, 4850.951490485094, 4622.491525932145, 4639.65202609804, 4770.165221086313, 4805.135075686375, 4817.951820481794, 4263.829446822124, 4271.63691086258, 4290.97854510727, 4332.7833608319615, 4527.456705195381, 4442.289233722605, 4464.508904020555, 4472.82108715651, 4501.887452813673, 4523.570260825233, 4265.0, 4314.956850431487, 4315.611594956437, 4330.220560299145, 4377.62790162835, 4308.353746937984, 4313.805878735465, 4317.050248945227, 4403.669724770655, 4422.756748378838, 4355.5208927018, 4358.302671572533, 4375.890602734957, 4405.493368262659, 4423.557644235583, 4551.68138230324, 4640.69835460694, 4654.953450465519, 4664.5102264262105, 4729.763511824395, 5094.796208151706, 5148.639595228336, 5160.7419629018395, 5172.353455818023, 5212.0, 5160.354955630546, 5174.327337446108, 5217.269582258449, 5238.13570760824, 5239.5546378557865, 4673.182193116191, 4806.639502037361, 4852.4662287148385, 4858.58702010326, 4876.878078048077, 5321.228421878806, 5359.919601205985, 5388.784448622011, 5393.7572809223675, 5398.163284690879, 5108.66793658411, 5114.5652619527755, 5156.432792392834, 5164.0, 5182.792688292501, 4997.875053123701, 5114.232865070269, 5174.508421699932, 5179.119549676564, 5194.6104042196985, 5119.948800511947, 5145.073886700355, 5148.279240906276, 5151.201563757623, 5204.635675502678, 4926.3349447824085, 5112.437631860566, 5130.563902068291, 5135.281060651511, 5139.12634852076, 4327.82688692455, 4348.956510434855, 4364.432623758891, 4366.410534577855, 4370.322599997005, 3772.4529943158086, 3789.204267103884, 3790.0714324990554, 3824.694024478035, 3830.7701537907815, 4363.3018717005125, 4394.406755088086, 4443.400140980926, 4445.2887537993765, 4462.531434199427, 5134.640575159778, 5185.196294574347, 5221.817236396708, 5229.869253268699, 5231.084560202025, 4472.709273897186, 4502.752348620792, 4510.458744950562, 4511.684182107221, 4560.042391097906, 4464.196444639996, 4623.768811559408, 4720.291956206596, 4724.929126063112, 4757.0, 4819.132556139999, 4845.927311090266, 4870.269459581091, 4870.829520966749, 4911.115999120193, 5154.948450515447, 5168.767405466688, 5170.43125256214, 5170.508801663836, 5219.582433405242, 4688.226442636927, 4785.090832741768, 4800.927986080282, 4840.83057093, 4864.756762161948, 5033.672811267256, 5047.949520504748, 5050.823221187241, 5057.241413787961, 5096.439391666988, 5094.032133894548, 5098.821541246039, 5099.031184075162, 5108.3103780989095, 5135.435102138687, 4879.0, 4950.084234416644, 4954.108260513142, 4993.076280888047, 5000.2499625056535, 4982.676126051795, 4999.45006049327, 5010.048090862724, 5013.348264725708, 5033.496650334937, 4882.316475693477, 4933.309336692794, 4935.333729946411, 4985.252212168204, 4987.226979818134, 4866.270059491105, 4974.154393752999, 5037.168867136881, 5042.84871453857, 5066.59467242627, 4945.035718035093, 4972.403311602631, 4972.726500042388, 4975.7512124392915, 5023.974880125576], "value": 4713.636620106346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6338761498833, 582.3099626942071, 583.4923616453686, 595.0598055072984, 610.0787810406287, 41346.79326603367, 42453.78773106138, 42481.57518424811, 42597.0, 46793.29810052852, 43711.344329834996, 44437.55562444375, 44515.554844451624, 44547.33179002318, 44572.108557828906, 7769.611519424035, 7939.920600794005, 7945.4438189326775, 7953.323967462773, 7955.443118981673, 7785.493942893722, 7841.019872515935, 7880.960595197016, 7881.7241396551135, 7888.329491993159, 7883.054033515986, 7888.723894663688, 7910.525368477881, 7919.445638805286, 7926.24700653437, 7854.096778870446, 7896.328812050925, 7915.52506849588, 7920.16838231986, 7922.168172341936, 7769.3784497240085, 7842.372610191228, 7850.764477065697, 7854.057513098408, 7855.567943763091, 40178.39732403987, 40682.18635627301, 40804.79597602023, 40947.590524094674, 40997.0, 3143.5599016137758, 3293.8682452701864, 3385.5768028996376, 3580.7493475456727, 3852.210296889131, 27403.45193096147, 27738.722612774018, 28574.285642858886, 31742.841285793653, 32412.0, 3571.375009373375, 3587.336342776595, 3588.3002814451193, 3621.0, 3649.251903359805, 268454.0, 269909.0, 272693.0, 273003.0, 273316.0, 58266.0, 58332.4166758327, 58580.7070964651, 58754.412455874895, 58757.70621146867, 52284.738576306874, 61068.38931610716, 61814.0, 62410.68794655998, 68060.3193968054, 14597.343119559646, 14795.482158124414, 14807.407703691948, 14826.851731482762, 14831.5550533482, 10190.541425635864, 11165.888341116484, 11221.0, 11226.607068752555, 11237.775244495228, 18755.0, 19183.232670693294, 19456.610867782565, 19487.415377538695, 20065.17063628696, 6989.89515157273, 7009.9299007010295, 7071.25751796054, 7107.609081500464, 7115.71537138519, 878.4202426398547, 952.2572393533055, 971.7099007880271, 1024.5543188712934, 1041.9739506512399, 28575.857120714678, 29032.12903612895, 29111.708882910898, 29621.25946851346, 29813.552796708067, 979.7697541077841, 1043.1498328861953, 1054.330500132406, 1084.2356138922041, 1100.6918062942389, 15167.620809479851, 15396.0, 15474.303656335493, 15505.457308994131, 15689.137097459521, 395220.0, 396639.0, 398244.0, 399806.0, 400044.0, 51866.48133518617, 53224.46775532272, 53269.733651331495, 53432.198517022276, 53502.46497535053, 1074.484247561175, 1184.1000839362148, 1216.8357271768198, 1224.2899118511243, 1237.0845574274972, 28648.997285095757, 29716.85141574278, 29732.0, 30242.24394390158, 30422.39155216839, 43442.0, 43679.12641747211, 44065.339019914085, 44183.33724994192, 44250.55749442464, 284116.0, 284521.0, 285168.0, 286005.0, 286134.0, 23420.414489637897, 25118.497630047663, 25939.35151621149, 26259.0, 27167.86416067907, 43652.345214821165, 44136.0, 45354.31968520541, 45399.0, 49942.25086623631, 38190.6180938187, 38579.61420385873, 39538.40692389559, 39928.201435970535, 40161.1967760649, 36809.31906809298, 38474.422883657324, 38622.227555449295, 38684.226315474094, 39007.80496097615, 37086.44370334393, 37176.81411593033, 37405.62594374021, 38056.42915356217, 38067.80966095152, 32518.34963300768, 34200.82899585486, 34798.30403391867, 34869.302613948086, 34975.12562185965, 26372.20883373425, 27187.86406067957, 27764.583531247452, 27919.302017449725, 28072.719272807826], "value": 18332.6653323496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.49697178492437, 357.7191904355081, 362.7406404420839, 374.36171327885955, 379.36077709060237, 15990.760138597916, 17216.655666886654, 17367.218475168615, 17656.205470753815, 17843.821561784378, 16585.41951031714, 16853.157342132876, 17442.0, 17460.0, 17488.912555437204, 4368.213721530124, 5097.643164978453, 5099.515546023141, 5102.438731739506, 5103.846884593449, 3707.6663100320966, 3834.789086600236, 5088.847334579968, 5619.831405057855, 5968.2838059432925, 6016.217891674098, 6028.4272994065495, 6033.7888173913925, 6047.5766696331275, 6048.062550304688, 5859.6777177255235, 5896.204012458306, 6041.124037014608, 6065.150878876939, 6070.6661133637635, 3342.782719123249, 4883.12103821312, 5176.792928282902, 5177.171652535576, 5245.134552798783, 20043.0, 20370.388888333375, 20435.284765033153, 20919.05864236113, 20958.05688743995, 1909.5417099896065, 1933.58427937994, 2028.868123571963, 2042.79572042796, 2546.987265063663, 13111.803322950163, 13204.0, 13273.137246078973, 13341.466341346335, 13552.119112257671, 1674.790651168604, 1710.298777501231, 1761.2954818072744, 1803.323753592403, 1804.440623406735, 64101.35898641001, 64515.67742161306, 65743.67128164376, 66116.33883661198, 66773.66613166951, 8383.0, 8938.24024957886, 9097.270119251076, 9227.492487913229, 9340.673076442292, 12191.756164876653, 12200.816987745191, 12214.328211948428, 12264.141510094387, 12291.877081229251, 7005.334493223136, 7070.0, 7151.82120446993, 7227.74702885407, 7265.309795569412, 6867.965660171767, 6997.265287144878, 7042.507024508338, 7084.326988936043, 7174.928250717426, 3886.4947556817997, 3908.335582950905, 3913.6477717005605, 3967.543732470745, 4013.0, 1737.7504067394054, 1772.6100257943233, 1800.918958646864, 1828.4254282876943, 1846.5291350705484, 752.7102065704634, 852.9914700853036, 871.7733389318696, 894.3873446689039, 989.3074847606704, 16335.428260010842, 16639.25123369451, 17004.149792510325, 17020.744688829676, 17323.740143897852, 847.2290215903621, 851.4252879306443, 871.263782104121, 889.3241136736127, 901.4275934781462, 9377.812443751087, 9540.284478664127, 9567.712968610953, 9620.326577139533, 9701.563429645683, 385921.0, 388306.0, 391842.0, 391993.0, 393023.0, 70227.64886175639, 72520.0, 73064.63467682734, 83029.16970830335, 84422.5778871114, 943.7310366545502, 958.6357184269933, 960.8078384323163, 974.7124598243536, 984.1683777208248, 15553.84446155524, 15706.371745130067, 15714.564242542425, 15732.449364272195, 16109.758353624939, 34874.65125348714, 36542.634573654985, 37039.444408334435, 37220.25559488849, 37481.437778433894, 49448.25827612517, 50862.23706644477, 50931.0, 51519.48480515297, 52090.73954630203, 7348.742794002184, 7372.889406659012, 7553.395728341829, 7688.115866675404, 7723.729669461542, 10362.689119326433, 10636.255462117888, 10762.461876905967, 11153.107751380032, 11402.315861048364, 8959.835221421434, 9246.260299175916, 9368.4378937264, 9409.48247846375, 9677.274204434554, 9487.620495180116, 9632.614695412105, 9708.854367184356, 9929.205663547044, 10037.749056273651, 9503.422526446795, 10089.344192627455, 10237.897621023694, 10309.845352319571, 10390.272680912263, 6373.107764913008, 6735.595864248161, 6982.406495447944, 7072.2220555737795, 7620.237976202336, 7469.365103966224, 8542.234943874753, 9506.38208516472, 9510.809783804423, 9764.170045545923], "value": 8123.6641757736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1200.1658847101269, 1398.699279654874, 1433.3048471491327, 1556.6575353422243, 1796.6406718656272, 6965.268646792084, 7743.496672716276, 7974.122846486882, 7985.081715602715, 8246.876296855537, 7973.162817904124, 8079.474834135777, 8136.877946830802, 8138.715144969912, 8142.267195952363, 2188.8796116213603, 2221.577900198957, 2222.944426389337, 2223.5108276179217, 2224.1214720185517, 2173.0981642618294, 2184.1918490158664, 2191.024993877725, 2202.9889850550726, 2209.3482422685265, 2119.1841141160685, 2133.434639820449, 2134.7758485359045, 2135.4020874155185, 2162.4053385319007, 2137.4335801012667, 2142.1324363632752, 2145.1526646974435, 2146.12009076278, 2146.6350720377573, 2106.8103870651717, 2153.5800518898823, 2158.57907707997, 2161.0923412166926, 2162.5458653682745, 7764.844703105963, 7930.444868859125, 8007.439479236456, 8015.318697910685, 8058.1538938411795, 4668.323093151508, 4784.712917224942, 4789.521047895218, 4790.0, 4819.349387832633, 2862.169970708492, 2865.7134286571386, 2887.725666061721, 2901.419716056797, 2940.5442156465783, 896.4890012692767, 897.5422534507383, 900.6757567275776, 912.1562554636935, 927.3879239701832, 10959.287646302964, 11087.889121108767, 11120.388378639173, 11122.388268645222, 11141.387223702694, 4069.2878746219294, 4147.709660323749, 4210.5578914213565, 4217.746935183899, 4228.450301460852, 8724.43291186071, 8794.956025219833, 8801.559922003875, 8810.911890881138, 8813.162749538784, 4698.365720627671, 4759.833405830779, 4761.381020467386, 4762.523747625279, 4764.14245435818, 3736.551613806361, 4074.409210664437, 4275.444192254987, 4288.313869780883, 4330.0, 3399.6940275375337, 3444.4144495435835, 3464.8787292444645, 3486.337595856779, 3525.7884526928465, 1004.0762498501397, 1006.4866917871938, 1010.4846528270635, 1019.6431249062769, 1022.4223313827664, 674.2078058281597, 699.7795694356262, 727.8835386338267, 785.3834739729285, 819.0539926385035, 5442.809501667422, 5549.944500554942, 5608.579356548275, 5778.595498315078, 5778.711064446761, 861.3539845116163, 886.1227384888914, 936.7704912296394, 940.5673390240429, 942.9104235097655, 3704.129529560551, 3822.7132965027736, 3833.639058134378, 3931.5478719947, 3947.289487892207, 9670.661526846527, 9993.450360230112, 10038.749031274276, 10056.094951454405, 10058.34620749649, 9966.352187107816, 10013.249006324555, 10129.341592796445, 10131.64539241123, 10131.645392411378, 7408.0, 7555.320021198118, 7721.65252563636, 7733.4973226740085, 7735.61321933901, 5444.074507333842, 5453.700046497322, 5474.178873169057, 5479.150731636602, 5600.4959553640365, 9368.437893726126, 9406.623735050522, 9460.716178514656, 9488.0, 9523.0, 8573.442726222776, 8847.20375166238, 8899.911000889908, 9115.908840911507, 9145.085491450802, 6730.091437655854, 6813.69338379784, 6852.794416167523, 6854.040434339297, 6860.897086543606, 7303.123625165014, 7390.7413240536325, 7506.211847755907, 7510.62446877643, 7518.398528117847, 5890.9116363253725, 6245.96877015612, 6933.445324374139, 7120.786376408716, 7170.318819712119, 6611.801645950629, 6883.380495755406, 6901.689423975833, 7035.613041282779, 7090.255523170199, 6362.331955144643, 6372.0760489729655, 6396.840078998062, 6413.390727880844, 6452.12896258999, 4618.099470603334, 4749.738764367993, 4750.548697873696, 4772.880677983078, 4850.6362022847725, 4818.566329030404, 4852.781624826821, 4880.511948805233, 4897.632677549127, 4903.436104847988], "value": 4177.017627255253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.8757321924791, 300.5626812987103, 302.83192827980463, 306.4560405280627, 311.2281541776394, 4773.591790421825, 4774.42706875175, 4775.188218002939, 4800.927986080212, 4817.662763606549, 4695.8356457524, 4698.238160689742, 4761.285807128932, 4766.690165139264, 4777.450593181777, 3535.752497325182, 3657.213699054703, 3661.6521430464063, 3748.6438788315068, 3765.9623403766022, 3713.3687273163628, 3729.9254014919684, 3739.420389839566, 3739.6821270192067, 3745.307118183131, 3574.696150827183, 3696.3716168251462, 3706.8517259309597, 3717.869874554391, 3726.7018638508853, 3621.275744851027, 3717.182219911615, 3724.087598538348, 3724.571674257468, 3734.2344819311975, 3289.9342013159844, 3292.851821668031, 3302.6862448067395, 3333.749968752353, 3343.498475228711, 4519.757066806622, 4530.1392735380505, 4553.362529245908, 4558.817647294104, 4571.1771881061395, 3972.8609498667697, 4183.372494125875, 4188.979055104735, 4297.3983642289795, 4304.160688665712, 4936.568395165397, 4938.135826230396, 4944.233643785183, 4960.454350021495, 4970.204767237261, 3513.2973405318785, 3531.2407832316176, 3547.308274886398, 3558.857645694169, 3562.6259242779392, 5038.168702164138, 5160.303359046518, 5193.324867767204, 5216.556592689626, 5222.608304377149, 3563.8396272167297, 3574.999000279926, 3577.928441431157, 3589.138606734417, 3604.098975256186, 3636.654517820803, 3651.087228192952, 3659.0, 3670.2109046554583, 3672.192117734094, 4187.183499217685, 4223.1975924574235, 4235.174141042528, 4238.491381034234, 4252.277112890816, 4877.3903262092235, 4951.702897826142, 4967.776450059756, 4987.202047672355, 5023.648344615842, 1263.690395853022, 1275.7193417448148, 1280.8472374862697, 1286.3825363825417, 1289.9742005160033, 809.1786836361098, 831.264331066998, 861.3927181337267, 877.2630989968458, 880.4365206267994, 4454.086912182995, 4460.397846290774, 4467.955320446819, 4469.575390337913, 4474.418325617649, 5025.4974502550185, 5041.521055499722, 5048.192289233705, 5092.185250359925, 5119.385673719177, 4264.978675106605, 4353.804078816461, 4437.844675436344, 4444.888877778081, 4453.532379100213, 2670.8798104085363, 2673.9036994832413, 2681.43689825135, 2708.9051883183993, 2727.2409296003966, 4808.374911261583, 4838.637102217348, 4863.419388698687, 4863.465018847924, 4878.463284065508, 5017.648764586517, 5110.36120484932, 5172.663776854492, 5195.6622819516615, 5207.453217412193, 4670.1360248353485, 4730.526947305242, 4799.160146974265, 4800.159972004885, 4815.4590531030435, 5013.423456302426, 5018.272350509229, 5045.142325804695, 5049.899002019865, 5118.462561430996, 4795.832145874878, 4919.778609962489, 4922.440331027917, 4991.276264941491, 4996.400431948044, 4796.25658023007, 4808.35087263229, 4814.0, 4820.807167713392, 4827.227643577011, 3613.5483064616924, 3627.383344831332, 3629.5100161478917, 3632.9101269619537, 3634.3639863024396, 4878.317035615088, 5047.217681259411, 5136.897262054663, 5143.897122057613, 5154.433012368561, 4845.988914600402, 4846.903061938812, 4881.096997055556, 4899.142650036159, 4904.2643603459765, 4897.363342765418, 4911.666958194829, 4919.188333925005, 4927.260910863399, 4931.580815630711, 4680.836170734008, 4836.347093142522, 4857.611391088775, 4858.5384388483035, 4860.854174374775, 4919.401194611694, 4928.778204980713, 4951.0, 4958.652894297365, 4960.925586116139, 4814.975925120352, 4828.782704778364, 4875.926861097016, 4886.438059623047, 4888.533439968067], "value": 3742.3635845468098, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.9510286728494, 721.3399739238597, 733.684515658267, 734.966926488308, 813.60133534568, 13771.793423098661, 14272.857271427296, 14275.571732848019, 14917.776233356508, 14943.178125203109, 13382.330883455812, 13440.932795336033, 13465.326733663302, 13465.394057267422, 13480.393382297796, 634.3624657219488, 767.6277005652254, 822.8148666550038, 823.0247157118804, 829.2909562324216, 817.7996390884211, 823.0247157118804, 823.3618945317367, 828.6022709099636, 829.1045670675655, 850.5747126436781, 855.8288342331558, 857.9790049840669, 858.6436628799041, 859.3726579596898, 655.5509476008949, 822.0792712162356, 842.9873551896726, 844.0926004545108, 855.8331125430543, 631.356016862802, 631.8578319878037, 632.7880160146357, 633.1041576169714, 633.3729607688401, 12842.807357889638, 12857.164284321489, 12878.87121128786, 12882.742345153138, 12892.226466412045, 12153.635390938285, 12312.753744925141, 12414.0, 12677.619671409873, 12686.682832929158, 11218.495167717472, 11349.829752553718, 11432.428378581038, 11536.769264614744, 11851.525938962432, 4067.6542493888055, 4141.585841415865, 4158.064435501988, 4168.874933751962, 4318.892027699333, 47416.5258347419, 47612.76193619044, 47731.52268477305, 47776.522234777556, 47845.5215447848, 9749.9025009749, 9997.550110245056, 10496.422696751599, 10588.788224235472, 10604.575816967235, 17905.7314140288, 17907.0, 17984.640307194044, 17998.550036248937, 18043.909780451013, 6752.696128674222, 6753.628550429774, 6812.761553345708, 6871.725130994704, 6916.446684265347, 3551.4317709166407, 3574.857005719742, 3617.909552261162, 3636.836342364547, 3697.9445308320396, 2760.530709779302, 2845.260232339607, 2845.5873898285045, 2848.273690208983, 2933.4133173365412, 535.6143576624823, 566.2073097663305, 570.2700543304577, 590.3063899917665, 597.1788790413182, 489.6107594462411, 500.1147968096449, 560.2548610348308, 593.3295376224838, 602.2381687165739, 8738.606762695694, 8788.871273153674, 8903.243224326005, 8942.910570894208, 8986.685466008788, 485.10256026351675, 567.2257368691706, 613.8557439001831, 621.8787336469345, 623.314354210365, 4038.818253178614, 4058.1072164123843, 4115.1975364803575, 4134.813933373005, 4149.647279981175, 58201.417985820444, 58973.70513147407, 59547.0, 59795.40204597898, 59876.70061649664, 39014.6098539011, 41002.0, 44540.66378008665, 53337.73331133397, 54331.45668543264, 49171.26243106428, 49718.25422618663, 50448.74775626172, 50462.74768626207, 51038.0, 7150.67821948024, 7208.315210055036, 7243.601601911945, 7292.671829767567, 7484.176740558425, 29238.26904327409, 32449.675503244664, 32717.345653087283, 33880.15299617529, 34520.48219276763, 37795.81102094582, 38675.0, 39721.80139099286, 39919.60080399159, 40738.1852362957, 10596.8940310599, 10728.356298622108, 10960.068394186583, 11165.330080195214, 11438.885611143782, 11614.128940329678, 11993.040556755614, 12067.155299129328, 12846.614601561969, 12902.96776257916, 8170.550619715973, 9429.292803039936, 11001.559937602406, 11137.498812553293, 11659.291044218917, 11272.943635281772, 11419.14356423255, 11683.88316116862, 12402.379881005732, 12714.87285127137, 10394.532246049097, 10972.506237219504, 11142.387168705798, 11569.247998880046, 11578.305301681927, 9571.952140239255, 9577.808443830945, 9649.662261820802, 10073.69778906634, 10224.13094886943, 9896.356736812084, 9905.405675659484, 9932.602695892083, 10064.899351006396, 10071.0], "value": 6175.9909606512565, "unit": "score"}], "name": "dromaeo_css", "value": 6385.339931529717, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 111.0, 112.0, 112.0, 113.0, 111.0, 113.0, 111.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 132.0, 133.0, 158.0, 137.0, 154.0, 162.0, 137.0, 195.0, 139.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 161.0, 164.0, 162.0, 162.0, 162.0, 160.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 107.0, 76.0, 78.0, 75.0, 76.0, 102.0, 77.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 128.0, 84.0, 84.0, 83.0, 121.0, 84.0, 85.0, 132.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 78.0, 64.0, 61.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 82.0, 83.0, 84.0, 83.0, 83.0, 83.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 128.0, 129.0, 130.0, 134.0, 127.0, 128.0, 177.0, 132.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 172.0, 172.0, 173.0, 169.0, 168.0, 170.0, 178.0, 167.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 65.0, 66.0, 73.0, 64.0, 65.0, 65.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1546.8, "alertThreshold": 2.0}]} 21:56:55 INFO - Return code: 0 21:56:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:56:55 ERROR - # TBPL SUCCESS # 21:56:55 INFO - Running post-action listener: _resource_record_post_action 21:56:55 INFO - ##### 21:56:55 INFO - ##### Finished run-tests step (success) 21:56:55 INFO - ##### 21:56:55 INFO - Running post-run listener: _resource_record_post_run 21:56:55 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530210816; Read time: 16; Write time: 427740 21:56:55 INFO - TinderboxPrint: CPU usage
13.1% 21:56:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 21:56:55 INFO - TinderboxPrint: I/O write bytes / time
530,210,816 / 427,740 21:56:55 INFO - TinderboxPrint: CPU idle
8,317.9 (86.9%) 21:56:55 INFO - TinderboxPrint: CPU user
1,209.7 (12.6%) 21:56:55 INFO - TinderboxPrint: Swap in / out
0 / 0 21:56:55 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 246939648; Read time: 0; Write time: 259276 21:56:55 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283271168; Read time: 16; Write time: 168464 21:56:55 INFO - Running post-run listener: _upload_blobber_files 21:56:55 INFO - Blob upload gear active. 21:56:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:56:55 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 21:56:55 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:56:55 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:56:56 INFO - (blobuploader) - INFO - Open directory for files ... 21:56:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:56:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:56:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:57 INFO - (blobuploader) - INFO - Done attempting. 21:56:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:56:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:56:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:57 INFO - (blobuploader) - INFO - Done attempting. 21:56:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:56:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:57:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:57:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:57:01 INFO - (blobuploader) - INFO - Done attempting. 21:57:01 INFO - (blobuploader) - INFO - Iteration through files over. 21:57:01 INFO - Return code: 0 21:57:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:57:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:57:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3a9c014df4a0cf4a20545f9556d466ec16d55781895c11dc1ff8d4894809eb0c792c93640cdd0bbf97ced19f90fd191b6bb3a12acc01abf507b38c9156298d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d9fac74ff56a523fec0e7e09a779c300303a87e00dcd97cb846363e1c83f8ee3725a7857833514a358c82fc0a6fddb82b54a99ebf2af13a1ffb1828c3e84adc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7ad7a4b49b909f7720b7fbcc63f4e92f89aeeb32c1dc2b1634b0125d5d65f498a6a2e818c2af7c0fe36f1718c4296f04fa56256f88b015ab0f8f84a5d932a512"} 21:57:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:57:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:57:01 INFO - Contents: 21:57:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3a9c014df4a0cf4a20545f9556d466ec16d55781895c11dc1ff8d4894809eb0c792c93640cdd0bbf97ced19f90fd191b6bb3a12acc01abf507b38c9156298d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d9fac74ff56a523fec0e7e09a779c300303a87e00dcd97cb846363e1c83f8ee3725a7857833514a358c82fc0a6fddb82b54a99ebf2af13a1ffb1828c3e84adc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7ad7a4b49b909f7720b7fbcc63f4e92f89aeeb32c1dc2b1634b0125d5d65f498a6a2e818c2af7c0fe36f1718c4296f04fa56256f88b015ab0f8f84a5d932a512"} 21:57:01 INFO - Running post-run listener: copy_logs_to_upload_dir 21:57:01 INFO - Copying logs to upload dir... 21:57:01 INFO - mkdir: /builds/slave/test/build/upload/logs 21:57:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.692204 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-24 21:57:01.519470) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 21:57:01.522893) ========= 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=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 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-Non-PGO/sha512/b3a9c014df4a0cf4a20545f9556d466ec16d55781895c11dc1ff8d4894809eb0c792c93640cdd0bbf97ced19f90fd191b6bb3a12acc01abf507b38c9156298d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d9fac74ff56a523fec0e7e09a779c300303a87e00dcd97cb846363e1c83f8ee3725a7857833514a358c82fc0a6fddb82b54a99ebf2af13a1ffb1828c3e84adc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7ad7a4b49b909f7720b7fbcc63f4e92f89aeeb32c1dc2b1634b0125d5d65f498a6a2e818c2af7c0fe36f1718c4296f04fa56256f88b015ab0f8f84a5d932a512"} build_url:https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011505 build_url: 'https://queue.taskcluster.net/v1/task/cpJFpBsATDaBXKg6vsHjwg/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-Non-PGO/sha512/b3a9c014df4a0cf4a20545f9556d466ec16d55781895c11dc1ff8d4894809eb0c792c93640cdd0bbf97ced19f90fd191b6bb3a12acc01abf507b38c9156298d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d9fac74ff56a523fec0e7e09a779c300303a87e00dcd97cb846363e1c83f8ee3725a7857833514a358c82fc0a6fddb82b54a99ebf2af13a1ffb1828c3e84adc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7ad7a4b49b909f7720b7fbcc63f4e92f89aeeb32c1dc2b1634b0125d5d65f498a6a2e818c2af7c0fe36f1718c4296f04fa56256f88b015ab0f8f84a5d932a512"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 21:57:01.548215) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:57:01.548544) ========= 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=7de08804244eb36560b44971000001c3-1464148411.61822-720399212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:57:01.635970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 21:57:01.636735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 21:57:01.637305) ========= ========= Total master_lag: 0.19 =========