builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1464832892.81 results: success (0) buildid: 20160601163010 builduid: 38d364c38cee4182b8ea3c8422008336 revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:32.808640) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:32.809108) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:32.809408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:32.849009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:32.849327) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 19:01:32-- 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% 46.5M=0s 2016-06-01 19:01:33 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.224119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.093697) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.094022) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.059992 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.163270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.163693) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 19:01:33,205 truncating revision to first 12 chars 2016-06-01 19:01:33,205 Setting DEBUG logging. 2016-06-01 19:01:33,205 attempt 1/10 2016-06-01 19:01:33,206 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22047a4eea78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 19:01:33,532 unpacking tar archive at: mozilla-central-22047a4eea78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.680929 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.857887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.858258) ========= script_repo_revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.858696) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.858989) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:01:33.869507) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-01 19:01:33.869798) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:01:34 INFO - MultiFileLogger online at 20160601 19:01:34 in /builds/slave/test 19:01:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:01:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:34 INFO - {'append_to_log': False, 19:01:34 INFO - 'base_work_dir': '/builds/slave/test', 19:01:34 INFO - 'blob_upload_branch': 'Firefox', 19:01:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:34 INFO - 'branch': 'Firefox', 19:01:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:34 INFO - 'config_files': ('talos/linux_config.py',), 19:01:34 INFO - 'default_actions': ('clobber', 19:01:34 INFO - 'read-buildbot-config', 19:01:34 INFO - 'download-and-extract', 19:01:34 INFO - 'populate-webroot', 19:01:34 INFO - 'create-virtualenv', 19:01:34 INFO - 'install', 19:01:34 INFO - 'run-tests'), 19:01:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:34 INFO - 'download_minidump_stackwalk': True, 19:01:34 INFO - 'download_symbols': 'ondemand', 19:01:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:34 INFO - 'installer_path': 'installer.exe', 19:01:34 INFO - 'log_level': 'info', 19:01:34 INFO - 'log_name': 'talos', 19:01:34 INFO - 'log_to_console': True, 19:01:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:01:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:01:34 INFO - 'opt_config_files': (), 19:01:34 INFO - 'pip_index': False, 19:01:34 INFO - 'sps_profile': False, 19:01:34 INFO - 'sps_profile_interval': 0, 19:01:34 INFO - 'suite': 'dromaeojs', 19:01:34 INFO - 'system_bits': '32', 19:01:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:34 INFO - 'title': 'talos-linux64-ix-033', 19:01:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:34 INFO - 'use_talos_json': True, 19:01:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:34 INFO - 'work_dir': 'build'} 19:01:34 INFO - ##### 19:01:34 INFO - ##### Running clobber step. 19:01:34 INFO - ##### 19:01:34 INFO - Running pre-action listener: _resource_record_pre_action 19:01:34 INFO - Running main action method: clobber 19:01:34 INFO - rmtree: /builds/slave/test/build 19:01:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:34 INFO - Running post-action listener: _resource_record_post_action 19:01:34 INFO - ##### 19:01:34 INFO - ##### Finished clobber step (success) 19:01:34 INFO - ##### 19:01:34 INFO - ##### 19:01:34 INFO - ##### Running read-buildbot-config step. 19:01:34 INFO - ##### 19:01:34 INFO - Running pre-action listener: _resource_record_pre_action 19:01:34 INFO - Running main action method: read_buildbot_config 19:01:34 INFO - Using buildbot properties: 19:01:34 INFO - { 19:01:34 INFO - "project": "", 19:01:34 INFO - "product": "firefox", 19:01:34 INFO - "script_repo_revision": "production", 19:01:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:01:34 INFO - "repository": "", 19:01:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:01:34 INFO - "buildid": "20160601163010", 19:01:34 INFO - "pgo_build": "True", 19:01:34 INFO - "basedir": "/builds/slave/test", 19:01:34 INFO - "buildnumber": 465, 19:01:34 INFO - "slavename": "talos-linux64-ix-033", 19:01:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:01:34 INFO - "platform": "ubuntu64_hw", 19:01:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:01:34 INFO - "branch": "mozilla-central", 19:01:34 INFO - "repo_path": "mozilla-central", 19:01:34 INFO - "revision": "22047a4eea784c15026c77911c0bd6ea1b70fa68", 19:01:34 INFO - "stage_platform": "linux64-pgo", 19:01:34 INFO - "builduid": "38d364c38cee4182b8ea3c8422008336", 19:01:34 INFO - "slavebuilddir": "test" 19:01:34 INFO - } 19:01:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:01:34 INFO - Running post-action listener: _resource_record_post_action 19:01:34 INFO - ##### 19:01:34 INFO - ##### Finished read-buildbot-config step (success) 19:01:34 INFO - ##### 19:01:34 INFO - ##### 19:01:34 INFO - ##### Running download-and-extract step. 19:01:34 INFO - ##### 19:01:34 INFO - Running pre-action listener: _resource_record_pre_action 19:01:34 INFO - Running main action method: download_and_extract 19:01:34 INFO - mkdir: /builds/slave/test/build/tests 19:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:34 INFO - https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:01:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:35 INFO - Downloaded 1361 bytes. 19:01:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:35 INFO - Using the following test package requirements: 19:01:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:01:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:01:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:01:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'jsshell-linux-x86_64.zip'], 19:01:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:01:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:01:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:01:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:01:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:01:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:01:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:35 INFO - https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:01:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:36 INFO - Downloaded 24422567 bytes. 19:01:36 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'] 19:01:36 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 19:01:38 INFO - Return code: 0 19:01:38 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 19:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:38 INFO - https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:01:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:39 INFO - Downloaded 24422567 bytes. 19:01:39 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'] 19:01:39 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 19:01:41 INFO - Return code: 0 19:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:41 INFO - https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:01:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:01:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:01:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:42 INFO - Downloaded 10834055 bytes. 19:01:42 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'] 19:01:42 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 19:01:42 INFO - Return code: 0 19:01: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')]} 19:01:42 INFO - https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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 19:01:44 INFO - Downloaded 59024182 bytes. 19:01:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:01:44 INFO - mkdir: /builds/slave/test/properties 19:01:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:01:44 INFO - Writing to file /builds/slave/test/properties/build_url 19:01:44 INFO - Contents: 19:01:44 INFO - build_url:https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:01:44 INFO - Running post-action listener: _resource_record_post_action 19:01:44 INFO - Running post-action listener: set_extra_try_arguments 19:01:44 INFO - ##### 19:01:44 INFO - ##### Finished download-and-extract step (success) 19:01:44 INFO - ##### 19:01:44 INFO - ##### 19:01:44 INFO - ##### Running populate-webroot step. 19:01:44 INFO - ##### 19:01:44 INFO - Running pre-action listener: _resource_record_pre_action 19:01:44 INFO - Running main action method: populate_webroot 19:01:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:01:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:01:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:01:45 INFO - '--tppagecycles', 19:01:45 INFO - '7'], 19:01:45 INFO - 'tests': ['tsvgm']}}, 19:01:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:01:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:01:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:01:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:01:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['damp', 'tps']}, 19:01:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'tests': ['damp', 'tps']}, 19:01:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['dromaeo_dom']}, 19:01:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:01:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'other-e10s': {'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['a11yr', 19:01:45 INFO - 'ts_paint', 19:01:45 INFO - 'tpaint', 19:01:45 INFO - 'sessionrestore', 19:01:45 INFO - 'sessionrestore_no_auto_restore', 19:01:45 INFO - 'tabpaint']}, 19:01:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['tsvgx', 19:01:45 INFO - 'tsvgr_opacity', 19:01:45 INFO - 'tart', 19:01:45 INFO - 'tscrollx', 19:01:45 INFO - 'cart']}, 19:01:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:01:45 INFO - 'tsvgr_opacity', 19:01:45 INFO - 'tart', 19:01:45 INFO - 'tscrollx', 19:01:45 INFO - 'cart']}, 19:01:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'talos_options': ['--disable-e10s'], 19:01:45 INFO - 'tests': ['tp5o']}, 19:01:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'tests': ['tp5o']}, 19:01:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'talos_options': ['--disable-e10s', 19:01:45 INFO - '--xperf_path', 19:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:45 INFO - 'tests': ['tp5n']}, 19:01:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:45 INFO - 'talos_options': ['--xperf_path', 19:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:45 INFO - 'tests': ['tp5n']}}, 19:01:45 INFO - 'talos.zip': {'path': '', 19:01:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:01:45 INFO - Running post-action listener: _resource_record_post_action 19:01:45 INFO - ##### 19:01:45 INFO - ##### Finished populate-webroot step (success) 19:01:45 INFO - ##### 19:01:45 INFO - ##### 19:01:45 INFO - ##### Running create-virtualenv step. 19:01:45 INFO - ##### 19:01:45 INFO - Running pre-action listener: _resource_record_pre_action 19:01:45 INFO - Running main action method: create_virtualenv 19:01:45 INFO - Creating virtualenv /builds/slave/test/build/venv 19:01:45 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 19:01:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:01:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:01:45 INFO - Using real prefix '/usr' 19:01:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:01:46 INFO - Installing distribute.............................................................................................................................................................................................done. 19:01:48 INFO - Installing pip.................done. 19:01:48 INFO - Return code: 0 19:01:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:01:48 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')]} 19:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:48 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')]} 19:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:48 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:01:48 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 19:01:48 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 19:01:48 INFO - Using env: {'DISPLAY': ':0', 19:01:48 INFO - 'HOME': '/home/cltbld', 19:01:48 INFO - 'LANG': 'en_US.UTF-8', 19:01:48 INFO - 'LANGUAGE': 'en_US:en', 19:01:48 INFO - 'LOGNAME': 'cltbld', 19:01:48 INFO - 'MAIL': '/var/mail/cltbld', 19:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:48 INFO - 'MOZ_NO_REMOTE': '1', 19:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:48 INFO - 'NO_EM_RESTART': '1', 19:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:48 INFO - 'PWD': '/builds/slave/test', 19:01:48 INFO - 'SHELL': '/bin/bash', 19:01:48 INFO - 'SHLVL': '1', 19:01:48 INFO - 'TERM': 'linux', 19:01:48 INFO - 'TMOUT': '86400', 19:01:48 INFO - 'USER': 'cltbld', 19:01:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:48 INFO - '_': '/tools/buildbot/bin/python'} 19:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:48 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 19:01:48 INFO - Cleaning up... 19:01:48 INFO - Return code: 0 19:01:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:01:48 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')]} 19:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:48 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')]} 19:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:48 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:01:48 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 19:01:48 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 19:01:48 INFO - Using env: {'DISPLAY': ':0', 19:01:48 INFO - 'HOME': '/home/cltbld', 19:01:48 INFO - 'LANG': 'en_US.UTF-8', 19:01:48 INFO - 'LANGUAGE': 'en_US:en', 19:01:48 INFO - 'LOGNAME': 'cltbld', 19:01:48 INFO - 'MAIL': '/var/mail/cltbld', 19:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:48 INFO - 'MOZ_NO_REMOTE': '1', 19:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:48 INFO - 'NO_EM_RESTART': '1', 19:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:48 INFO - 'PWD': '/builds/slave/test', 19:01:48 INFO - 'SHELL': '/bin/bash', 19:01:48 INFO - 'SHLVL': '1', 19:01:48 INFO - 'TERM': 'linux', 19:01:48 INFO - 'TMOUT': '86400', 19:01:48 INFO - 'USER': 'cltbld', 19:01:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:48 INFO - '_': '/tools/buildbot/bin/python'} 19:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:49 INFO - Downloading/unpacking psutil>=3.1.1 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:52 INFO - Installing collected packages: psutil 19:01:52 INFO - Running setup.py install for psutil 19:01:52 INFO - building 'psutil._psutil_linux' extension 19:01:52 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 19:01:53 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 19:01:53 INFO - building 'psutil._psutil_posix' extension 19:01:53 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 19:01:53 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 19:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:54 INFO - Successfully installed psutil 19:01:54 INFO - Cleaning up... 19:01:54 INFO - Return code: 0 19:01:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:01:54 INFO - Using env: {'DISPLAY': ':0', 19:01:54 INFO - 'HOME': '/home/cltbld', 19:01:54 INFO - 'LANG': 'en_US.UTF-8', 19:01:54 INFO - 'LANGUAGE': 'en_US:en', 19:01:54 INFO - 'LOGNAME': 'cltbld', 19:01:54 INFO - 'MAIL': '/var/mail/cltbld', 19:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:54 INFO - 'MOZ_NO_REMOTE': '1', 19:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:54 INFO - 'NO_EM_RESTART': '1', 19:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:54 INFO - 'PWD': '/builds/slave/test', 19:01:54 INFO - 'SHELL': '/bin/bash', 19:01:54 INFO - 'SHLVL': '1', 19:01:54 INFO - 'TERM': 'linux', 19:01:54 INFO - 'TMOUT': '86400', 19:01:54 INFO - 'USER': 'cltbld', 19:01:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:54 INFO - '_': '/tools/buildbot/bin/python'} 19:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:01:56 INFO - Installing collected packages: mozsystemmonitor 19:01:56 INFO - Running setup.py install for mozsystemmonitor 19:01:56 INFO - Successfully installed mozsystemmonitor 19:01:56 INFO - Cleaning up... 19:01:56 INFO - Return code: 0 19:01:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:01:56 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')]} 19:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:56 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')]} 19:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:56 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:01:56 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 19:01:56 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 19:01:56 INFO - Using env: {'DISPLAY': ':0', 19:01:56 INFO - 'HOME': '/home/cltbld', 19:01:56 INFO - 'LANG': 'en_US.UTF-8', 19:01:56 INFO - 'LANGUAGE': 'en_US:en', 19:01:56 INFO - 'LOGNAME': 'cltbld', 19:01:56 INFO - 'MAIL': '/var/mail/cltbld', 19:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:56 INFO - 'MOZ_NO_REMOTE': '1', 19:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:56 INFO - 'NO_EM_RESTART': '1', 19:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:56 INFO - 'PWD': '/builds/slave/test', 19:01:56 INFO - 'SHELL': '/bin/bash', 19:01:56 INFO - 'SHLVL': '1', 19:01:56 INFO - 'TERM': 'linux', 19:01:56 INFO - 'TMOUT': '86400', 19:01:56 INFO - 'USER': 'cltbld', 19:01:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:56 INFO - '_': '/tools/buildbot/bin/python'} 19:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:56 INFO - Downloading/unpacking blobuploader==1.2.4 19:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:58 INFO - Downloading blobuploader-1.2.4.tar.gz 19:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:01:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:01:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:58 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 19:01:59 INFO - Downloading docopt-0.6.1.tar.gz 19:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:01:59 INFO - Installing collected packages: blobuploader, requests, docopt 19:01:59 INFO - Running setup.py install for blobuploader 19:01:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:01:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:01:59 INFO - Running setup.py install for requests 19:01:59 INFO - Running setup.py install for docopt 19:01:59 INFO - Successfully installed blobuploader requests docopt 19:01:59 INFO - Cleaning up... 19:01:59 INFO - Return code: 0 19:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:59 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:01:59 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 19:01:59 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 19:01:59 INFO - Using env: {'DISPLAY': ':0', 19:01:59 INFO - 'HOME': '/home/cltbld', 19:01:59 INFO - 'LANG': 'en_US.UTF-8', 19:01:59 INFO - 'LANGUAGE': 'en_US:en', 19:01:59 INFO - 'LOGNAME': 'cltbld', 19:01:59 INFO - 'MAIL': '/var/mail/cltbld', 19:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:59 INFO - 'MOZ_NO_REMOTE': '1', 19:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:59 INFO - 'NO_EM_RESTART': '1', 19:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:59 INFO - 'PWD': '/builds/slave/test', 19:01:59 INFO - 'SHELL': '/bin/bash', 19:01:59 INFO - 'SHLVL': '1', 19:01:59 INFO - 'TERM': 'linux', 19:01:59 INFO - 'TMOUT': '86400', 19:01:59 INFO - 'USER': 'cltbld', 19:01:59 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:59 INFO - '_': '/tools/buildbot/bin/python'} 19:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:00 INFO - Running setup.py (path:/tmp/pip-Wlme4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:00 INFO - Running setup.py (path:/tmp/pip-EnjWnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:00 INFO - Running setup.py (path:/tmp/pip-CSd8ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:00 INFO - Running setup.py (path:/tmp/pip-ZjAkIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:00 INFO - Running setup.py (path:/tmp/pip-3qENaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:00 INFO - Running setup.py (path:/tmp/pip-uvP42C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:00 INFO - Running setup.py (path:/tmp/pip-BVxKQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:00 INFO - Running setup.py (path:/tmp/pip-imz0OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:00 INFO - Running setup.py (path:/tmp/pip-pX2o1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:00 INFO - Running setup.py (path:/tmp/pip-xXfwyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:00 INFO - Running setup.py (path:/tmp/pip-LqrnCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:00 INFO - Running setup.py (path:/tmp/pip-mVh1mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:00 INFO - Running setup.py (path:/tmp/pip-E6q5j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:01 INFO - Running setup.py (path:/tmp/pip-AF8Ufa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:01 INFO - Running setup.py (path:/tmp/pip-upEiqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:01 INFO - Running setup.py (path:/tmp/pip-BJqy_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:01 INFO - Running setup.py (path:/tmp/pip-__PuHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:01 INFO - Running setup.py install for manifestparser 19:02:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:01 INFO - Running setup.py install for mozcrash 19:02:01 INFO - Running setup.py install for mozdebug 19:02:01 INFO - Running setup.py install for mozdevice 19:02:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:02:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:01 INFO - Running setup.py install for mozfile 19:02:01 INFO - Running setup.py install for mozhttpd 19:02:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:01 INFO - Running setup.py install for mozinfo 19:02:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for mozInstall 19:02:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for mozleak 19:02:02 INFO - Running setup.py install for mozlog 19:02:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for moznetwork 19:02:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for mozprocess 19:02:02 INFO - Running setup.py install for mozprofile 19:02:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for mozrunner 19:02:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:02 INFO - Running setup.py install for mozscreenshot 19:02:02 INFO - Running setup.py install for moztest 19:02:02 INFO - Running setup.py install for mozversion 19:02:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:03 INFO - Cleaning up... 19:02:03 INFO - Return code: 0 19:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:03 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:02:03 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 19:02:03 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 19:02:03 INFO - Using env: {'DISPLAY': ':0', 19:02:03 INFO - 'HOME': '/home/cltbld', 19:02:03 INFO - 'LANG': 'en_US.UTF-8', 19:02:03 INFO - 'LANGUAGE': 'en_US:en', 19:02:03 INFO - 'LOGNAME': 'cltbld', 19:02:03 INFO - 'MAIL': '/var/mail/cltbld', 19:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:03 INFO - 'MOZ_NO_REMOTE': '1', 19:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:03 INFO - 'NO_EM_RESTART': '1', 19:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:03 INFO - 'PWD': '/builds/slave/test', 19:02:03 INFO - 'SHELL': '/bin/bash', 19:02:03 INFO - 'SHLVL': '1', 19:02:03 INFO - 'TERM': 'linux', 19:02:03 INFO - 'TMOUT': '86400', 19:02:03 INFO - 'USER': 'cltbld', 19:02:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:03 INFO - '_': '/tools/buildbot/bin/python'} 19:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:03 INFO - Running setup.py (path:/tmp/pip-tT_PhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:03 INFO - Running setup.py (path:/tmp/pip-atCAjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:03 INFO - Running setup.py (path:/tmp/pip-VpVma9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:03 INFO - Running setup.py (path:/tmp/pip-xbyliB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:03 INFO - Running setup.py (path:/tmp/pip-Wa_Xn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:03 INFO - Running setup.py (path:/tmp/pip-5i7hY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:03 INFO - Running setup.py (path:/tmp/pip-1HKpXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:03 INFO - Running setup.py (path:/tmp/pip-W3ikGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:03 INFO - Running setup.py (path:/tmp/pip-HhOSDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:03 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)) 19:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:03 INFO - Running setup.py (path:/tmp/pip-iqQbJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:04 INFO - Running setup.py (path:/tmp/pip-Tdkm8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:04 INFO - Running setup.py (path:/tmp/pip-nxMows-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:04 INFO - Running setup.py (path:/tmp/pip-uFlfR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:04 INFO - Running setup.py (path:/tmp/pip-WhBj6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:04 INFO - Running setup.py (path:/tmp/pip-dva9ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:04 INFO - Running setup.py (path:/tmp/pip-OrfWM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:04 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)) 19:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:04 INFO - Running setup.py (path:/tmp/pip-JSnp0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:04 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)) 19:02:04 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)) 19:02:04 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)) 19:02:04 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)) 19:02:04 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)) 19:02:04 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)) 19:02:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:04 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 19:02:04 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 19:02:04 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 19:02:04 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 19:02:04 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 19:02:04 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 19:02:06 INFO - Downloading blessings-1.6.tar.gz 19:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:06 INFO - Installing collected packages: blessings 19:02:06 INFO - Running setup.py install for blessings 19:02:06 INFO - Successfully installed blessings 19:02:06 INFO - Cleaning up... 19:02:06 INFO - Return code: 0 19:02:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:06 INFO - Reading from file tmpfile_stdout 19:02:06 INFO - Current package versions: 19:02:06 INFO - argparse == 1.2.1 19:02:06 INFO - blessings == 1.6 19:02:06 INFO - blobuploader == 1.2.4 19:02:06 INFO - docopt == 0.6.1 19:02:06 INFO - manifestparser == 1.1 19:02:06 INFO - mozInstall == 1.12 19:02:06 INFO - mozcrash == 0.17 19:02:06 INFO - mozdebug == 0.1 19:02:06 INFO - mozdevice == 0.48 19:02:06 INFO - mozfile == 1.2 19:02:06 INFO - mozhttpd == 0.7 19:02:06 INFO - mozinfo == 0.9 19:02:06 INFO - mozleak == 0.1 19:02:06 INFO - mozlog == 3.2 19:02:06 INFO - moznetwork == 0.27 19:02:06 INFO - mozprocess == 0.22 19:02:06 INFO - mozprofile == 0.28 19:02:06 INFO - mozrunner == 6.11 19:02:06 INFO - mozscreenshot == 0.1 19:02:06 INFO - mozsystemmonitor == 0.3 19:02:06 INFO - moztest == 0.7 19:02:06 INFO - mozversion == 1.4 19:02:06 INFO - psutil == 3.1.1 19:02:06 INFO - requests == 1.2.3 19:02:06 INFO - wsgiref == 0.1.2 19:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:06 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:02:06 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 19:02:06 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 19:02:06 INFO - Using env: {'DISPLAY': ':0', 19:02:06 INFO - 'HOME': '/home/cltbld', 19:02:06 INFO - 'LANG': 'en_US.UTF-8', 19:02:06 INFO - 'LANGUAGE': 'en_US:en', 19:02:06 INFO - 'LOGNAME': 'cltbld', 19:02:06 INFO - 'MAIL': '/var/mail/cltbld', 19:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:06 INFO - 'MOZ_NO_REMOTE': '1', 19:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:06 INFO - 'NO_EM_RESTART': '1', 19:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:06 INFO - 'PWD': '/builds/slave/test', 19:02:06 INFO - 'SHELL': '/bin/bash', 19:02:06 INFO - 'SHLVL': '1', 19:02:06 INFO - 'TERM': 'linux', 19:02:06 INFO - 'TMOUT': '86400', 19:02:06 INFO - 'USER': 'cltbld', 19:02:06 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:06 INFO - '_': '/tools/buildbot/bin/python'} 19:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 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)) 19:02:07 INFO - Cleaning up... 19:02:07 INFO - Return code: 0 19:02:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02:07 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')]} 19:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:07 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')]} 19:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:07 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:02:07 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 19:02:07 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 19:02:07 INFO - Using env: {'DISPLAY': ':0', 19:02:07 INFO - 'HOME': '/home/cltbld', 19:02:07 INFO - 'LANG': 'en_US.UTF-8', 19:02:07 INFO - 'LANGUAGE': 'en_US:en', 19:02:07 INFO - 'LOGNAME': 'cltbld', 19:02:07 INFO - 'MAIL': '/var/mail/cltbld', 19:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:07 INFO - 'MOZ_NO_REMOTE': '1', 19:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:07 INFO - 'NO_EM_RESTART': '1', 19:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:07 INFO - 'PWD': '/builds/slave/test', 19:02:07 INFO - 'SHELL': '/bin/bash', 19:02:07 INFO - 'SHLVL': '1', 19:02:07 INFO - 'TERM': 'linux', 19:02:07 INFO - 'TMOUT': '86400', 19:02:07 INFO - 'USER': 'cltbld', 19:02:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:07 INFO - '_': '/tools/buildbot/bin/python'} 19:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:07 INFO - Downloading/unpacking jsonschema 19:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:09 INFO - Downloading/unpacking functools32 (from jsonschema) 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:09 INFO - warning: no files found matching '*.txt' 19:02:09 INFO - no previously-included directories found matching 'build' 19:02:09 INFO - no previously-included directories found matching 'dist' 19:02:09 INFO - no previously-included directories found matching '.git*' 19:02:09 INFO - Installing collected packages: jsonschema, functools32 19:02:09 INFO - Running setup.py install for functools32 19:02:09 INFO - warning: no files found matching '*.txt' 19:02:09 INFO - no previously-included directories found matching 'build' 19:02:09 INFO - no previously-included directories found matching 'dist' 19:02:09 INFO - no previously-included directories found matching '.git*' 19:02:09 INFO - Successfully installed jsonschema functools32 19:02:09 INFO - Cleaning up... 19:02:09 INFO - Return code: 0 19:02:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:02:09 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')]} 19:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:09 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')]} 19:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:09 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 0x7f7d638b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18808e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', '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 19:02:09 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 19:02:09 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 19:02:09 INFO - Using env: {'DISPLAY': ':0', 19:02:09 INFO - 'HOME': '/home/cltbld', 19:02:09 INFO - 'LANG': 'en_US.UTF-8', 19:02:09 INFO - 'LANGUAGE': 'en_US:en', 19:02:09 INFO - 'LOGNAME': 'cltbld', 19:02:09 INFO - 'MAIL': '/var/mail/cltbld', 19:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:09 INFO - 'MOZ_NO_REMOTE': '1', 19:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:09 INFO - 'NO_EM_RESTART': '1', 19:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:09 INFO - 'PWD': '/builds/slave/test', 19:02:09 INFO - 'SHELL': '/bin/bash', 19:02:09 INFO - 'SHLVL': '1', 19:02:09 INFO - 'TERM': 'linux', 19:02:09 INFO - 'TMOUT': '86400', 19:02:09 INFO - 'USER': 'cltbld', 19:02:09 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:09 INFO - '_': '/tools/buildbot/bin/python'} 19:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:09 INFO - Downloading/unpacking flake8 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:09 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 19:02:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:02:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:02:11 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 19:02:11 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 19:02:11 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 19:02:11 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 19:02:11 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 19:02:11 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 19:02:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:02:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:02:12 INFO - no previously-included directories found matching 'docs/_build' 19:02:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:02:12 INFO - Running setup.py install for pep8 19:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:02:12 INFO - no previously-included directories found matching 'docs/_build' 19:02:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:02:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:02:12 INFO - Cleaning up... 19:02:12 INFO - Return code: 0 19:02:12 INFO - Running post-action listener: _resource_record_post_action 19:02:12 INFO - Running post-action listener: _start_resource_monitoring 19:02:12 INFO - Starting resource monitoring. 19:02:12 INFO - ##### 19:02:12 INFO - ##### Finished create-virtualenv step (success) 19:02:12 INFO - ##### 19:02:12 INFO - ##### 19:02:12 INFO - ##### Running install step. 19:02:13 INFO - ##### 19:02:13 INFO - Running pre-action listener: _resource_record_pre_action 19:02:13 INFO - Running main action method: install 19:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:13 INFO - Reading from file tmpfile_stdout 19:02:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:13 INFO - Reading from file tmpfile_stdout 19:02:13 INFO - Output received: 19:02:13 INFO - Usage: mozinstall [options] installer 19:02:13 INFO - Options: 19:02:13 INFO - -h, --help show this help message and exit 19:02:13 INFO - -d DEST, --destination=DEST 19:02:13 INFO - Directory to install application into. [default: 19:02:13 INFO - "/builds/slave/test"] 19:02:13 INFO - --app=APP Application being installed. [default: firefox] 19:02:13 INFO - mkdir: /builds/slave/test/build/application 19:02:13 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'] 19:02:13 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 19:02:30 INFO - Reading from file tmpfile_stdout 19:02:30 INFO - Output received: 19:02:30 INFO - /builds/slave/test/build/application/firefox/firefox 19:02:30 INFO - Running post-action listener: _resource_record_post_action 19:02:30 INFO - ##### 19:02:30 INFO - ##### Finished install step (success) 19:02:30 INFO - ##### 19:02:30 INFO - ##### 19:02:30 INFO - ##### Running run-tests step. 19:02:30 INFO - ##### 19:02:30 INFO - Running pre-action listener: _resource_record_pre_action 19:02:30 INFO - Running main action method: run_tests 19:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:30 INFO - Python 2.7.3 19:02:30 INFO - Return code: 0 19:02:30 INFO - grabbing minidump binary from tooltool 19:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:30 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 0x190fb20>, '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 0x1910a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1910eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:30 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 19:02:30 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 19:02:30 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 19:02:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:30 INFO - Return code: 0 19:02:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:02:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:02:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:02:31 INFO - Return code: 0 19:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 19:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 19:02:31 INFO - Using env: {'DISPLAY': ':0', 19:02:31 INFO - 'HOME': '/home/cltbld', 19:02:31 INFO - 'LANG': 'en_US.UTF-8', 19:02:31 INFO - 'LANGUAGE': 'en_US:en', 19:02:31 INFO - 'LOGNAME': 'cltbld', 19:02:31 INFO - 'MAIL': '/var/mail/cltbld', 19:02:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:31 INFO - 'MOZ_NO_REMOTE': '1', 19:02:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:31 INFO - 'NO_EM_RESTART': '1', 19:02:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:31 INFO - 'PWD': '/builds/slave/test', 19:02:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:31 INFO - 'SHELL': '/bin/bash', 19:02:31 INFO - 'SHLVL': '1', 19:02:31 INFO - 'TERM': 'linux', 19:02:31 INFO - 'TMOUT': '86400', 19:02:31 INFO - 'USER': 'cltbld', 19:02:31 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766', 19:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:31 INFO - '_': '/tools/buildbot/bin/python'} 19:02:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 19:02:31 INFO - mozversion application_buildid: 20160601163010 19:02:31 INFO - mozversion application_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 19:02:31 INFO - mozversion application_display_name: Nightly 19:02:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:31 INFO - mozversion application_name: Firefox 19:02:31 INFO - mozversion application_remotingname: firefox 19:02:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:02:31 INFO - mozversion application_vendor: Mozilla 19:02:31 INFO - mozversion application_version: 49.0a1 19:02:31 INFO - mozversion platform_buildid: 20160601163010 19:02:31 INFO - mozversion platform_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 19:02:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:02:31 INFO - mozversion platform_version: 49.0a1 19:02:31 INFO - using testdate: 1464832951 19:02:31 INFO - actual date: 1464832951 19:02:31 INFO - starting webserver on 'localhost:60061' 19:02:31 INFO - SUITE-START | Running 2 tests 19:02:31 INFO - TEST-START | dromaeo_css 19:02:31 INFO - operating with platform_type : linux_ 19:02:31 INFO - Initialising browser for dromaeo_css test... 19:02:31 INFO - TEST-INFO | started process 3287 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpATQj8b/profile http://localhost:60061/getInfo.html) 19:02:39 INFO - PROCESS | 3287 | __metrics Screen width/height:1600/1200 19:02:39 INFO - PROCESS | 3287 | colorDepth:24 19:02:39 INFO - PROCESS | 3287 | Browser inner width/height: 1024/697 19:02:39 INFO - PROCESS | 3287 | __metrics 19:02:40 ERROR - PROCESS | 3287 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:02:41 INFO - TEST-INFO | 3287: exit 0 19:02:41 INFO - Browser initialized. 19:02:41 INFO - Running cycle 1/1 for dromaeo_css test... 19:02:41 INFO - TEST-INFO | started process 3370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpATQj8b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:53 INFO - PROCESS | 3370 | RSS: Main: 156610560 19:02:53 INFO - PROCESS | 3370 | 19:03:44 INFO - PROCESS | 3370 | 1464833024641 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] 19:05:53 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60061/tests/dromaeo/cssquery-ext.html) 19:05:54 INFO - PROCESS | 3370 | RSS: Main: 286703616 19:05:54 INFO - PROCESS | 3370 | 19:08:56 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-ext.html (next: http://localhost:60061/tests/dromaeo/cssquery-jquery.html) 19:08:57 INFO - PROCESS | 3370 | RSS: Main: 241405952 19:08:57 INFO - PROCESS | 3370 | 19:11:59 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60061/tests/dromaeo/cssquery-mootools.html) 19:11:59 INFO - PROCESS | 3370 | RSS: Main: 233398272 19:11:59 INFO - PROCESS | 3370 | 19:15:01 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60061/tests/dromaeo/cssquery-prototype.html) 19:15:02 INFO - PROCESS | 3370 | RSS: Main: 235143168 19:15:02 INFO - PROCESS | 3370 | 19:18:05 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60061/tests/dromaeo/cssquery-yui.html) 19:18:05 INFO - PROCESS | 3370 | RSS: Main: 228237312 19:18:05 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:60061/tests/dromaeo/cssquery-yui.html (next: http://localhost:60061/tests/dromaeo/cssquery-dojo.html) 19:21:08 INFO - PROCESS | 3370 | RSS: Main: 266854400 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | __start_tp_report 19:21:08 INFO - PROCESS | 3370 | _x_x_mozilla_page_load 19:21:08 INFO - PROCESS | 3370 | _x_x_mozilla_page_load_details 19:21:08 INFO - PROCESS | 3370 | |i|pagename|runs| 19:21:08 INFO - PROCESS | 3370 | |0;dojo.html;3079.8614062367196;3092.2733157707935;3092.768042396821;3133.1227256368225;3150.3856747934146;5212.113940630102;5289.259503669485;5302.443243459439;5358.6516876403;5383.057964856154;5336.332958380202;5357.544608708255;5358.839234822962;5382.461753824616;5407.486288802567;4812.759362031902;4855.732934688582;4948.331975183341;5002.4997500249865;5039.924401133986;4936.062148191832;4980.526849949249;4994.725290109042;5009.574186194178;5016.147254966664;4714.245720684674;4791.257355109946;4800.663953523254;4832.226843705025;4902.950970490285;4819.6867203631655;4828.879278018043;4845.055214233226;4883.926741098886;4901.656884018084;4761.47623761386;4798.520147985174;4816.205326121186;4825;4832.106060378841;5130.102232109403;5211.270422140866;5249.212618107276;5263;5298.735063246822;5619.831405057855;5693.544516438675;5698.572607054488;5699.74351154199;5703.686297253649;5756.30924289088;5780.364159942403;5800.564957628152;5804.912926306109;5809.535237181015;5235;5241.737913104368;5265.657732247391;5265.815696450606;5414.187871819219;5765.538756899438;5769.596128271065;5782.335031471351;5788.7684492620665;5803.042497987784;5540.833774986757;5564.27664403633;5593.916091258634;5594.384617692051;5605.495505404451;5528.889422211533;5621.297337832771;5668.234788303526;5684.175794509773;5709.086546152679;5667.574931880125;5667.773289068474;5670.744816483269;5676.631018983835;5688.402717714663;5476;5673.262475878109;5675.290588676416;5698.772049118072;5699.031164701928;4213.0310028693575;4289.699721019499;4314.784260786948;4315.266404711207;4332.176886391576;3831.0613899594778;3837.347650899354;3842.634949679776;3869.845206191777;3883.669888059547;5331.493508116722;5339.3058902342445;5346.839594812084;5353.929214157184;5366.329208848894;5593.888122237532;5738.483536481736;5765.769369225184;5777.24895763548;5778.971105144447;5419.349678038582;5426.782928682887;5428.050091234098;5429.321334833146;5434.212039254286;4789.784459699322;4960.603151747851;4968.577670897942;4985.326980857611;4994.325766021613;5837.678927658934;5860.3260625027815;5872.2366092408565;5895.08626162954;5920.141579470953;5693.9430605693415;5717.914231286451;5731.140328950691;5733.2260144881575;5733.455321744427;5752.568557358131;5851.093080572518;5896.734646940984;5910.940890591039;5918.467337939606;5608.242887210175;5665.093585026376;5672.829815105553;5677.630953987978;5723.828285151452;5725.570582206435;5745.1382292656435;5777.768889244551;5795.8551036224435;5801.332846722681;5388.515033646834;5582.385937546947;5608.6073974821375;5616.85957851057;5628.409017053151;5630.127330263816;5637.52081073097;5640.266765320482;5648.519875810603;5680.715964201856;5534.031544479781;5544.22380866687;5549.1676248563035;5549.639273447213;5550.4449555044175;5479.698616576126;5586.52514536269;5635.88728225425;5652.463016013472;5668.093105103164;5574.303212098488;5575;5576.665400076008;5582.581306401955;5606.383297837153 19:21:08 INFO - PROCESS | 3370 | |1;ext.html;581.7091454272863;609.5641616244387;615.2063837649432;642.075411407573;644.3041515163624;31373.372532549365;42927.78536107319;44001.11997760043;44011.3398299025;44076.11847763051;44761.104777904424;45490.772546137305;45521;45537.316940245924;45576.3163552547;8126.471779334354;8716.782080448007;8895.777605559848;10480.685579432591;10723.195760317969;10394.220433467483;10514.106300964428;10517.579296828118;10522.947385263102;10523.73690657732;10485.423301718403;10548.47257637115;10551.577936882515;10555.261131720783;10557.630482933098;10464.267501274913;10489.58041678332;10549.208809339254;10551.577936882515;10559.049685528262;10395.84406233907;10480.63317783874;10488.423136727477;10498.79002419955;10505.842412363821;41344;41819.954501137414;41826.1634767306;41883.581164188276;41943.58056419427;3583.605803361628;3604.963950360489;3611.4943907852658;3708.629137086297;3709.1283548366114;32343.6765632343;33155;33247.20326198981;34389.312213755584;34406.6559334406;4078.449409329732;4085.4893138357706;4093.89765255868;4114.670826333886;4137.40007698882;334850;334889;335661;336160.3191984049;336446;60237.69881150566;60598;60643;61131.69434152801;61228.693856531325;63076.684616576626;63516;63680.36319636837;63782.36217637857;63794.68102659457;13164.736705266032;13219.405126769318;13238.073334866467;13272.668183295495;13335;12470.937645311715;12503.687407814878;12536.185147965352;12541.059420543495;12543.623691289276;7600.3539699126195;7768.7669369918995;7775.533467991939;7874.56689882062;7884.802879928047;2980.5231163014246;2987.5070613348553;2988.6861879502776;3010.9397812043635;3014.7437467815043;1012.832882574367;1034.5861655337926;1048.0253364371056;1117.2291119127776;1180.8523934508187;32410;32534.34931301361;32615.673843261262;32710;32862.671373285964;938.2775263047415;1125.611663975927;1127.413744852672;1219.4451524556305;1256.0579565326007;16370.836291636931;16957.660846782994;17245.56886077858;17718.734218986727;18185.63628727418;524447;529465;532039;532489;532751;57695;57723.422765772644;57725.13412298819;57921;57973;1355.7762969110183;1359.9660008499668;1366.6719987203;1400.6078298076554;1402.7334806386755;31174.37651246917;32703.836480817445;32755.344893101526;33308.167295817795;33565.83217083997;48567.27149092837;49607;49655.75172124116;49778.751106244235;49810;400140;402442;404752;610630;624798;26085.608715868897;26314.473710526065;26711.732882671702;26761.598576020984;26924.596131057657;40024.59975400209;44611.77694111509;44877;44904.550954491344;45463;37861.81069094637;38339.61660383472;38492.42261366026;38670.41994370143;39014.41478377883;36738.448923265634;38366.80816595899;38565.4215186778;38576;38839.6116038836;38727;38769.224615508094;40941;41117.383239250834;41926.371104432845;34315;36463;36585;37025.30542315995;37501;28245;28605.570916436685;28910;28911.56632650554;28981 19:21:08 INFO - PROCESS | 3370 | |2;jquery.html;385.86109000759706;399.1398536154586;399.2155414610291;409.18163672654686;420.65296130692184;14246.430142794276;17175.227114779835;17845.7323140153;19141.904290478546;19142;14872.851271487281;17379.217935192915;18101.4569562913;18211.908940455312;18977.33579324724;5442.6462279951775;5645.15322701596;5665.036943719578;5700.3159620845345;5706.543476521868;4118.485189351331;5578.86052848678;5587.888242235153;5936.643801371932;6243.250809902818;6207.410296021893;6261.405166509197;6267.623942563438;6278.843028924268;6291.559590828644;6089.238845144357;6200.906986395185;6229.937700622981;6263.561550691431;6300.684965751717;5452.182172674091;5488.4785945335125;5527.088030474967;5555.388907220203;5556.638818476827;21784;22447.77552224473;22530.21144259943;22556.774432255632;22695.659565106536;2371.750966148547;2374.3233178544033;2473.332200305925;2725.4004119093765;2732.27594687408;13098.672533186747;13820.308984550733;13822.308884555832;13878.375473103735;14617.561473155822;1927.9421617351502;1957.1486403414417;2053.2608261026166;2082.1567265257445;2161.9567608647894;80793.19206807915;81361;81606.18393816045;83656.16343836545;83926.16073839244;10966.122710183166;11160.944195278971;11170.720731981603;11261.099112071015;11300.265482743594;15507.84492155064;15568.377264909503;15625.14061726616;15660;15683.843161568466;7986.440949133505;8195.754127376189;8197.71308004217;8292.709755158541;8343.249107580346;7083.539569927938;7841.411894107965;8074.394420418492;8220.465669731448;8244.68085106392;4444.466664000276;4695.295705644113;4813.109574728686;4873.683210591371;4897.338859253955;1954.7361106250758;2017.1023894366865;2019.4143698327614;2026.9189232430833;2037;957.9808403831885;973.2116985241977;1015.4719545836124;1063.043261065032;1088.646189988257;11813.88186118145;11969.820452693217;11990.520379184734;12513.874861251454;18356.173972171284;491.76149567459754;493.2896628854438;497.0928056297272;500.8046861723858;513.2378418049194;6255.967765318763;6495.382938620918;6517;6548.541602087904;6610.570312929644;346669;348853;353694;486399;506001;90118;97702;97845;98236;99024;486.093435742338;498.2028753993581;500.5095006893265;501.3031885678909;501.70900877490965;10970.451477426257;11019.614313498989;11264.155188360743;11336.943315283701;11434.085273178292;38161.6183838158;39149.21701565896;41760.791196045044;42240;43538.34692479552;53291;54241;57554.7122264386;58238.12642810276;58964.41035589589;8650.826983460422;8721.6947406843;8772.538485175755;8800.207981281716;8874.112588741074;9490.952545237229;12112.75774484523;12940.094193406747;13203.339833008504;13347.332633368487;11922.940385298018;11973.102017348909;12017.939910300393;12034.097442691658;12140;11602.18784685064;11641.185117041725;11846.940765296118;11904.940475297568;11927.224730392496;11626.128040397174;11903.82144267854;12057.457414416196;12298.262104273772;12353;8558.400911936105;8714.69498567547;8862.689805856764;8926.68756593516;8983.910160898307;8321.66473158895;8434.24091831738;8512.957435212784;8973.01296857332;9095.818083638422 19:21:08 INFO - PROCESS | 3370 | |3;mootools.html;1322.6494978830606;1486.5168820133458;1511.939522419103;1654.4788391656627;1802.5043113143884;7851.411144164189;8121.634526446309;8142.755717328475;8332.66669333226;8352.958235208815;8123.390745694067;8303.709370172046;8319.584020798966;8327.750167494985;8328.250457458827;2000.259903835583;2264.1396269417683;2285.53146604946;2308.319045881469;2339.4736184358553;2322.581935251654;2325.5116425550655;2329.04509151248;2330.196082351586;2330.906763729449;2290.2327720213666;2345.155743932183;2346.1671106757112;2346.21401830387;2348.377679914824;2007.9498012549657;2250.1224522436346;2325.802306803924;2325.93022209334;2328.5924963131383;2240.641497360431;2245.236619549361;2249.3814201094665;2250.088714070788;2302.3898666853297;8403.621837017288;8427.073021967579;8437.746867593982;8459;8461.280791132762;4954.455009948902;5049.974750126225;5051.444341122474;5062.215356619729;5066.062778385974;3158.3418705179733;3198.392305461955;3215.742740580748;3275.606927168731;3287.736981041535;1066.2003497376968;1106.4633652678444;1118.72031992002;1127.4813585750564;1141.2182654881433;11384.715382115348;11412.885871141349;11418.200725949187;11453.942730286295;11454.083673306115;4353.151135528541;4444;4499;4508.391367165391;4515.390422292949;9699;9717.757056073655;9725.270604704676;9732.854007189897;9743;4517.593416592523;4961.90076198474;4977.601791856643;4980.377452818398;4983.02830947969;4504.504504504502;4646.210144275481;4662.836800711959;4680.438347398266;4692.741899195509;3877.6316249956208;3969.6427321540623;4009.158076803904;4038.6163314485075;4090.3046482098116;1275.6045625856013;1287.291989405823;1294.9028822838325;1301.6680746409604;1303.7653222420056;986.7483791633088;1007.0936157458347;1021.4637315409469;1024.5901639344172;1039.859618951432;5959.106134079922;5984.760609575568;6054.182685337423;6060.545459090586;6078.331383547807;439.0823179554682;442.87820849266063;453.25665907911053;455.8928651766832;456.42490462018094;4172.624463798273;4207.137536804948;4207.852725154666;4209.2633789086785;4217.957820421757;9941.751456213507;10304.381737095648;10315.226358023177;10334.483275836179;10385.740356491147;10138.746531336776;10215.744606384751;10264.794704106025;10271.075603195599;10273.74315642115;7594.734184303523;7876.606169691492;7949.085855126619;7968.442209045311;7989.480683755537;5888.793892213752;5891.3224979127945;5931.703414829328;6016.969915150396;6079.696015199311;9169.724908252763;9500.714978550655;9599.376040557341;9661.71014869555;9682;9574.47340396285;9765.9023409765;9780.853287200556;9889.25830562725;9898.20814334837;6736.831579210363;7096.219415864354;7260.818479538054;7523.172451030492;7653.349465295512;8120.228578285053;8121.147279535564;8157.75526734199;8188.303994160563;8191.221833925768;7069.893951590833;7219.9892015116875;7240.601766902871;7318;7401.296876796696;7078.2921707830865;7125.786226413215;7151.749688760869;7172.49792514519;7196;6698.83252918681;6787.185537735503;6795.966020169867;6805.761798337035;6807.251202367835;4875.756212189447;4989.501049894981;5013.097642424253;5052.5705315048635;5102.10713125209;5245.265662807134;5305.442928492607;5333.466653334635;5354.625176237626;5440.945590544202 19:21:08 INFO - PROCESS | 3370 | |4;prototype.html;320.356084270616;326.4140867143477;333.6563339760047;338.588614832978;341.74710714071597;5248.265242865992;5299.8410047698535;5422.40353561108;5452.909418116377;5513.476219759122;5328.227407025979;5361.758720857571;5414.620976531644;5421.268128802611;5427.565794736422;4200.768957707332;4212.789360531977;4220.134872351161;4224.66202703783;4238.3430568261965;3984.565556399694;3993.6605388542007;4203.591796748078;4214;4242.193983143193;4088.2641124597562;4116.403121547374;4153.833846646131;4163.417121602978;4177.624013838754;4115.423840662294;4125.133721918401;4141.627253547195;4147.7926103695;4190.329547272452;3474.9131271718156;3706.629337066299;3709.1839795245273;3722.5160729105314;3723.5345581802276;5121.155009423441;5136.768845401966;5149.665271757323;5162.6128040397125;5163.896722065575;4718.8584342469785;4738.228136540129;4751.168545504523;4761.976190119062;4763;5375.919361209585;5495.258140151069;5521.806736764234;5559.277293951801;5563.859408821182;3961.1879564689457;3970.9602903971168;3977.250009995593;4000.6399424051974;4009.799510024487;5796.333421656479;5807.88384232313;5816.360200377955;5865.882682346371;5912;4025.4364388985564;4094.7952602369764;4121.196366708463;4122.711410201257;4145.647619952338;4124.381342798604;4143.730657507313;4146.315857883415;4161.001359673656;4175.060611362419;4769.499202583748;4772.904541909143;4780.784864681098;4783.210770222944;4790.856274311776;5457.508824205841;5598.488408129782;5675.659460432387;5718.285214348207;5718.771249150071;1371.163590209971;1389.4720006397706;1397.0779285671408;1397.3991183791068;1403.7613605687059;881.7354793562034;886.2068448738343;887.5961437545901;900.2482926756232;903.503073309677;4822;4828.551434569615;4841.612670986313;4848.781804818792;4874.926876096862;4974.751262436864;5034.84895453137;5052.797888084436;5114.337840201949;5120.359955005624;4719.126961512131;4738.19450693383;4806.855794326176;4831.323614693945;4872.879237775261;2737.835729856215;2743.5856446327102;2747.821391609539;2751.5786108224634;2762.8894844206006;5093.0137246473905;5204.230561608995;5216.426193118754;5265.3936301530985;5301.946980530222;5193.28621554886;5205.687658740488;5222.686638801608;5243.872567398028;5244.895102097937;5201.29782479368;5249.690136339998;5253.4221235664045;5263.473652634706;5275.868103297448;4925.285833554043;5096.796128154832;5118.334616499831;5134.922976155286;5153.201253805516;5054.772535235849;5059.46875578059;5091.1854103343285;5139.511746384088;5145.974270128625;4990.47600002009;5308.177232528964;5330.733463326896;5346.224797404432;5374.838754837361;3729.776213427203;3751.9624803751612;3761.6426439488205;3771.5851256362325;3771.6039815818945;5098.643094983316;5135.6405051646025;5140.742962851918;5201.635885487979;5208.140656791587;4971.254311853251;4980.260856528967;4989.577970278453;5009.899802004013;5027.7234752087525;5177.7670004849115;5403.000444917703;5645.887082258414;5684.516816070681;5699.458551437607;5457.645253058538;5561.860953476195;5574.801417695215;5584.664920104807;5594.244776955059;5692.971535142297;5716.371199168171;5745.971270143623;5758.827235182951;5760.8271751847515;5521.779128834802;5622.341409284353;5630.943690563206;5641.746121424464;5647.435256474319 19:21:08 INFO - PROCESS | 3370 | |5;yui.html;769.8652735771238;776.3975155279503;784.0356361675141;839.2362949715753;863.6329559937025;15522;15718.292676829542;15754.054756714591;15778.447754328601;15786.052836829784;14535.418583256656;15260.38958441661;15273.236338183102;15703.21483925802;15750.606234844134;676.3777324861115;827.5159031966307;829.6391069884589;834.7620928035511;834.8038211020405;798.3573223555036;819.5902048975512;826.2027143806225;826.396730386818;827.668932427031;853.1255463150259;854.7948492361819;856.4390324337538;857.3184318466803;857.4298091768966;698.5389642835755;827.8344331133737;852.1052894460822;856.092541905582;861.1689719420758;614.8339948214002;617.493655202734;617.7158507086746;618.0698049435615;618.1284389011518;14573.70852582953;14607.634809129751;14619.561413157622;14631.487897923522;14639.707205855822;12914;13488.583698711593;13494.920406367468;13731.176129432166;13806.17162970215;11614.477348519253;12139.585822381503;12445.62663120108;12452.81320780189;12646.367681615975;3756.7370284080125;3812.618738126193;3835.5013848199555;3865;3996.6003399660094;62494.37505624931;62971;63955.3604463954;63969.36030639727;64044.67977660128;10300.639477618248;10306.433146176882;10530.57877684884;10616.575336986589;10837.18721095921;19969.301074462324;20014.89992550028;20065.899670501847;20097.095630696655;20099.899500502695;8271.834563308821;8321.500709957421;8419.452735572168;8452.704155354533;8481.830363392697;4533.093381323749;4583.266677331678;4667.346571479996;4668.906621867543;4700.764961751899;3243.61076670801;3367.0572239772414;3368.3768502827056;3370.342783157261;3371.325734853039;605.7698074731574;617.9073139029182;620.1225266248208;623.9563230573907;625.4933503861887;648.6043513456788;756.6557216466495;787.7597332813432;788.755485799404;792.445288298182;9439.386439881386;10124.34191777532;10261.640842570623;10306.227032972558;10349.63776267827;629.8110566829932;652.8414002802181;762.6110683551428;786.9803254918672;788.798856291642;4250.787460626956;4416.933745993812;4430.13612345596;4466.508684044752;4496.235639941217;67871;70453.2954670457;71002;71079.64460177666;71346.9297960531;60488;61471.38528614746;61479;62455;63632;55124;55221.72389138029;55354.44645553573;56208.71895640578;56830.43169568334;9337.439753614804;9611.471369074488;9621.08599683029;9630;9631.373960692812;36340.454893176095;36449.45325820168;37276.813615931744;37316.25367492689;37407.625923740416;46594.534054659016;49019.26471102865;50689.239661405845;51367.74316128396;51481.48518514917;11117.11063114965;11728.41357932117;11734.295942243492;11913;12202.32887191213;13470.393832277376;13476.393562289917;13504.6623834401;13729.313534323044;14287.714245715235;11896.22674526153;12098.334591597546;13450.192988420726;13585.388657510633;14008.949328800178;13061.34693265352;13671.111377760413;13685.178889266675;13839.377228024965;13868.791968120688;12853.42159602839;13178.934105329412;13232.404541795835;13238.139520931109;13415.195088294733;11112.444377781241;11276.830847537458;11304.830427543759;11370.260933039326;11552;11165;11370;11427.714307142389;11495.885041149815;11672.591459298883 19:21:08 INFO - PROCESS | 3370 | __end_tp_report 19:21:08 INFO - PROCESS | 3370 | __start_cc_report 19:21:08 INFO - PROCESS | 3370 | _x_x_mozilla_cycle_collect,1075 19:21:08 INFO - PROCESS | 3370 | __end_cc_report 19:21:08 INFO - PROCESS | 3370 | __startTimestamp1464834068946__endTimestamp 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | ------- Summary: start ------- 19:21:08 INFO - PROCESS | 3370 | Number of tests: 6 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#0] dojo.html Cycles:165 Average:5300.88 Median:5549.17 stddev:602.93 (10.9%) stddev-sans-first:579.05 19:21:08 INFO - PROCESS | 3370 | Values: 3079.9 3092.3 3092.8 3133.1 3150.4 5212.1 5289.3 5302.4 5358.7 5383.1 5336.3 5357.5 5358.8 5382.5 5407.5 4812.8 4855.7 4948.3 5002.5 5039.9 4936.1 4980.5 4994.7 5009.6 5016.1 4714.2 4791.3 4800.7 4832.2 4903.0 4819.7 4828.9 4845.1 4883.9 4901.7 4761.5 4798.5 4816.2 4825.0 4832.1 5130.1 5211.3 5249.2 5263.0 5298.7 5619.8 5693.5 5698.6 5699.7 5703.7 5756.3 5780.4 5800.6 5804.9 5809.5 5235.0 5241.7 5265.7 5265.8 5414.2 5765.5 5769.6 5782.3 5788.8 5803.0 5540.8 5564.3 5593.9 5594.4 5605.5 5528.9 5621.3 5668.2 5684.2 5709.1 5667.6 5667.8 5670.7 5676.6 5688.4 5476.0 5673.3 5675.3 5698.8 5699.0 4213.0 4289.7 4314.8 4315.3 4332.2 3831.1 3837.3 3842.6 3869.8 3883.7 5331.5 5339.3 5346.8 5353.9 5366.3 5593.9 5738.5 5765.8 5777.2 5779.0 5419.3 5426.8 5428.1 5429.3 5434.2 4789.8 4960.6 4968.6 4985.3 4994.3 5837.7 5860.3 5872.2 5895.1 5920.1 5693.9 5717.9 5731.1 5733.2 5733.5 5752.6 5851.1 5896.7 5910.9 5918.5 5608.2 5665.1 5672.8 5677.6 5723.8 5725.6 5745.1 5777.8 5795.9 5801.3 5388.5 5582.4 5608.6 5616.9 5628.4 5630.1 5637.5 5640.3 5648.5 5680.7 5534.0 5544.2 5549.2 5549.6 5550.4 5479.7 5586.5 5635.9 5652.5 5668.1 5574.3 5575.0 5576.7 5582.6 5606.4 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#1] ext.html Cycles:180 Average:60642.23 Median:20037.25 stddev:123289.57 (615.3%) stddev-sans-first:123552.96 19:21:08 INFO - PROCESS | 3370 | Values: 581.7 609.6 615.2 642.1 644.3 31373.4 42927.8 44001.1 44011.3 44076.1 44761.1 45490.8 45521.0 45537.3 45576.3 8126.5 8716.8 8895.8 10480.7 10723.2 10394.2 10514.1 10517.6 10522.9 10523.7 10485.4 10548.5 10551.6 10555.3 10557.6 10464.3 10489.6 10549.2 10551.6 10559.0 10395.8 10480.6 10488.4 10498.8 10505.8 41344.0 41820.0 41826.2 41883.6 41943.6 3583.6 3605.0 3611.5 3708.6 3709.1 32343.7 33155.0 33247.2 34389.3 34406.7 4078.4 4085.5 4093.9 4114.7 4137.4 334850.0 334889.0 335661.0 336160.3 336446.0 60237.7 60598.0 60643.0 61131.7 61228.7 63076.7 63516.0 63680.4 63782.4 63794.7 13164.7 13219.4 13238.1 13272.7 13335.0 12470.9 12503.7 12536.2 12541.1 12543.6 7600.4 7768.8 7775.5 7874.6 7884.8 2980.5 2987.5 2988.7 3010.9 3014.7 1012.8 1034.6 1048.0 1117.2 1180.9 32410.0 32534.3 32615.7 32710.0 32862.7 938.3 1125.6 1127.4 1219.4 1256.1 16370.8 16957.7 17245.6 17718.7 18185.6 524447.0 529465.0 532039.0 532489.0 532751.0 57695.0 57723.4 57725.1 57921.0 57973.0 1355.8 1360.0 1366.7 1400.6 1402.7 31174.4 32703.8 32755.3 33308.2 33565.8 48567.3 49607.0 49655.8 49778.8 49810.0 400140.0 402442.0 404752.0 610630.0 624798.0 26085.6 26314.5 26711.7 26761.6 26924.6 40024.6 44611.8 44877.0 44904.6 45463.0 37861.8 38339.6 38492.4 38670.4 39014.4 36738.4 38366.8 38565.4 38576.0 38839.6 38727.0 38769.2 40941.0 41117.4 41926.4 34315.0 36463.0 36585.0 37025.3 37501.0 28245.0 28605.6 28910.0 28911.6 28981.0 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#2] jquery.html Cycles:180 Average:26304.05 Median:23991.41 stddev:69148.22 (288.2%) stddev-sans-first:69314.81 19:21:08 INFO - PROCESS | 3370 | Values: 385.9 399.1 399.2 409.2 420.7 14246.4 17175.2 17845.7 19141.9 19142.0 14872.9 17379.2 18101.5 18211.9 18977.3 5442.6 5645.2 5665.0 5700.3 5706.5 4118.5 5578.9 5587.9 5936.6 6243.3 6207.4 6261.4 6267.6 6278.8 6291.6 6089.2 6200.9 6229.9 6263.6 6300.7 5452.2 5488.5 5527.1 5555.4 5556.6 21784.0 22447.8 22530.2 22556.8 22695.7 2371.8 2374.3 2473.3 2725.4 2732.3 13098.7 13820.3 13822.3 13878.4 14617.6 1927.9 1957.1 2053.3 2082.2 2162.0 80793.2 81361.0 81606.2 83656.2 83926.2 10966.1 11160.9 11170.7 11261.1 11300.3 15507.8 15568.4 15625.1 15660.0 15683.8 7986.4 8195.8 8197.7 8292.7 8343.2 7083.5 7841.4 8074.4 8220.5 8244.7 4444.5 4695.3 4813.1 4873.7 4897.3 1954.7 2017.1 2019.4 2026.9 2037.0 958.0 973.2 1015.5 1063.0 1088.6 11813.9 11969.8 11990.5 12513.9 18356.2 491.8 493.3 497.1 500.8 513.2 6256.0 6495.4 6517.0 6548.5 6610.6 346669.0 348853.0 353694.0 486399.0 506001.0 90118.0 97702.0 97845.0 98236.0 99024.0 486.1 498.2 500.5 501.3 501.7 10970.5 11019.6 11264.2 11336.9 11434.1 38161.6 39149.2 41760.8 42240.0 43538.3 53291.0 54241.0 57554.7 58238.1 58964.4 8650.8 8721.7 8772.5 8800.2 8874.1 9491.0 12112.8 12940.1 13203.3 13347.3 11922.9 11973.1 12017.9 12034.1 12140.0 11602.2 11641.2 11846.9 11904.9 11927.2 11626.1 11903.8 12057.5 12298.3 12353.0 8558.4 8714.7 8862.7 8926.7 8983.9 8321.7 8434.2 8513.0 8973.0 9095.8 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#3] mootools.html Cycles:180 Average:5552.62 Median:4958.18 stddev:3088.96 (62.3%) stddev-sans-first:3081.27 19:21:08 INFO - PROCESS | 3370 | Values: 1322.6 1486.5 1511.9 1654.5 1802.5 7851.4 8121.6 8142.8 8332.7 8353.0 8123.4 8303.7 8319.6 8327.8 8328.3 2000.3 2264.1 2285.5 2308.3 2339.5 2322.6 2325.5 2329.0 2330.2 2330.9 2290.2 2345.2 2346.2 2346.2 2348.4 2007.9 2250.1 2325.8 2325.9 2328.6 2240.6 2245.2 2249.4 2250.1 2302.4 8403.6 8427.1 8437.7 8459.0 8461.3 4954.5 5050.0 5051.4 5062.2 5066.1 3158.3 3198.4 3215.7 3275.6 3287.7 1066.2 1106.5 1118.7 1127.5 1141.2 11384.7 11412.9 11418.2 11453.9 11454.1 4353.2 4444.0 4499.0 4508.4 4515.4 9699.0 9717.8 9725.3 9732.9 9743.0 4517.6 4961.9 4977.6 4980.4 4983.0 4504.5 4646.2 4662.8 4680.4 4692.7 3877.6 3969.6 4009.2 4038.6 4090.3 1275.6 1287.3 1294.9 1301.7 1303.8 986.7 1007.1 1021.5 1024.6 1039.9 5959.1 5984.8 6054.2 6060.5 6078.3 439.1 442.9 453.3 455.9 456.4 4172.6 4207.1 4207.9 4209.3 4218.0 9941.8 10304.4 10315.2 10334.5 10385.7 10138.7 10215.7 10264.8 10271.1 10273.7 7594.7 7876.6 7949.1 7968.4 7989.5 5888.8 5891.3 5931.7 6017.0 6079.7 9169.7 9500.7 9599.4 9661.7 9682.0 9574.5 9765.9 9780.9 9889.3 9898.2 6736.8 7096.2 7260.8 7523.2 7653.3 8120.2 8121.1 8157.8 8188.3 8191.2 7069.9 7220.0 7240.6 7318.0 7401.3 7078.3 7125.8 7151.7 7172.5 7196.0 6698.8 6787.2 6796.0 6805.8 6807.3 4875.8 4989.5 5013.1 5052.6 5102.1 5245.3 5305.4 5333.5 5354.6 5440.9 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#4] prototype.html Cycles:180 Average:4513.84 Median:5057.12 stddev:1302.66 (25.8%) stddev-sans-first:1267.72 19:21:08 INFO - PROCESS | 3370 | Values: 320.4 326.4 333.7 338.6 341.7 5248.3 5299.8 5422.4 5452.9 5513.5 5328.2 5361.8 5414.6 5421.3 5427.6 4200.8 4212.8 4220.1 4224.7 4238.3 3984.6 3993.7 4203.6 4214.0 4242.2 4088.3 4116.4 4153.8 4163.4 4177.6 4115.4 4125.1 4141.6 4147.8 4190.3 3474.9 3706.6 3709.2 3722.5 3723.5 5121.2 5136.8 5149.7 5162.6 5163.9 4718.9 4738.2 4751.2 4762.0 4763.0 5375.9 5495.3 5521.8 5559.3 5563.9 3961.2 3971.0 3977.3 4000.6 4009.8 5796.3 5807.9 5816.4 5865.9 5912.0 4025.4 4094.8 4121.2 4122.7 4145.6 4124.4 4143.7 4146.3 4161.0 4175.1 4769.5 4772.9 4780.8 4783.2 4790.9 5457.5 5598.5 5675.7 5718.3 5718.8 1371.2 1389.5 1397.1 1397.4 1403.8 881.7 886.2 887.6 900.2 903.5 4822.0 4828.6 4841.6 4848.8 4874.9 4974.8 5034.8 5052.8 5114.3 5120.4 4719.1 4738.2 4806.9 4831.3 4872.9 2737.8 2743.6 2747.8 2751.6 2762.9 5093.0 5204.2 5216.4 5265.4 5301.9 5193.3 5205.7 5222.7 5243.9 5244.9 5201.3 5249.7 5253.4 5263.5 5275.9 4925.3 5096.8 5118.3 5134.9 5153.2 5054.8 5059.5 5091.2 5139.5 5146.0 4990.5 5308.2 5330.7 5346.2 5374.8 3729.8 3752.0 3761.6 3771.6 3771.6 5098.6 5135.6 5140.7 5201.6 5208.1 4971.3 4980.3 4989.6 5009.9 5027.7 5177.8 5403.0 5645.9 5684.5 5699.5 5457.6 5561.9 5574.8 5584.7 5594.2 5693.0 5716.4 5746.0 5758.8 5760.8 5521.8 5622.3 5630.9 5641.7 5647.4 19:21:08 INFO - PROCESS | 3370 | 19:21:08 INFO - PROCESS | 3370 | [#5] yui.html Cycles:180 Average:16092.56 Median:4423.53 stddev:19377.96 (438.1%) stddev-sans-first:19398.16 19:21:08 INFO - PROCESS | 3370 | Values: 769.9 776.4 784.0 839.2 863.6 15522.0 15718.3 15754.1 15778.4 15786.1 14535.4 15260.4 15273.2 15703.2 15750.6 676.4 827.5 829.6 834.8 834.8 798.4 819.6 826.2 826.4 827.7 853.1 854.8 856.4 857.3 857.4 698.5 827.8 852.1 856.1 861.2 614.8 617.5 617.7 618.1 618.1 14573.7 14607.6 14619.6 14631.5 14639.7 12914.0 13488.6 13494.9 13731.2 13806.2 11614.5 12139.6 12445.6 12452.8 12646.4 3756.7 3812.6 3835.5 3865.0 3996.6 62494.4 62971.0 63955.4 63969.4 64044.7 10300.6 10306.4 10530.6 10616.6 10837.2 19969.3 20014.9 20065.9 20097.1 20099.9 8271.8 8321.5 8419.5 8452.7 8481.8 4533.1 4583.3 4667.3 4668.9 4700.8 3243.6 3367.1 3368.4 3370.3 3371.3 605.8 617.9 620.1 624.0 625.5 648.6 756.7 787.8 788.8 792.4 9439.4 10124.3 10261.6 10306.2 10349.6 629.8 652.8 762.6 787.0 788.8 4250.8 4416.9 4430.1 4466.5 4496.2 67871.0 70453.3 71002.0 71079.6 71346.9 60488.0 61471.4 61479.0 62455.0 63632.0 55124.0 55221.7 55354.4 56208.7 56830.4 9337.4 9611.5 9621.1 9630.0 9631.4 36340.5 36449.5 37276.8 37316.3 37407.6 46594.5 49019.3 50689.2 51367.7 51481.5 11117.1 11728.4 11734.3 11913.0 12202.3 13470.4 13476.4 13504.7 13729.3 14287.7 11896.2 12098.3 13450.2 13585.4 14008.9 13061.3 13671.1 13685.2 13839.4 13868.8 12853.4 13178.9 13232.4 13238.1 13415.2 11112.4 11276.8 11304.8 11370.3 11552.0 11165.0 11370.0 11427.7 11495.9 11672.6 19:21:08 INFO - PROCESS | 3370 | -------- Summary: end -------- 19:21:08 INFO - PROCESS | 3370 | 19:21:09 INFO - TEST-INFO | 3370: exit 0 19:21:09 INFO - TEST-OK | dromaeo_css | took 1118265ms 19:21:09 INFO - TEST-START | kraken 19:21:09 INFO - operating with platform_type : linux_ 19:21:09 INFO - Initialising browser for kraken test... 19:21:09 INFO - TEST-INFO | started process 3550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDS7ko/profile http://localhost:60061/getInfo.html) 19:21:14 INFO - PROCESS | 3550 | __metrics Screen width/height:1600/1200 19:21:14 INFO - PROCESS | 3550 | colorDepth:24 19:21:14 INFO - PROCESS | 3550 | Browser inner width/height: 1024/697 19:21:14 INFO - PROCESS | 3550 | __metrics 19:21:15 ERROR - PROCESS | 3550 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:21:16 INFO - TEST-INFO | 3550: exit 0 19:21:16 INFO - Browser initialized. 19:21:16 INFO - Running cycle 1/1 for kraken test... 19:21:16 INFO - TEST-INFO | started process 3630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDS7ko/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:21:28 INFO - PROCESS | 3630 | RSS: Main: 156692480 19:21:28 INFO - PROCESS | 3630 | 19:21:30 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60061/tests/kraken/driver.html?testName=audio-beat-detection) 19:21:31 INFO - PROCESS | 3630 | RSS: Main: 214249472 19:21:31 INFO - PROCESS | 3630 | 19:21:34 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60061/tests/kraken/driver.html?testName=audio-dft) 19:21:35 INFO - PROCESS | 3630 | RSS: Main: 202174464 19:21:35 INFO - PROCESS | 3630 | 19:21:38 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60061/tests/kraken/driver.html?testName=audio-fft) 19:21:38 INFO - PROCESS | 3630 | RSS: Main: 269238272 19:21:38 INFO - PROCESS | 3630 | 19:21:40 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60061/tests/kraken/driver.html?testName=audio-oscillator) 19:21:41 INFO - PROCESS | 3630 | RSS: Main: 179544064 19:21:41 INFO - PROCESS | 3630 | 19:21:43 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60061/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:44 INFO - PROCESS | 3630 | RSS: Main: 183955456 19:21:44 INFO - PROCESS | 3630 | 19:21:47 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60061/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:47 INFO - PROCESS | 3630 | RSS: Main: 217890816 19:21:47 INFO - PROCESS | 3630 | 19:21:51 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60061/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:51 INFO - PROCESS | 3630 | RSS: Main: 220635136 19:21:51 INFO - PROCESS | 3630 | 19:21:54 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60061/tests/kraken/driver.html?testName=json-parse-financial) 19:21:55 INFO - PROCESS | 3630 | RSS: Main: 220495872 19:21:55 INFO - PROCESS | 3630 | 19:21:56 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60061/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:21:57 INFO - PROCESS | 3630 | RSS: Main: 208699392 19:21:57 INFO - PROCESS | 3630 | 19:21:59 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:21:59 INFO - PROCESS | 3630 | RSS: Main: 209936384 19:21:59 INFO - PROCESS | 3630 | 19:22:01 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:02 INFO - PROCESS | 3630 | RSS: Main: 215740416 19:22:02 INFO - PROCESS | 3630 | 19:22:05 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:05 INFO - PROCESS | 3630 | RSS: Main: 221728768 19:22:05 INFO - PROCESS | 3630 | 19:22:08 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:08 INFO - PROCESS | 3630 | RSS: Main: 223588352 19:22:08 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:60061/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60061/tests/kraken/driver.html?testName=ai-astar) 19:22:10 INFO - PROCESS | 3630 | RSS: Main: 265310208 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | __start_tp_report 19:22:10 INFO - PROCESS | 3630 | _x_x_mozilla_page_load 19:22:10 INFO - PROCESS | 3630 | _x_x_mozilla_page_load_details 19:22:10 INFO - PROCESS | 3630 | |i|pagename|runs| 19:22:10 INFO - PROCESS | 3630 | |0;ai-astar;108;104;113;111;109;110;112;108;112;110 19:22:10 INFO - PROCESS | 3630 | |1;audio-beat-detection;154;125;126;178;124;130;156;125;171;126 19:22:10 INFO - PROCESS | 3630 | |2;audio-dft;161;160;160;160;161;161;160;159;161;161 19:22:10 INFO - PROCESS | 3630 | |3;audio-fft;74;74;77;126;75;76;75;74;112;75 19:22:10 INFO - PROCESS | 3630 | |4;audio-oscillator;83;84;111;84;82;83;127;83;83;133 19:22:10 INFO - PROCESS | 3630 | |5;imaging-gaussian-blur;118;117;117;116;117;117;117;116;116;117 19:22:10 INFO - PROCESS | 3630 | |6;imaging-darkroom;146;147;147;146;146;147;146;145;146;146 19:22:10 INFO - PROCESS | 3630 | |7;imaging-desaturate;95;95;94;95;96;95;95;95;95;95 19:22:10 INFO - PROCESS | 3630 | |8;json-parse-financial;58;53;55;55;54;55;54;54;55;54 19:22:10 INFO - PROCESS | 3630 | |9;json-stringify-tinderbox;62;62;61;60;62;68;61;62;62;62 19:22:10 INFO - PROCESS | 3630 | |10;stanford-crypto-aes;80;79;79;80;83;80;80;83;109;80 19:22:10 INFO - PROCESS | 3630 | |11;stanford-crypto-ccm;121;129;118;119;121;127;130;124;137;131 19:22:10 INFO - PROCESS | 3630 | |12;stanford-crypto-pbkdf2;162;163;161;161;160;164;159;166;165;158 19:22:10 INFO - PROCESS | 3630 | |13;stanford-crypto-sha256-iterative;63;62;67;64;63;63;64;62;68;64 19:22:10 INFO - PROCESS | 3630 | __end_tp_report 19:22:10 INFO - PROCESS | 3630 | __start_cc_report 19:22:10 INFO - PROCESS | 3630 | _x_x_mozilla_cycle_collect,1622 19:22:10 INFO - PROCESS | 3630 | __end_cc_report 19:22:10 INFO - PROCESS | 3630 | __startTimestamp1464834130887__endTimestamp 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | ------- Summary: start ------- 19:22:10 INFO - PROCESS | 3630 | Number of tests: 14 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.63 (2.4%) stddev-sans-first:2.71 19:22:10 INFO - PROCESS | 3630 | Values: 108.0 104.0 113.0 111.0 109.0 110.0 112.0 108.0 112.0 110.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:142.00 stddev:21.17 (14.9%) stddev-sans-first:21.96 19:22:10 INFO - PROCESS | 3630 | Values: 154.0 125.0 126.0 178.0 124.0 130.0 156.0 125.0 171.0 126.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 19:22:10 INFO - PROCESS | 3630 | Values: 161.0 160.0 160.0 160.0 161.0 161.0 160.0 159.0 161.0 161.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#3] audio-fft Cycles:10 Average:83.80 Median:75.00 stddev:18.87 (25.2%) stddev-sans-first:19.68 19:22:10 INFO - PROCESS | 3630 | Values: 74.0 74.0 77.0 126.0 75.0 76.0 75.0 74.0 112.0 75.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:83.00 stddev:20.30 (24.5%) stddev-sans-first:21.04 19:22:10 INFO - PROCESS | 3630 | Values: 83.0 84.0 111.0 84.0 82.0 83.0 127.0 83.0 83.0 133.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 19:22:10 INFO - PROCESS | 3630 | Values: 118.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 19:22:10 INFO - PROCESS | 3630 | Values: 146.0 147.0 147.0 146.0 146.0 147.0 146.0 145.0 146.0 146.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 19:22:10 INFO - PROCESS | 3630 | Values: 95.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.34 (2.4%) stddev-sans-first:0.71 19:22:10 INFO - PROCESS | 3630 | Values: 58.0 53.0 55.0 55.0 54.0 55.0 54.0 54.0 55.0 54.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:2.15 (3.5%) stddev-sans-first:2.28 19:22:10 INFO - PROCESS | 3630 | Values: 62.0 62.0 61.0 60.0 62.0 68.0 61.0 62.0 62.0 62.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:80.00 stddev:9.14 (11.4%) stddev-sans-first:9.62 19:22:10 INFO - PROCESS | 3630 | Values: 80.0 79.0 79.0 80.0 83.0 80.0 80.0 83.0 109.0 80.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:128.00 stddev:6.13 (4.8%) stddev-sans-first:6.26 19:22:10 INFO - PROCESS | 3630 | Values: 121.0 129.0 118.0 119.0 121.0 127.0 130.0 124.0 137.0 131.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.50 stddev:2.60 (1.6%) stddev-sans-first:2.76 19:22:10 INFO - PROCESS | 3630 | Values: 162.0 163.0 161.0 161.0 160.0 164.0 159.0 166.0 165.0 158.0 19:22:10 INFO - PROCESS | 3630 | 19:22:10 INFO - PROCESS | 3630 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:2.00 (3.1%) stddev-sans-first:2.09 19:22:10 INFO - PROCESS | 3630 | Values: 63.0 62.0 67.0 64.0 63.0 63.0 64.0 62.0 68.0 64.0 19:22:10 INFO - PROCESS | 3630 | -------- Summary: end -------- 19:22:10 INFO - PROCESS | 3630 | 19:22:13 INFO - TEST-INFO | 3630: exit 0 19:22:13 INFO - TEST-OK | kraken | took 63908ms 19:22:13 INFO - SUITE-END | took 1182s 19:22:13 INFO - Completed test suite (00:19:42) 19:22:13 INFO - javascript benchmark 19:22:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.8614062367196, 3092.2733157707935, 3092.768042396821, 3133.1227256368225, 3150.3856747934146, 5212.113940630102, 5289.259503669485, 5302.443243459439, 5358.6516876403, 5383.057964856154, 5336.332958380202, 5357.544608708255, 5358.839234822962, 5382.461753824616, 5407.486288802567, 4812.759362031902, 4855.732934688582, 4948.331975183341, 5002.4997500249865, 5039.924401133986, 4936.062148191832, 4980.526849949249, 4994.725290109042, 5009.574186194178, 5016.147254966664, 4714.245720684674, 4791.257355109946, 4800.663953523254, 4832.226843705025, 4902.950970490285, 4819.6867203631655, 4828.879278018043, 4845.055214233226, 4883.926741098886, 4901.656884018084, 4761.47623761386, 4798.520147985174, 4816.205326121186, 4825.0, 4832.106060378841, 5130.102232109403, 5211.270422140866, 5249.212618107276, 5263.0, 5298.735063246822, 5619.831405057855, 5693.544516438675, 5698.572607054488, 5699.74351154199, 5703.686297253649, 5756.30924289088, 5780.364159942403, 5800.564957628152, 5804.912926306109, 5809.535237181015, 5235.0, 5241.737913104368, 5265.657732247391, 5265.815696450606, 5414.187871819219, 5765.538756899438, 5769.596128271065, 5782.335031471351, 5788.7684492620665, 5803.042497987784, 5540.833774986757, 5564.27664403633, 5593.916091258634, 5594.384617692051, 5605.495505404451, 5528.889422211533, 5621.297337832771, 5668.234788303526, 5684.175794509773, 5709.086546152679, 5667.574931880125, 5667.773289068474, 5670.744816483269, 5676.631018983835, 5688.402717714663, 5476.0, 5673.262475878109, 5675.290588676416, 5698.772049118072, 5699.031164701928, 4213.0310028693575, 4289.699721019499, 4314.784260786948, 4315.266404711207, 4332.176886391576, 3831.0613899594778, 3837.347650899354, 3842.634949679776, 3869.845206191777, 3883.669888059547, 5331.493508116722, 5339.3058902342445, 5346.839594812084, 5353.929214157184, 5366.329208848894, 5593.888122237532, 5738.483536481736, 5765.769369225184, 5777.24895763548, 5778.971105144447, 5419.349678038582, 5426.782928682887, 5428.050091234098, 5429.321334833146, 5434.212039254286, 4789.784459699322, 4960.603151747851, 4968.577670897942, 4985.326980857611, 4994.325766021613, 5837.678927658934, 5860.3260625027815, 5872.2366092408565, 5895.08626162954, 5920.141579470953, 5693.9430605693415, 5717.914231286451, 5731.140328950691, 5733.2260144881575, 5733.455321744427, 5752.568557358131, 5851.093080572518, 5896.734646940984, 5910.940890591039, 5918.467337939606, 5608.242887210175, 5665.093585026376, 5672.829815105553, 5677.630953987978, 5723.828285151452, 5725.570582206435, 5745.1382292656435, 5777.768889244551, 5795.8551036224435, 5801.332846722681, 5388.515033646834, 5582.385937546947, 5608.6073974821375, 5616.85957851057, 5628.409017053151, 5630.127330263816, 5637.52081073097, 5640.266765320482, 5648.519875810603, 5680.715964201856, 5534.031544479781, 5544.22380866687, 5549.1676248563035, 5549.639273447213, 5550.4449555044175, 5479.698616576126, 5586.52514536269, 5635.88728225425, 5652.463016013472, 5668.093105103164, 5574.303212098488, 5575.0, 5576.665400076008, 5582.581306401955, 5606.383297837153], "value": 5260.061822079871, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.7091454272863, 609.5641616244387, 615.2063837649432, 642.075411407573, 644.3041515163624, 31373.372532549365, 42927.78536107319, 44001.11997760043, 44011.3398299025, 44076.11847763051, 44761.104777904424, 45490.772546137305, 45521.0, 45537.316940245924, 45576.3163552547, 8126.471779334354, 8716.782080448007, 8895.777605559848, 10480.685579432591, 10723.195760317969, 10394.220433467483, 10514.106300964428, 10517.579296828118, 10522.947385263102, 10523.73690657732, 10485.423301718403, 10548.47257637115, 10551.577936882515, 10555.261131720783, 10557.630482933098, 10464.267501274913, 10489.58041678332, 10549.208809339254, 10551.577936882515, 10559.049685528262, 10395.84406233907, 10480.63317783874, 10488.423136727477, 10498.79002419955, 10505.842412363821, 41344.0, 41819.954501137414, 41826.1634767306, 41883.581164188276, 41943.58056419427, 3583.605803361628, 3604.963950360489, 3611.4943907852658, 3708.629137086297, 3709.1283548366114, 32343.6765632343, 33155.0, 33247.20326198981, 34389.312213755584, 34406.6559334406, 4078.449409329732, 4085.4893138357706, 4093.89765255868, 4114.670826333886, 4137.40007698882, 334850.0, 334889.0, 335661.0, 336160.3191984049, 336446.0, 60237.69881150566, 60598.0, 60643.0, 61131.69434152801, 61228.693856531325, 63076.684616576626, 63516.0, 63680.36319636837, 63782.36217637857, 63794.68102659457, 13164.736705266032, 13219.405126769318, 13238.073334866467, 13272.668183295495, 13335.0, 12470.937645311715, 12503.687407814878, 12536.185147965352, 12541.059420543495, 12543.623691289276, 7600.3539699126195, 7768.7669369918995, 7775.533467991939, 7874.56689882062, 7884.802879928047, 2980.5231163014246, 2987.5070613348553, 2988.6861879502776, 3010.9397812043635, 3014.7437467815043, 1012.832882574367, 1034.5861655337926, 1048.0253364371056, 1117.2291119127776, 1180.8523934508187, 32410.0, 32534.34931301361, 32615.673843261262, 32710.0, 32862.671373285964, 938.2775263047415, 1125.611663975927, 1127.413744852672, 1219.4451524556305, 1256.0579565326007, 16370.836291636931, 16957.660846782994, 17245.56886077858, 17718.734218986727, 18185.63628727418, 524447.0, 529465.0, 532039.0, 532489.0, 532751.0, 57695.0, 57723.422765772644, 57725.13412298819, 57921.0, 57973.0, 1355.7762969110183, 1359.9660008499668, 1366.6719987203, 1400.6078298076554, 1402.7334806386755, 31174.37651246917, 32703.836480817445, 32755.344893101526, 33308.167295817795, 33565.83217083997, 48567.27149092837, 49607.0, 49655.75172124116, 49778.751106244235, 49810.0, 400140.0, 402442.0, 404752.0, 610630.0, 624798.0, 26085.608715868897, 26314.473710526065, 26711.732882671702, 26761.598576020984, 26924.596131057657, 40024.59975400209, 44611.77694111509, 44877.0, 44904.550954491344, 45463.0, 37861.81069094637, 38339.61660383472, 38492.42261366026, 38670.41994370143, 39014.41478377883, 36738.448923265634, 38366.80816595899, 38565.4215186778, 38576.0, 38839.6116038836, 38727.0, 38769.224615508094, 40941.0, 41117.383239250834, 41926.371104432845, 34315.0, 36463.0, 36585.0, 37025.30542315995, 37501.0, 28245.0, 28605.570916436685, 28910.0, 28911.56632650554, 28981.0], "value": 19406.98841369847, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.86109000759706, 399.1398536154586, 399.2155414610291, 409.18163672654686, 420.65296130692184, 14246.430142794276, 17175.227114779835, 17845.7323140153, 19141.904290478546, 19142.0, 14872.851271487281, 17379.217935192915, 18101.4569562913, 18211.908940455312, 18977.33579324724, 5442.6462279951775, 5645.15322701596, 5665.036943719578, 5700.3159620845345, 5706.543476521868, 4118.485189351331, 5578.86052848678, 5587.888242235153, 5936.643801371932, 6243.250809902818, 6207.410296021893, 6261.405166509197, 6267.623942563438, 6278.843028924268, 6291.559590828644, 6089.238845144357, 6200.906986395185, 6229.937700622981, 6263.561550691431, 6300.684965751717, 5452.182172674091, 5488.4785945335125, 5527.088030474967, 5555.388907220203, 5556.638818476827, 21784.0, 22447.77552224473, 22530.21144259943, 22556.774432255632, 22695.659565106536, 2371.750966148547, 2374.3233178544033, 2473.332200305925, 2725.4004119093765, 2732.27594687408, 13098.672533186747, 13820.308984550733, 13822.308884555832, 13878.375473103735, 14617.561473155822, 1927.9421617351502, 1957.1486403414417, 2053.2608261026166, 2082.1567265257445, 2161.9567608647894, 80793.19206807915, 81361.0, 81606.18393816045, 83656.16343836545, 83926.16073839244, 10966.122710183166, 11160.944195278971, 11170.720731981603, 11261.099112071015, 11300.265482743594, 15507.84492155064, 15568.377264909503, 15625.14061726616, 15660.0, 15683.843161568466, 7986.440949133505, 8195.754127376189, 8197.71308004217, 8292.709755158541, 8343.249107580346, 7083.539569927938, 7841.411894107965, 8074.394420418492, 8220.465669731448, 8244.68085106392, 4444.466664000276, 4695.295705644113, 4813.109574728686, 4873.683210591371, 4897.338859253955, 1954.7361106250758, 2017.1023894366865, 2019.4143698327614, 2026.9189232430833, 2037.0, 957.9808403831885, 973.2116985241977, 1015.4719545836124, 1063.043261065032, 1088.646189988257, 11813.88186118145, 11969.820452693217, 11990.520379184734, 12513.874861251454, 18356.173972171284, 491.76149567459754, 493.2896628854438, 497.0928056297272, 500.8046861723858, 513.2378418049194, 6255.967765318763, 6495.382938620918, 6517.0, 6548.541602087904, 6610.570312929644, 346669.0, 348853.0, 353694.0, 486399.0, 506001.0, 90118.0, 97702.0, 97845.0, 98236.0, 99024.0, 486.093435742338, 498.2028753993581, 500.5095006893265, 501.3031885678909, 501.70900877490965, 10970.451477426257, 11019.614313498989, 11264.155188360743, 11336.943315283701, 11434.085273178292, 38161.6183838158, 39149.21701565896, 41760.791196045044, 42240.0, 43538.34692479552, 53291.0, 54241.0, 57554.7122264386, 58238.12642810276, 58964.41035589589, 8650.826983460422, 8721.6947406843, 8772.538485175755, 8800.207981281716, 8874.112588741074, 9490.952545237229, 12112.75774484523, 12940.094193406747, 13203.339833008504, 13347.332633368487, 11922.940385298018, 11973.102017348909, 12017.939910300393, 12034.097442691658, 12140.0, 11602.18784685064, 11641.185117041725, 11846.940765296118, 11904.940475297568, 11927.224730392496, 11626.128040397174, 11903.82144267854, 12057.457414416196, 12298.262104273772, 12353.0, 8558.400911936105, 8714.69498567547, 8862.689805856764, 8926.68756593516, 8983.910160898307, 8321.66473158895, 8434.24091831738, 8512.957435212784, 8973.01296857332, 9095.818083638422], "value": 8557.153212343259, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.6494978830606, 1486.5168820133458, 1511.939522419103, 1654.4788391656627, 1802.5043113143884, 7851.411144164189, 8121.634526446309, 8142.755717328475, 8332.66669333226, 8352.958235208815, 8123.390745694067, 8303.709370172046, 8319.584020798966, 8327.750167494985, 8328.250457458827, 2000.259903835583, 2264.1396269417683, 2285.53146604946, 2308.319045881469, 2339.4736184358553, 2322.581935251654, 2325.5116425550655, 2329.04509151248, 2330.196082351586, 2330.906763729449, 2290.2327720213666, 2345.155743932183, 2346.1671106757112, 2346.21401830387, 2348.377679914824, 2007.9498012549657, 2250.1224522436346, 2325.802306803924, 2325.93022209334, 2328.5924963131383, 2240.641497360431, 2245.236619549361, 2249.3814201094665, 2250.088714070788, 2302.3898666853297, 8403.621837017288, 8427.073021967579, 8437.746867593982, 8459.0, 8461.280791132762, 4954.455009948902, 5049.974750126225, 5051.444341122474, 5062.215356619729, 5066.062778385974, 3158.3418705179733, 3198.392305461955, 3215.742740580748, 3275.606927168731, 3287.736981041535, 1066.2003497376968, 1106.4633652678444, 1118.72031992002, 1127.4813585750564, 1141.2182654881433, 11384.715382115348, 11412.885871141349, 11418.200725949187, 11453.942730286295, 11454.083673306115, 4353.151135528541, 4444.0, 4499.0, 4508.391367165391, 4515.390422292949, 9699.0, 9717.757056073655, 9725.270604704676, 9732.854007189897, 9743.0, 4517.593416592523, 4961.90076198474, 4977.601791856643, 4980.377452818398, 4983.02830947969, 4504.504504504502, 4646.210144275481, 4662.836800711959, 4680.438347398266, 4692.741899195509, 3877.6316249956208, 3969.6427321540623, 4009.158076803904, 4038.6163314485075, 4090.3046482098116, 1275.6045625856013, 1287.291989405823, 1294.9028822838325, 1301.6680746409604, 1303.7653222420056, 986.7483791633088, 1007.0936157458347, 1021.4637315409469, 1024.5901639344172, 1039.859618951432, 5959.106134079922, 5984.760609575568, 6054.182685337423, 6060.545459090586, 6078.331383547807, 439.0823179554682, 442.87820849266063, 453.25665907911053, 455.8928651766832, 456.42490462018094, 4172.624463798273, 4207.137536804948, 4207.852725154666, 4209.2633789086785, 4217.957820421757, 9941.751456213507, 10304.381737095648, 10315.226358023177, 10334.483275836179, 10385.740356491147, 10138.746531336776, 10215.744606384751, 10264.794704106025, 10271.075603195599, 10273.74315642115, 7594.734184303523, 7876.606169691492, 7949.085855126619, 7968.442209045311, 7989.480683755537, 5888.793892213752, 5891.3224979127945, 5931.703414829328, 6016.969915150396, 6079.696015199311, 9169.724908252763, 9500.714978550655, 9599.376040557341, 9661.71014869555, 9682.0, 9574.47340396285, 9765.9023409765, 9780.853287200556, 9889.25830562725, 9898.20814334837, 6736.831579210363, 7096.219415864354, 7260.818479538054, 7523.172451030492, 7653.349465295512, 8120.228578285053, 8121.147279535564, 8157.75526734199, 8188.303994160563, 8191.221833925768, 7069.893951590833, 7219.9892015116875, 7240.601766902871, 7318.0, 7401.296876796696, 7078.2921707830865, 7125.786226413215, 7151.749688760869, 7172.49792514519, 7196.0, 6698.83252918681, 6787.185537735503, 6795.966020169867, 6805.761798337035, 6807.251202367835, 4875.756212189447, 4989.501049894981, 5013.097642424253, 5052.5705315048635, 5102.10713125209, 5245.265662807134, 5305.442928492607, 5333.466653334635, 5354.625176237626, 5440.945590544202], "value": 4418.6561990954915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.356084270616, 326.4140867143477, 333.6563339760047, 338.588614832978, 341.74710714071597, 5248.265242865992, 5299.8410047698535, 5422.40353561108, 5452.909418116377, 5513.476219759122, 5328.227407025979, 5361.758720857571, 5414.620976531644, 5421.268128802611, 5427.565794736422, 4200.768957707332, 4212.789360531977, 4220.134872351161, 4224.66202703783, 4238.3430568261965, 3984.565556399694, 3993.6605388542007, 4203.591796748078, 4214.0, 4242.193983143193, 4088.2641124597562, 4116.403121547374, 4153.833846646131, 4163.417121602978, 4177.624013838754, 4115.423840662294, 4125.133721918401, 4141.627253547195, 4147.7926103695, 4190.329547272452, 3474.9131271718156, 3706.629337066299, 3709.1839795245273, 3722.5160729105314, 3723.5345581802276, 5121.155009423441, 5136.768845401966, 5149.665271757323, 5162.6128040397125, 5163.896722065575, 4718.8584342469785, 4738.228136540129, 4751.168545504523, 4761.976190119062, 4763.0, 5375.919361209585, 5495.258140151069, 5521.806736764234, 5559.277293951801, 5563.859408821182, 3961.1879564689457, 3970.9602903971168, 3977.250009995593, 4000.6399424051974, 4009.799510024487, 5796.333421656479, 5807.88384232313, 5816.360200377955, 5865.882682346371, 5912.0, 4025.4364388985564, 4094.7952602369764, 4121.196366708463, 4122.711410201257, 4145.647619952338, 4124.381342798604, 4143.730657507313, 4146.315857883415, 4161.001359673656, 4175.060611362419, 4769.499202583748, 4772.904541909143, 4780.784864681098, 4783.210770222944, 4790.856274311776, 5457.508824205841, 5598.488408129782, 5675.659460432387, 5718.285214348207, 5718.771249150071, 1371.163590209971, 1389.4720006397706, 1397.0779285671408, 1397.3991183791068, 1403.7613605687059, 881.7354793562034, 886.2068448738343, 887.5961437545901, 900.2482926756232, 903.503073309677, 4822.0, 4828.551434569615, 4841.612670986313, 4848.781804818792, 4874.926876096862, 4974.751262436864, 5034.84895453137, 5052.797888084436, 5114.337840201949, 5120.359955005624, 4719.126961512131, 4738.19450693383, 4806.855794326176, 4831.323614693945, 4872.879237775261, 2737.835729856215, 2743.5856446327102, 2747.821391609539, 2751.5786108224634, 2762.8894844206006, 5093.0137246473905, 5204.230561608995, 5216.426193118754, 5265.3936301530985, 5301.946980530222, 5193.28621554886, 5205.687658740488, 5222.686638801608, 5243.872567398028, 5244.895102097937, 5201.29782479368, 5249.690136339998, 5253.4221235664045, 5263.473652634706, 5275.868103297448, 4925.285833554043, 5096.796128154832, 5118.334616499831, 5134.922976155286, 5153.201253805516, 5054.772535235849, 5059.46875578059, 5091.1854103343285, 5139.511746384088, 5145.974270128625, 4990.47600002009, 5308.177232528964, 5330.733463326896, 5346.224797404432, 5374.838754837361, 3729.776213427203, 3751.9624803751612, 3761.6426439488205, 3771.5851256362325, 3771.6039815818945, 5098.643094983316, 5135.6405051646025, 5140.742962851918, 5201.635885487979, 5208.140656791587, 4971.254311853251, 4980.260856528967, 4989.577970278453, 5009.899802004013, 5027.7234752087525, 5177.7670004849115, 5403.000444917703, 5645.887082258414, 5684.516816070681, 5699.458551437607, 5457.645253058538, 5561.860953476195, 5574.801417695215, 5584.664920104807, 5594.244776955059, 5692.971535142297, 5716.371199168171, 5745.971270143623, 5758.827235182951, 5760.8271751847515, 5521.779128834802, 5622.341409284353, 5630.943690563206, 5641.746121424464, 5647.435256474319], "value": 4098.8831282014135, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.8652735771238, 776.3975155279503, 784.0356361675141, 839.2362949715753, 863.6329559937025, 15522.0, 15718.292676829542, 15754.054756714591, 15778.447754328601, 15786.052836829784, 14535.418583256656, 15260.38958441661, 15273.236338183102, 15703.21483925802, 15750.606234844134, 676.3777324861115, 827.5159031966307, 829.6391069884589, 834.7620928035511, 834.8038211020405, 798.3573223555036, 819.5902048975512, 826.2027143806225, 826.396730386818, 827.668932427031, 853.1255463150259, 854.7948492361819, 856.4390324337538, 857.3184318466803, 857.4298091768966, 698.5389642835755, 827.8344331133737, 852.1052894460822, 856.092541905582, 861.1689719420758, 614.8339948214002, 617.493655202734, 617.7158507086746, 618.0698049435615, 618.1284389011518, 14573.70852582953, 14607.634809129751, 14619.561413157622, 14631.487897923522, 14639.707205855822, 12914.0, 13488.583698711593, 13494.920406367468, 13731.176129432166, 13806.17162970215, 11614.477348519253, 12139.585822381503, 12445.62663120108, 12452.81320780189, 12646.367681615975, 3756.7370284080125, 3812.618738126193, 3835.5013848199555, 3865.0, 3996.6003399660094, 62494.37505624931, 62971.0, 63955.3604463954, 63969.36030639727, 64044.67977660128, 10300.639477618248, 10306.433146176882, 10530.57877684884, 10616.575336986589, 10837.18721095921, 19969.301074462324, 20014.89992550028, 20065.899670501847, 20097.095630696655, 20099.899500502695, 8271.834563308821, 8321.500709957421, 8419.452735572168, 8452.704155354533, 8481.830363392697, 4533.093381323749, 4583.266677331678, 4667.346571479996, 4668.906621867543, 4700.764961751899, 3243.61076670801, 3367.0572239772414, 3368.3768502827056, 3370.342783157261, 3371.325734853039, 605.7698074731574, 617.9073139029182, 620.1225266248208, 623.9563230573907, 625.4933503861887, 648.6043513456788, 756.6557216466495, 787.7597332813432, 788.755485799404, 792.445288298182, 9439.386439881386, 10124.34191777532, 10261.640842570623, 10306.227032972558, 10349.63776267827, 629.8110566829932, 652.8414002802181, 762.6110683551428, 786.9803254918672, 788.798856291642, 4250.787460626956, 4416.933745993812, 4430.13612345596, 4466.508684044752, 4496.235639941217, 67871.0, 70453.2954670457, 71002.0, 71079.64460177666, 71346.9297960531, 60488.0, 61471.38528614746, 61479.0, 62455.0, 63632.0, 55124.0, 55221.72389138029, 55354.44645553573, 56208.71895640578, 56830.43169568334, 9337.439753614804, 9611.471369074488, 9621.08599683029, 9630.0, 9631.373960692812, 36340.454893176095, 36449.45325820168, 37276.813615931744, 37316.25367492689, 37407.625923740416, 46594.534054659016, 49019.26471102865, 50689.239661405845, 51367.74316128396, 51481.48518514917, 11117.11063114965, 11728.41357932117, 11734.295942243492, 11913.0, 12202.32887191213, 13470.393832277376, 13476.393562289917, 13504.6623834401, 13729.313534323044, 14287.714245715235, 11896.22674526153, 12098.334591597546, 13450.192988420726, 13585.388657510633, 14008.949328800178, 13061.34693265352, 13671.111377760413, 13685.178889266675, 13839.377228024965, 13868.791968120688, 12853.42159602839, 13178.934105329412, 13232.404541795835, 13238.139520931109, 13415.195088294733, 11112.444377781241, 11276.830847537458, 11304.830427543759, 11370.260933039326, 11552.0, 11165.0, 11370.0, 11427.714307142389, 11495.885041149815, 11672.591459298883], "value": 7011.957762946197, "unit": "score"}], "name": "dromaeo_css", "value": 6931.9147878296135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 104.0, 113.0, 111.0, 109.0, 110.0, 112.0, 108.0, 112.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 126.0, 178.0, 124.0, 130.0, 156.0, 125.0, 171.0, 126.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 159.0, 161.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 126.0, 75.0, 76.0, 75.0, 74.0, 112.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 111.0, 84.0, 82.0, 83.0, 127.0, 83.0, 83.0, 133.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 60.0, 62.0, 68.0, 61.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 80.0, 83.0, 80.0, 80.0, 83.0, 109.0, 80.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 129.0, 118.0, 119.0, 121.0, 127.0, 130.0, 124.0, 137.0, 131.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 161.0, 160.0, 164.0, 159.0, 166.0, 165.0, 158.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 67.0, 64.0, 63.0, 63.0, 64.0, 62.0, 68.0, 64.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1500.5000000000002, "alertThreshold": 2.0}]} 19:22:13 INFO - Return code: 0 19:22:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:22:13 ERROR - # TBPL SUCCESS # 19:22:13 INFO - Running post-action listener: _resource_record_post_action 19:22:13 INFO - ##### 19:22:13 INFO - ##### Finished run-tests step (success) 19:22:13 INFO - ##### 19:22:13 INFO - Running post-run listener: _resource_record_post_run 19:22:13 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 7962624; Write bytes: 457297920; Read time: 2460; Write time: 684444 19:22:13 INFO - TinderboxPrint: CPU usage
13.3% 19:22:13 INFO - TinderboxPrint: I/O read bytes / time
7,962,624 / 2,460 19:22:13 INFO - TinderboxPrint: I/O write bytes / time
457,297,920 / 684,444 19:22:13 INFO - TinderboxPrint: CPU idle
8,305.4 (86.7%) 19:22:13 INFO - TinderboxPrint: CPU user
1,215.3 (12.7%) 19:22:13 INFO - TinderboxPrint: Swap in / out
0 / 0 19:22:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 15249408; Read time: 0; Write time: 3908 19:22:13 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 6201344; Write bytes: 442048512; Read time: 2420; Write time: 680536 19:22:14 INFO - Running post-run listener: _upload_blobber_files 19:22:14 INFO - Blob upload gear active. 19:22:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:22:14 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 19:22:14 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:22:14 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:22:14 INFO - (blobuploader) - INFO - Open directory for files ... 19:22:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:22:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:20 INFO - (blobuploader) - INFO - Done attempting. 19:22:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:22:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:20 INFO - (blobuploader) - INFO - Done attempting. 19:22:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:22:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:22:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:21 INFO - (blobuploader) - INFO - Done attempting. 19:22:21 INFO - (blobuploader) - INFO - Iteration through files over. 19:22:21 INFO - Return code: 0 19:22:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:22:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:22:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b2e59f7fa39b6729c9fc82f489abaf29ec10b65dcfe1abc825cbfbcf3d4c257452c38022c15e60dcb480d201062e784356f4aee5719fe7b3acc539753bf40a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053fa9219bd69b782b388ac8b57899f788f8803f53b259d2771f986d3ec3179ab5d17dfb6be8a320179afec3012c091ff3c8bb127bc8647258b6ea4ceefe158c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1fce8c400210047fba63ae5a15f123dd77558071dc2c240e78d74cb3e6df2bf0b5f02e5f3b4f6d15ba58b72d3040fabbd563c751eabb72b0421740e0d93d2"} 19:22:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:22:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:22:21 INFO - Contents: 19:22:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b2e59f7fa39b6729c9fc82f489abaf29ec10b65dcfe1abc825cbfbcf3d4c257452c38022c15e60dcb480d201062e784356f4aee5719fe7b3acc539753bf40a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053fa9219bd69b782b388ac8b57899f788f8803f53b259d2771f986d3ec3179ab5d17dfb6be8a320179afec3012c091ff3c8bb127bc8647258b6ea4ceefe158c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1fce8c400210047fba63ae5a15f123dd77558071dc2c240e78d74cb3e6df2bf0b5f02e5f3b4f6d15ba58b72d3040fabbd563c751eabb72b0421740e0d93d2"} 19:22:21 INFO - Running post-run listener: copy_logs_to_upload_dir 19:22:21 INFO - Copying logs to upload dir... 19:22:21 INFO - mkdir: /builds/slave/test/build/upload/logs 19:22:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.749011 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-01 19:22:21.655984) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:22:21.663877) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b2e59f7fa39b6729c9fc82f489abaf29ec10b65dcfe1abc825cbfbcf3d4c257452c38022c15e60dcb480d201062e784356f4aee5719fe7b3acc539753bf40a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053fa9219bd69b782b388ac8b57899f788f8803f53b259d2771f986d3ec3179ab5d17dfb6be8a320179afec3012c091ff3c8bb127bc8647258b6ea4ceefe158c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1fce8c400210047fba63ae5a15f123dd77558071dc2c240e78d74cb3e6df2bf0b5f02e5f3b4f6d15ba58b72d3040fabbd563c751eabb72b0421740e0d93d2"} build_url:https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/PU2_KnZcSw2vbcJ5LXqOdQ/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/Firefox/sha512/d4b2e59f7fa39b6729c9fc82f489abaf29ec10b65dcfe1abc825cbfbcf3d4c257452c38022c15e60dcb480d201062e784356f4aee5719fe7b3acc539753bf40a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053fa9219bd69b782b388ac8b57899f788f8803f53b259d2771f986d3ec3179ab5d17dfb6be8a320179afec3012c091ff3c8bb127bc8647258b6ea4ceefe158c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1fce8c400210047fba63ae5a15f123dd77558071dc2c240e78d74cb3e6df2bf0b5f02e5f3b4f6d15ba58b72d3040fabbd563c751eabb72b0421740e0d93d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:22:21.691651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:22:21.691990) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464831765.542126-68323766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004638 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:22:21.741635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:22:21.741993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:22:21.742301) ========= ========= Total master_lag: 0.14 =========