builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-024 starttime: 1463808797.69 results: success (0) buildid: 20160520212034 builduid: 37f845c2f7054708a3bac5462d464393 revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.692638) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.706381) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.706818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.746325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.746637) ========= 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=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 22:33:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-05-20 22:33:17 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149217 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.909340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.909673) ========= 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=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017357 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.959466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:17.959832) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f1f2644d34440fe38c098e323bf570e09197e51a --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 f1f2644d34440fe38c098e323bf570e09197e51a --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=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 22:33:18,026 truncating revision to first 12 chars 2016-05-20 22:33:18,026 Setting DEBUG logging. 2016-05-20 22:33:18,026 attempt 1/10 2016-05-20 22:33:18,026 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f1f2644d3444?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 22:33:18,290 unpacking tar archive at: mozilla-central-f1f2644d3444/testing/mozharness/ program finished with exit code 0 elapsedTime=0.552534 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:18.525671) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:18.526006) ========= script_repo_revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:18.526441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:18.526732) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 22:33:18.535561) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-05-20 22:33:18.535967) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:33:18 INFO - MultiFileLogger online at 20160520 22:33:18 in /builds/slave/test 22:33:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:33:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:33:18 INFO - {'append_to_log': False, 22:33:18 INFO - 'base_work_dir': '/builds/slave/test', 22:33:18 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:33:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:33:18 INFO - 'branch': 'Firefox-Non-PGO', 22:33:18 INFO - 'buildbot_json_path': 'buildprops.json', 22:33:18 INFO - 'config_files': ('talos/linux_config.py',), 22:33:18 INFO - 'default_actions': ('clobber', 22:33:18 INFO - 'read-buildbot-config', 22:33:18 INFO - 'download-and-extract', 22:33:18 INFO - 'populate-webroot', 22:33:18 INFO - 'create-virtualenv', 22:33:18 INFO - 'install', 22:33:18 INFO - 'run-tests'), 22:33:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:33:18 INFO - 'download_minidump_stackwalk': True, 22:33:18 INFO - 'download_symbols': 'ondemand', 22:33:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:33:18 INFO - 'tooltool.py': '/tools/tooltool.py', 22:33:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:33:18 INFO - '/tools/misc-python/virtualenv.py')}, 22:33:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:33:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:33:18 INFO - 'installer_path': 'installer.exe', 22:33:18 INFO - 'log_level': 'info', 22:33:18 INFO - 'log_name': 'talos', 22:33:18 INFO - 'log_to_console': True, 22:33:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:33:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:33:18 INFO - 'opt_config_files': (), 22:33:18 INFO - 'pip_index': False, 22:33:18 INFO - 'sps_profile': False, 22:33:18 INFO - 'sps_profile_interval': 0, 22:33:18 INFO - 'suite': 'dromaeojs', 22:33:18 INFO - 'system_bits': '32', 22:33:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:33:18 INFO - 'title': 'talos-linux64-ix-024', 22:33:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:33:18 INFO - 'use_talos_json': True, 22:33:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:33:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:33:18 INFO - 'work_dir': 'build'} 22:33:18 INFO - ##### 22:33:18 INFO - ##### Running clobber step. 22:33:18 INFO - ##### 22:33:18 INFO - Running pre-action listener: _resource_record_pre_action 22:33:18 INFO - Running main action method: clobber 22:33:18 INFO - rmtree: /builds/slave/test/build 22:33:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:33:19 INFO - Running post-action listener: _resource_record_post_action 22:33:19 INFO - ##### 22:33:19 INFO - ##### Finished clobber step (success) 22:33:19 INFO - ##### 22:33:19 INFO - ##### 22:33:19 INFO - ##### Running read-buildbot-config step. 22:33:19 INFO - ##### 22:33:19 INFO - Running pre-action listener: _resource_record_pre_action 22:33:19 INFO - Running main action method: read_buildbot_config 22:33:19 INFO - Using buildbot properties: 22:33:19 INFO - { 22:33:19 INFO - "project": "", 22:33:19 INFO - "product": "firefox", 22:33:19 INFO - "script_repo_revision": "production", 22:33:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:33:19 INFO - "repository": "", 22:33:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 22:33:19 INFO - "buildid": "20160520212034", 22:33:19 INFO - "pgo_build": "False", 22:33:19 INFO - "basedir": "/builds/slave/test", 22:33:19 INFO - "buildnumber": 93, 22:33:19 INFO - "slavename": "talos-linux64-ix-024", 22:33:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:33:19 INFO - "platform": "ubuntu64_hw", 22:33:19 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 22:33:19 INFO - "branch": "mozilla-central", 22:33:19 INFO - "repo_path": "mozilla-central", 22:33:19 INFO - "revision": "f1f2644d34440fe38c098e323bf570e09197e51a", 22:33:19 INFO - "stage_platform": "linux64", 22:33:19 INFO - "builduid": "37f845c2f7054708a3bac5462d464393", 22:33:19 INFO - "slavebuilddir": "test" 22:33:19 INFO - } 22:33:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:33:19 INFO - Running post-action listener: _resource_record_post_action 22:33:19 INFO - ##### 22:33:19 INFO - ##### Finished read-buildbot-config step (success) 22:33:19 INFO - ##### 22:33:19 INFO - ##### 22:33:19 INFO - ##### Running download-and-extract step. 22:33:19 INFO - ##### 22:33:19 INFO - Running pre-action listener: _resource_record_pre_action 22:33:19 INFO - Running main action method: download_and_extract 22:33:19 INFO - mkdir: /builds/slave/test/build/tests 22:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:19 INFO - https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:33:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:33:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:33:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:27 INFO - Downloaded 1361 bytes. 22:33:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:33:27 INFO - Using the following test package requirements: 22:33:27 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:33:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:33:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:33:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'jsshell-linux-x86_64.zip'], 22:33:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:33:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:33:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:33:27 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:33:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:33:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:33:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:27 INFO - https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:33:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:33:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:33:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:32 INFO - Downloaded 22814329 bytes. 22:33:32 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'] 22:33:32 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 22:33:34 INFO - Return code: 0 22:33:34 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 22:33: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')]} 22:33:34 INFO - https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:33:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:33:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:33:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:35 INFO - Downloaded 22814329 bytes. 22:33:35 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'] 22:33:35 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 22:33:37 INFO - Return code: 0 22:33:37 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')]} 22:33:37 INFO - https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:33:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:33:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:33:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:38 INFO - Downloaded 10840359 bytes. 22:33:38 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'] 22:33:38 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 22:33:38 INFO - Return code: 0 22:33: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')]} 22:33:38 INFO - https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:33:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:33:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:33:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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 22:33:41 INFO - Downloaded 54818616 bytes. 22:33:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:33:41 INFO - mkdir: /builds/slave/test/properties 22:33:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:33:41 INFO - Writing to file /builds/slave/test/properties/build_url 22:33:41 INFO - Contents: 22:33:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:33:41 INFO - Running post-action listener: _resource_record_post_action 22:33:41 INFO - Running post-action listener: set_extra_try_arguments 22:33:41 INFO - ##### 22:33:41 INFO - ##### Finished download-and-extract step (success) 22:33:41 INFO - ##### 22:33:41 INFO - ##### 22:33:41 INFO - ##### Running populate-webroot step. 22:33:41 INFO - ##### 22:33:41 INFO - Running pre-action listener: _resource_record_pre_action 22:33:41 INFO - Running main action method: populate_webroot 22:33:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:33:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:33:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:33:41 INFO - '--tppagecycles', 22:33:41 INFO - '7'], 22:33:41 INFO - 'tests': ['tsvgm']}}, 22:33:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:33:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:33:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:33:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:33:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['damp', 'tps']}, 22:33:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'tests': ['damp', 'tps']}, 22:33:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['dromaeo_dom']}, 22:33:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:33:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'other-e10s': {'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['a11yr', 22:33:41 INFO - 'ts_paint', 22:33:41 INFO - 'tpaint', 22:33:41 INFO - 'sessionrestore', 22:33:41 INFO - 'sessionrestore_no_auto_restore', 22:33:41 INFO - 'tabpaint']}, 22:33:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['tsvgx', 22:33:41 INFO - 'tsvgr_opacity', 22:33:41 INFO - 'tart', 22:33:41 INFO - 'tscrollx', 22:33:41 INFO - 'cart']}, 22:33:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:33:41 INFO - 'tsvgr_opacity', 22:33:41 INFO - 'tart', 22:33:41 INFO - 'tscrollx', 22:33:41 INFO - 'cart']}, 22:33:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'talos_options': ['--disable-e10s'], 22:33:41 INFO - 'tests': ['tp5o']}, 22:33:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'tests': ['tp5o']}, 22:33:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'talos_options': ['--disable-e10s', 22:33:41 INFO - '--xperf_path', 22:33:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:41 INFO - 'tests': ['tp5n']}, 22:33:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:41 INFO - 'talos_options': ['--xperf_path', 22:33:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:41 INFO - 'tests': ['tp5n']}}, 22:33:41 INFO - 'talos.zip': {'path': '', 22:33:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:33:41 INFO - Running post-action listener: _resource_record_post_action 22:33:41 INFO - ##### 22:33:41 INFO - ##### Finished populate-webroot step (success) 22:33:41 INFO - ##### 22:33:41 INFO - ##### 22:33:41 INFO - ##### Running create-virtualenv step. 22:33:41 INFO - ##### 22:33:41 INFO - Running pre-action listener: _resource_record_pre_action 22:33:41 INFO - Running main action method: create_virtualenv 22:33:41 INFO - Creating virtualenv /builds/slave/test/build/venv 22:33:41 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 22:33:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:33:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:33:41 INFO - Using real prefix '/usr' 22:33:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:33:42 INFO - Installing distribute.............................................................................................................................................................................................done. 22:33:44 INFO - Installing pip.................done. 22:33:44 INFO - Return code: 0 22:33:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:44 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:44 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 22:33:44 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 22:33:44 INFO - Using env: {'DISPLAY': ':0', 22:33:44 INFO - 'HOME': '/home/cltbld', 22:33:44 INFO - 'LANG': 'en_US.UTF-8', 22:33:44 INFO - 'LANGUAGE': 'en_US:en', 22:33:44 INFO - 'LOGNAME': 'cltbld', 22:33:44 INFO - 'MAIL': '/var/mail/cltbld', 22:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:44 INFO - 'MOZ_NO_REMOTE': '1', 22:33:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:44 INFO - 'NO_EM_RESTART': '1', 22:33:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:44 INFO - 'PWD': '/builds/slave/test', 22:33:44 INFO - 'SHELL': '/bin/bash', 22:33:44 INFO - 'SHLVL': '1', 22:33:44 INFO - 'TERM': 'linux', 22:33:44 INFO - 'TMOUT': '86400', 22:33:44 INFO - 'USER': 'cltbld', 22:33:44 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:44 INFO - '_': '/tools/buildbot/bin/python'} 22:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:44 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 22:33:44 INFO - Cleaning up... 22:33:44 INFO - Return code: 0 22:33:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:44 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:44 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 22:33:44 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 22:33:44 INFO - Using env: {'DISPLAY': ':0', 22:33:44 INFO - 'HOME': '/home/cltbld', 22:33:44 INFO - 'LANG': 'en_US.UTF-8', 22:33:44 INFO - 'LANGUAGE': 'en_US:en', 22:33:44 INFO - 'LOGNAME': 'cltbld', 22:33:44 INFO - 'MAIL': '/var/mail/cltbld', 22:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:44 INFO - 'MOZ_NO_REMOTE': '1', 22:33:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:44 INFO - 'NO_EM_RESTART': '1', 22:33:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:44 INFO - 'PWD': '/builds/slave/test', 22:33:44 INFO - 'SHELL': '/bin/bash', 22:33:44 INFO - 'SHLVL': '1', 22:33:44 INFO - 'TERM': 'linux', 22:33:44 INFO - 'TMOUT': '86400', 22:33:44 INFO - 'USER': 'cltbld', 22:33:44 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:44 INFO - '_': '/tools/buildbot/bin/python'} 22:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:44 INFO - Downloading/unpacking psutil>=3.1.1 22:33:44 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 22:33:44 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 22:33:44 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 22:33:44 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 22:33:44 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 22:33:44 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 22:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:33:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:46 INFO - Installing collected packages: psutil 22:33:46 INFO - Running setup.py install for psutil 22:33:46 INFO - building 'psutil._psutil_linux' extension 22:33:46 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 22:33:47 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 22:33:47 INFO - building 'psutil._psutil_posix' extension 22:33:47 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 22:33:47 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 22:33:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:47 INFO - Successfully installed psutil 22:33:47 INFO - Cleaning up... 22:33:47 INFO - Return code: 0 22:33:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:47 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:47 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 22:33:47 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 22:33:47 INFO - Using env: {'DISPLAY': ':0', 22:33:47 INFO - 'HOME': '/home/cltbld', 22:33:47 INFO - 'LANG': 'en_US.UTF-8', 22:33:47 INFO - 'LANGUAGE': 'en_US:en', 22:33:47 INFO - 'LOGNAME': 'cltbld', 22:33:47 INFO - 'MAIL': '/var/mail/cltbld', 22:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:47 INFO - 'MOZ_NO_REMOTE': '1', 22:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:47 INFO - 'NO_EM_RESTART': '1', 22:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:47 INFO - 'PWD': '/builds/slave/test', 22:33:47 INFO - 'SHELL': '/bin/bash', 22:33:47 INFO - 'SHLVL': '1', 22:33:47 INFO - 'TERM': 'linux', 22:33:47 INFO - 'TMOUT': '86400', 22:33:47 INFO - 'USER': 'cltbld', 22:33:47 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:47 INFO - '_': '/tools/buildbot/bin/python'} 22:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:33:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:33:49 INFO - Installing collected packages: mozsystemmonitor 22:33:49 INFO - Running setup.py install for mozsystemmonitor 22:33:49 INFO - Successfully installed mozsystemmonitor 22:33:49 INFO - Cleaning up... 22:33:49 INFO - Return code: 0 22:33:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:33:49 INFO - Using env: {'DISPLAY': ':0', 22:33:49 INFO - 'HOME': '/home/cltbld', 22:33:49 INFO - 'LANG': 'en_US.UTF-8', 22:33:49 INFO - 'LANGUAGE': 'en_US:en', 22:33:49 INFO - 'LOGNAME': 'cltbld', 22:33:49 INFO - 'MAIL': '/var/mail/cltbld', 22:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:49 INFO - 'MOZ_NO_REMOTE': '1', 22:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:49 INFO - 'NO_EM_RESTART': '1', 22:33:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:49 INFO - 'PWD': '/builds/slave/test', 22:33:49 INFO - 'SHELL': '/bin/bash', 22:33:49 INFO - 'SHLVL': '1', 22:33:49 INFO - 'TERM': 'linux', 22:33:49 INFO - 'TMOUT': '86400', 22:33:49 INFO - 'USER': 'cltbld', 22:33:49 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:49 INFO - '_': '/tools/buildbot/bin/python'} 22:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:49 INFO - Downloading/unpacking blobuploader==1.2.4 22:33: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 22:33: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 22:33: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 22:33: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 22:33: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 22:33: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 22:33:51 INFO - Downloading blobuploader-1.2.4.tar.gz 22:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:33:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:33:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:52 INFO - Downloading docopt-0.6.1.tar.gz 22:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:33:52 INFO - Installing collected packages: blobuploader, requests, docopt 22:33:52 INFO - Running setup.py install for blobuploader 22:33:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:33:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:33:52 INFO - Running setup.py install for requests 22:33:52 INFO - Running setup.py install for docopt 22:33:53 INFO - Successfully installed blobuploader requests docopt 22:33:53 INFO - Cleaning up... 22:33:53 INFO - Return code: 0 22:33:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:53 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:53 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 22:33:53 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 22:33:53 INFO - Using env: {'DISPLAY': ':0', 22:33:53 INFO - 'HOME': '/home/cltbld', 22:33:53 INFO - 'LANG': 'en_US.UTF-8', 22:33:53 INFO - 'LANGUAGE': 'en_US:en', 22:33:53 INFO - 'LOGNAME': 'cltbld', 22:33:53 INFO - 'MAIL': '/var/mail/cltbld', 22:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:53 INFO - 'MOZ_NO_REMOTE': '1', 22:33:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:53 INFO - 'NO_EM_RESTART': '1', 22:33:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:53 INFO - 'PWD': '/builds/slave/test', 22:33:53 INFO - 'SHELL': '/bin/bash', 22:33:53 INFO - 'SHLVL': '1', 22:33:53 INFO - 'TERM': 'linux', 22:33:53 INFO - 'TMOUT': '86400', 22:33:53 INFO - 'USER': 'cltbld', 22:33:53 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:53 INFO - '_': '/tools/buildbot/bin/python'} 22:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:33:53 INFO - Running setup.py (path:/tmp/pip-2iJGPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:33:53 INFO - Running setup.py (path:/tmp/pip-4AobIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:33:53 INFO - Running setup.py (path:/tmp/pip-EkOFdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:33:53 INFO - Running setup.py (path:/tmp/pip-t7UnON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:33:53 INFO - Running setup.py (path:/tmp/pip-y9dJYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:33:53 INFO - Running setup.py (path:/tmp/pip-37YS6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:33:53 INFO - Running setup.py (path:/tmp/pip-6vNtQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:33:53 INFO - Running setup.py (path:/tmp/pip-HxEGyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:33:53 INFO - Running setup.py (path:/tmp/pip-Q9ymqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:33:54 INFO - Running setup.py (path:/tmp/pip-cJ1bFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:33:54 INFO - Running setup.py (path:/tmp/pip-Q9LdOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:33:54 INFO - Running setup.py (path:/tmp/pip-9tMdXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:33:54 INFO - Running setup.py (path:/tmp/pip-8cNIS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:33:54 INFO - Running setup.py (path:/tmp/pip-nB40Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:54 INFO - Running setup.py (path:/tmp/pip-lbgLA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:33:54 INFO - Running setup.py (path:/tmp/pip-IxMTIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:33:54 INFO - Running setup.py (path:/tmp/pip-86Pei1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:33:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:33:54 INFO - Running setup.py install for manifestparser 22:33:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:33:54 INFO - Running setup.py install for mozcrash 22:33:54 INFO - Running setup.py install for mozdebug 22:33:54 INFO - Running setup.py install for mozdevice 22:33:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:33:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozfile 22:33:55 INFO - Running setup.py install for mozhttpd 22:33:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozinfo 22:33:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozInstall 22:33:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozleak 22:33:55 INFO - Running setup.py install for mozlog 22:33:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for moznetwork 22:33:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozprocess 22:33:55 INFO - Running setup.py install for mozprofile 22:33:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:33:55 INFO - Running setup.py install for mozrunner 22:33:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:33:56 INFO - Running setup.py install for mozscreenshot 22:33:56 INFO - Running setup.py install for moztest 22:33:56 INFO - Running setup.py install for mozversion 22:33:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:33:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:33:56 INFO - Cleaning up... 22:33:56 INFO - Return code: 0 22:33:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33: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')]} 22:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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')]} 22:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:56 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:33:56 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 22:33:56 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 22:33:56 INFO - Using env: {'DISPLAY': ':0', 22:33:56 INFO - 'HOME': '/home/cltbld', 22:33:56 INFO - 'LANG': 'en_US.UTF-8', 22:33:56 INFO - 'LANGUAGE': 'en_US:en', 22:33:56 INFO - 'LOGNAME': 'cltbld', 22:33:56 INFO - 'MAIL': '/var/mail/cltbld', 22:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:56 INFO - 'MOZ_NO_REMOTE': '1', 22:33:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:56 INFO - 'NO_EM_RESTART': '1', 22:33:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:56 INFO - 'PWD': '/builds/slave/test', 22:33:56 INFO - 'SHELL': '/bin/bash', 22:33:56 INFO - 'SHLVL': '1', 22:33:56 INFO - 'TERM': 'linux', 22:33:56 INFO - 'TMOUT': '86400', 22:33:56 INFO - 'USER': 'cltbld', 22:33:56 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:56 INFO - '_': '/tools/buildbot/bin/python'} 22:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:33:56 INFO - Running setup.py (path:/tmp/pip-lX3fCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:33:56 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)) 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:33:56 INFO - Running setup.py (path:/tmp/pip-evE7LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:33:56 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)) 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:33:56 INFO - Running setup.py (path:/tmp/pip-Bre4K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:33:56 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)) 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:33:56 INFO - Running setup.py (path:/tmp/pip-yJD5CZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:33:56 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)) 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:33:56 INFO - Running setup.py (path:/tmp/pip-aRBLPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:33:56 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)) 22:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:33:56 INFO - Running setup.py (path:/tmp/pip-Oz6Zxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:33:57 INFO - Running setup.py (path:/tmp/pip-4hamuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:33:57 INFO - Running setup.py (path:/tmp/pip-QuODHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:33:57 INFO - Running setup.py (path:/tmp/pip-plWRgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:33:57 INFO - Running setup.py (path:/tmp/pip-xaF331-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:33:57 INFO - Running setup.py (path:/tmp/pip-31NIox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:33:57 INFO - Running setup.py (path:/tmp/pip-OYfuJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:33:57 INFO - Running setup.py (path:/tmp/pip-wRTrcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:33:57 INFO - Running setup.py (path:/tmp/pip-qE9LM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:57 INFO - Running setup.py (path:/tmp/pip-IJnRSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:33:57 INFO - Running setup.py (path:/tmp/pip-GJr5GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:33:57 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)) 22:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:33:57 INFO - Running setup.py (path:/tmp/pip-yNvhkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:33:57 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)) 22:33:57 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)) 22:33:57 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)) 22:33:57 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)) 22:33:57 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)) 22:33:57 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)) 22:33:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:59 INFO - Downloading blessings-1.6.tar.gz 22:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:33:59 INFO - Installing collected packages: blessings 22:33:59 INFO - Running setup.py install for blessings 22:33:59 INFO - Successfully installed blessings 22:33:59 INFO - Cleaning up... 22:33:59 INFO - Return code: 0 22:33:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:33:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:33:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:00 INFO - Reading from file tmpfile_stdout 22:34:00 INFO - Current package versions: 22:34:00 INFO - argparse == 1.2.1 22:34:00 INFO - blessings == 1.6 22:34:00 INFO - blobuploader == 1.2.4 22:34:00 INFO - docopt == 0.6.1 22:34:00 INFO - manifestparser == 1.1 22:34:00 INFO - mozInstall == 1.12 22:34:00 INFO - mozcrash == 0.17 22:34:00 INFO - mozdebug == 0.1 22:34:00 INFO - mozdevice == 0.48 22:34:00 INFO - mozfile == 1.2 22:34:00 INFO - mozhttpd == 0.7 22:34:00 INFO - mozinfo == 0.9 22:34:00 INFO - mozleak == 0.1 22:34:00 INFO - mozlog == 3.2 22:34:00 INFO - moznetwork == 0.27 22:34:00 INFO - mozprocess == 0.22 22:34:00 INFO - mozprofile == 0.28 22:34:00 INFO - mozrunner == 6.11 22:34:00 INFO - mozscreenshot == 0.1 22:34:00 INFO - mozsystemmonitor == 0.3 22:34:00 INFO - moztest == 0.7 22:34:00 INFO - mozversion == 1.4 22:34:00 INFO - psutil == 3.1.1 22:34:00 INFO - requests == 1.2.3 22:34:00 INFO - wsgiref == 0.1.2 22:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:34:00 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 22:34:00 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 22:34:00 INFO - Using env: {'DISPLAY': ':0', 22:34:00 INFO - 'HOME': '/home/cltbld', 22:34:00 INFO - 'LANG': 'en_US.UTF-8', 22:34:00 INFO - 'LANGUAGE': 'en_US:en', 22:34:00 INFO - 'LOGNAME': 'cltbld', 22:34:00 INFO - 'MAIL': '/var/mail/cltbld', 22:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:00 INFO - 'MOZ_NO_REMOTE': '1', 22:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:00 INFO - 'NO_EM_RESTART': '1', 22:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:00 INFO - 'PWD': '/builds/slave/test', 22:34:00 INFO - 'SHELL': '/bin/bash', 22:34:00 INFO - 'SHLVL': '1', 22:34:00 INFO - 'TERM': 'linux', 22:34:00 INFO - 'TMOUT': '86400', 22:34:00 INFO - 'USER': 'cltbld', 22:34:00 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:00 INFO - '_': '/tools/buildbot/bin/python'} 22:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 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)) 22:34:00 INFO - Cleaning up... 22:34:00 INFO - Return code: 0 22:34:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:34:00 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 22:34:00 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 22:34:00 INFO - Using env: {'DISPLAY': ':0', 22:34:00 INFO - 'HOME': '/home/cltbld', 22:34:00 INFO - 'LANG': 'en_US.UTF-8', 22:34:00 INFO - 'LANGUAGE': 'en_US:en', 22:34:00 INFO - 'LOGNAME': 'cltbld', 22:34:00 INFO - 'MAIL': '/var/mail/cltbld', 22:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:00 INFO - 'MOZ_NO_REMOTE': '1', 22:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:00 INFO - 'NO_EM_RESTART': '1', 22:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:00 INFO - 'PWD': '/builds/slave/test', 22:34:00 INFO - 'SHELL': '/bin/bash', 22:34:00 INFO - 'SHLVL': '1', 22:34:00 INFO - 'TERM': 'linux', 22:34:00 INFO - 'TMOUT': '86400', 22:34:00 INFO - 'USER': 'cltbld', 22:34:00 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:00 INFO - '_': '/tools/buildbot/bin/python'} 22:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:00 INFO - Downloading/unpacking jsonschema 22:34:00 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 22:34:00 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 22:34:00 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 22:34:00 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 22:34:00 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 22:34:00 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 22:34:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:34:02 INFO - Downloading/unpacking functools32 (from jsonschema) 22:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:34:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:34:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:34:02 INFO - Downloading functools32-3.2.3-2.tar.gz 22:34:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:34:02 INFO - warning: no files found matching '*.txt' 22:34:02 INFO - no previously-included directories found matching 'build' 22:34:02 INFO - no previously-included directories found matching 'dist' 22:34:02 INFO - no previously-included directories found matching '.git*' 22:34:02 INFO - Installing collected packages: jsonschema, functools32 22:34:02 INFO - Running setup.py install for functools32 22:34:02 INFO - warning: no files found matching '*.txt' 22:34:02 INFO - no previously-included directories found matching 'build' 22:34:02 INFO - no previously-included directories found matching 'dist' 22:34:02 INFO - no previously-included directories found matching '.git*' 22:34:02 INFO - Successfully installed jsonschema functools32 22:34:02 INFO - Cleaning up... 22:34:03 INFO - Return code: 0 22:34:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:34: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')]} 22:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34: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')]} 22:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:03 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 0x7fd32e04bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b098e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, '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': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', '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 22:34:03 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 22:34:03 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 22:34:03 INFO - Using env: {'DISPLAY': ':0', 22:34:03 INFO - 'HOME': '/home/cltbld', 22:34:03 INFO - 'LANG': 'en_US.UTF-8', 22:34:03 INFO - 'LANGUAGE': 'en_US:en', 22:34:03 INFO - 'LOGNAME': 'cltbld', 22:34:03 INFO - 'MAIL': '/var/mail/cltbld', 22:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:03 INFO - 'MOZ_NO_REMOTE': '1', 22:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:03 INFO - 'NO_EM_RESTART': '1', 22:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:03 INFO - 'PWD': '/builds/slave/test', 22:34:03 INFO - 'SHELL': '/bin/bash', 22:34:03 INFO - 'SHLVL': '1', 22:34:03 INFO - 'TERM': 'linux', 22:34:03 INFO - 'TMOUT': '86400', 22:34:03 INFO - 'USER': 'cltbld', 22:34:03 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:03 INFO - '_': '/tools/buildbot/bin/python'} 22:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:03 INFO - Downloading/unpacking flake8 22:34:03 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 22:34:03 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 22:34:03 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 22:34:03 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 22:34:03 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 22:34:03 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 22:34:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:34:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:34:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:34:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:34:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:34:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:34:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:34:05 INFO - no previously-included directories found matching 'docs/_build' 22:34:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:34:05 INFO - Running setup.py install for pep8 22:34:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:34:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:34:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:34:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:34:05 INFO - no previously-included directories found matching 'docs/_build' 22:34:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:34:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:34:06 INFO - Cleaning up... 22:34:06 INFO - Return code: 0 22:34:06 INFO - Running post-action listener: _resource_record_post_action 22:34:06 INFO - Running post-action listener: _start_resource_monitoring 22:34:06 INFO - Starting resource monitoring. 22:34:06 INFO - ##### 22:34:06 INFO - ##### Finished create-virtualenv step (success) 22:34:06 INFO - ##### 22:34:06 INFO - ##### 22:34:06 INFO - ##### Running install step. 22:34:06 INFO - ##### 22:34:06 INFO - Running pre-action listener: _resource_record_pre_action 22:34:06 INFO - Running main action method: install 22:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:06 INFO - Reading from file tmpfile_stdout 22:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:34:06 INFO - Reading from file tmpfile_stdout 22:34:06 INFO - Output received: 22:34:06 INFO - Usage: mozinstall [options] installer 22:34:06 INFO - Options: 22:34:06 INFO - -h, --help show this help message and exit 22:34:06 INFO - -d DEST, --destination=DEST 22:34:06 INFO - Directory to install application into. [default: 22:34:06 INFO - "/builds/slave/test"] 22:34:06 INFO - --app=APP Application being installed. [default: firefox] 22:34:06 INFO - mkdir: /builds/slave/test/build/application 22:34:06 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'] 22:34:06 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 22:34:22 INFO - Reading from file tmpfile_stdout 22:34:22 INFO - Output received: 22:34:22 INFO - /builds/slave/test/build/application/firefox/firefox 22:34:22 INFO - Running post-action listener: _resource_record_post_action 22:34:22 INFO - ##### 22:34:22 INFO - ##### Finished install step (success) 22:34:22 INFO - ##### 22:34:22 INFO - ##### 22:34:22 INFO - ##### Running run-tests step. 22:34:22 INFO - ##### 22:34:22 INFO - Running pre-action listener: _resource_record_pre_action 22:34:22 INFO - Running main action method: run_tests 22:34:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:34:22 INFO - Python 2.7.3 22:34:22 INFO - Return code: 0 22:34:22 INFO - grabbing minidump binary from tooltool 22:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:22 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 0x1b98b20>, '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 0x1b99a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b99eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:34:22 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 22:34:22 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 22:34:22 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 22:34:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:34:22 INFO - Return code: 0 22:34:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:34:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:34:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:34:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:34:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:34:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:34:23 INFO - Return code: 0 22:34:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 22:34:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 22:34:23 INFO - Using env: {'DISPLAY': ':0', 22:34:23 INFO - 'HOME': '/home/cltbld', 22:34:23 INFO - 'LANG': 'en_US.UTF-8', 22:34:23 INFO - 'LANGUAGE': 'en_US:en', 22:34:23 INFO - 'LOGNAME': 'cltbld', 22:34:23 INFO - 'MAIL': '/var/mail/cltbld', 22:34:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:34:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:23 INFO - 'MOZ_NO_REMOTE': '1', 22:34:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:23 INFO - 'NO_EM_RESTART': '1', 22:34:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:23 INFO - 'PWD': '/builds/slave/test', 22:34:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:34:23 INFO - 'SHELL': '/bin/bash', 22:34:23 INFO - 'SHLVL': '1', 22:34:23 INFO - 'TERM': 'linux', 22:34:23 INFO - 'TMOUT': '86400', 22:34:23 INFO - 'USER': 'cltbld', 22:34:23 INFO - 'XDG_SESSION_COOKIE': 'ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475', 22:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:23 INFO - '_': '/tools/buildbot/bin/python'} 22:34:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 22:34:23 INFO - mozversion application_buildid: 20160520212034 22:34:23 INFO - mozversion application_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 22:34:23 INFO - mozversion application_display_name: Nightly 22:34:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:34:23 INFO - mozversion application_name: Firefox 22:34:23 INFO - mozversion application_remotingname: firefox 22:34:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:34:23 INFO - mozversion application_vendor: Mozilla 22:34:23 INFO - mozversion application_version: 49.0a1 22:34:23 INFO - mozversion platform_buildid: 20160520212034 22:34:23 INFO - mozversion platform_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 22:34:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:34:23 INFO - mozversion platform_version: 49.0a1 22:34:23 INFO - using testdate: 1463808863 22:34:23 INFO - actual date: 1463808863 22:34:23 INFO - starting webserver on 'localhost:57712' 22:34:23 INFO - SUITE-START | Running 2 tests 22:34:23 INFO - TEST-START | dromaeo_css 22:34:23 INFO - operating with platform_type : linux_ 22:34:23 INFO - Initialising browser for dromaeo_css test... 22:34:23 INFO - TEST-INFO | started process 29348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGzzgon/profile http://localhost:57712/getInfo.html) 22:34:27 INFO - PROCESS | 29348 | __metrics Screen width/height:1600/1200 22:34:27 INFO - PROCESS | 29348 | colorDepth:24 22:34:27 INFO - PROCESS | 29348 | Browser inner width/height: 1024/697 22:34:27 INFO - PROCESS | 29348 | __metrics 22:34:28 ERROR - PROCESS | 29348 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:34:29 INFO - TEST-INFO | 29348: exit 0 22:34:29 INFO - Browser initialized. 22:34:29 INFO - Running cycle 1/1 for dromaeo_css test... 22:34:29 INFO - TEST-INFO | started process 29429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGzzgon/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:34:41 INFO - PROCESS | 29429 | RSS: Main: 155136000 22:34:41 INFO - PROCESS | 29429 | 22:35:33 INFO - PROCESS | 29429 | 1463808933050 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] 22:37:44 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57712/tests/dromaeo/cssquery-ext.html) 22:37:45 INFO - PROCESS | 29429 | RSS: Main: 235728896 22:37:45 INFO - PROCESS | 29429 | 22:40:48 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-ext.html (next: http://localhost:57712/tests/dromaeo/cssquery-jquery.html) 22:40:48 INFO - PROCESS | 29429 | RSS: Main: 238686208 22:40:48 INFO - PROCESS | 29429 | 22:43:50 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57712/tests/dromaeo/cssquery-mootools.html) 22:43:51 INFO - PROCESS | 29429 | RSS: Main: 235241472 22:43:51 INFO - PROCESS | 29429 | 22:46:53 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57712/tests/dromaeo/cssquery-prototype.html) 22:46:54 INFO - PROCESS | 29429 | RSS: Main: 235741184 22:46:54 INFO - PROCESS | 29429 | 22:49:57 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57712/tests/dromaeo/cssquery-yui.html) 22:49:57 INFO - PROCESS | 29429 | RSS: Main: 219447296 22:49:57 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | Cycle 1(1): loaded http://localhost:57712/tests/dromaeo/cssquery-yui.html (next: http://localhost:57712/tests/dromaeo/cssquery-dojo.html) 22:53:00 INFO - PROCESS | 29429 | RSS: Main: 256593920 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | __start_tp_report 22:53:00 INFO - PROCESS | 29429 | _x_x_mozilla_page_load 22:53:00 INFO - PROCESS | 29429 | _x_x_mozilla_page_load_details 22:53:00 INFO - PROCESS | 29429 | |i|pagename|runs| 22:53:00 INFO - PROCESS | 29429 | |0;dojo.html;2670.6661667291587;2673.438577898641;2697.056030389364;2711.7152698966606;2716.2801857507766;4657.976710116453;4687.273472611746;4714.622830173586;4778.4504781950045;4797.54423329784;4623.861284161472;4659.114768194049;4666.859994200171;4673.392458980336;4677.53224677532;4105.199486100211;4193.538710741824;4262.041040765818;4347.304431290994;4498.775061246941;4401.05377343871;4444.000099977496;4464.620507256871;4464.843730469434;4486.371907932892;4213.241616509027;4251.128518653669;4320.978395108035;4361.367601697736;4363.149185908749;4304;4321.611055005064;4322.222000039991;4365.43249377582;4388.1223755249075;4337.436133302682;4362.280223763075;4368.759718215497;4404.207242696313;4411.316245981878;4560.794764235584;4611.861644150681;4612.654050946193;4668.183067963093;4669.766511674403;5137.280780690707;5157.664751791159;5166.560842328407;5166.664166829144;5167.844964651066;5127.435982041972;5182.818601348972;5191.688498690053;5200.895982080375;5205.141151709963;4632.837850675211;4691.577758001728;4746.145693775154;4748.905021899543;4769.42766867978;5240.659357141773;5242.370915490165;5274.393444753902;5274.868128296824;5275.340582427197;4857.587105096107;5012.097822391932;5016.648834581617;5060.873478163076;5065.949340506548;5000.39995200571;5076.3908330999775;5081.517255860687;5105.540501354822;5121.2574176744165;4972.204447288416;4982;4989;5001.724905130252;5032.194848824171;4945.2582112683385;4959.578435832923;4963.875903102451;4972.453030166679;5020.548150666385;4288.764117973541;4315.136972605492;4319.07139964909;4338.219120558329;4370.825166993348;3750.1937083527173;3776.527934008249;3786.2995345860554;3818.770873747584;3833.1183827719224;4215.241256573785;4257.74453532789;4290.227759003409;4336.176126535948;4342.544032876503;5070.2141168119;5111.693298402108;5122.974385128051;5133.255677926754;5172.224166375074;4341.696081274281;4346.543612920661;4346.847860324873;4386.363977223285;4393.0994146199955;4419.160359531678;4482.350059241392;4507;4574;4582;4763.404574428197;4776.952230477651;4778.689385189951;4819.325294458778;4825.203841366135;5092.287079808903;5098.7705553250935;5099.821506247264;5100.719460429712;5107.080725469303;4680.297955306595;4704.811807527661;4762;4769.833055843168;4815.879603009953;4983.152864013054;5000;5003.4246061701915;5005.62457815658;5052.9747351263;5054.393472783289;5083.440821509705;5086.313347698014;5090.516400941905;5137.588992880485;4792.353032340589;4861.052094841472;4929.285253638274;4930.260460930889;4966.751662416937;4927.039227350632;4959.801607935642;4964.602831773522;4964.801407943642;5003.899922001467;4750.239961606127;4887.095887260868;4891.290762839297;4896.2410826321975;4942.4069111705385;4916.237983112624;4950.9257361140335;4955.108080545537;4976.154053810933;4994.1759609663995;4873.049755297683;4876.244182151772;4886.315915771866;4892.877678058077;4928.063667903087 22:53:00 INFO - PROCESS | 29429 | |1;ext.html;598.5570677698504;599.2688919518187;606.1544145916444;606.996965015175;621.2296752027485;40239.39640905385;41015.58984410159;41087.58912410875;44136.77931610346;44579;40390.59609403905;40978.18043639125;41052.38421423681;41147;41202;7690.884636730453;7799.454038217327;7826.295633392993;7842.882356764653;7878.566678832662;7643.541387516767;7749.806254843617;7768.922310776877;7771.339436147934;7773.144954055049;7750.7674769756995;7855.371570274393;7877.212278772135;7889.5266284022855;7907.960460197691;7825.960870195641;7874.330681892046;7886.290233878978;7891.921080789233;7893;7597.126330472011;7701.884471732928;7713.807154821175;7715.614219289013;7721;37621.81189094064;38019.619803801885;38035.809820950715;38076.619233807585;38132.23735525274;3054.282243672735;3193.8722451101926;3577.5349204603235;3784.0918179636965;3807.6382743639588;29249.70750292491;29290.853545732134;29409.55885661717;29480.705192948226;30269;3503.789772613651;3565.447355659877;3565.5186549815703;3565.6612621800887;3591.3535563598543;257911;259916;259944;260117.69941150362;260126;52929;52947.73526132422;53259.46740532623;53276;53359.73320133452;47947.280790788165;56429.71785141048;57633.423665762806;57735;63348.36651633517;11887.88112118885;12115.091368147425;12141.575044873387;12153.635390938285;12186.390680465942;7576.318131368093;8662;8738.475691458532;8788.472691638523;8818.853549038713;15419.768703469457;15906.443274485337;16022.359105635876;16062.43781467643;16101.436449724204;6527.640979746159;6585.934140658628;6620.63586502747;6744;6754.8649027020165;802.1056521977953;840.6679361652235;893.2228960804142;915.9358844880794;995.0447570332561;27106.457870842867;27678.169654910384;28465.146045618665;28667.856660716563;28734.425311494073;1002.8144793213279;1017.6183931025865;1043.8642976413018;1046.3669479964665;1087.396494945297;14509.927450362891;14951;14993.925030374996;15316.846831531764;15332.233388330538;370223;373197;376127.11936440144;378863;378950;49026.26460603094;49080;49190.262146067835;49214.753926230136;49231.26153107706;1086.3536195963388;1211.3337664284784;1220.4020030185145;1224.4918358880964;1238.0652607281625;27102;28684.13947581576;28860.134195974155;28908.277293067;29143.417131657672;40974.1805163901;41485.58514414938;41530.1693966125;41620.375694364004;41708.58291417047;294957;296388;297059;297920;298372;29066.27334316659;29403.264918377212;35466.46800297946;35637.64362356343;40014.799926001346;41294.38058429066;41507.16985660209;41719.374209386275;42344.36483452689;42510.36234456424;34021.65978340185;35125.64874351224;36162.638373615926;36199.09502262464;36387.63612363948;35335.64664353323;35607.64392356147;35681.82159089188;35758;36104.819475902455;34525.30949380948;35129.64870351264;35464.64535354613;35775;35816.64183358133;31132;32418.351632966736;32478.675213247567;32788.344233115684;32843.343133136725;24193.39516512101;24890.75109248957;24955.500889981737;25241.24276271715;25389.11138110157 22:53:00 INFO - PROCESS | 29429 | |2;jquery.html;368.5540495999839;374.2384248055207;374.4215187535258;381.38406473545234;386.6945113360445;15969.281382337793;17224;17462.73805892911;17750.55623609407;17866.553336166588;10593.205509586773;11193.832092518618;11223.719407014809;11497.770044599103;11597;4426.490953540336;4633.189191891414;5235.816746413857;5237.659552129118;5244.39689435716;5014.498550144975;5166.689998600077;5172.560332371753;5189.325387699594;5209.270702101709;5037.395512538482;5069.873253168682;5144.048351055048;5160.458151894053;5160.587153027749;5068.645194836381;5115.104856650071;5118.948810511922;5122.897542049157;5158.3552055993005;4482.932756008662;4622.445306563201;4626.676132670715;4640.257558790578;4664.416947881515;12598.622041338775;12937.870621293854;15812;20010.69983950242;20257.594848102955;1961.08809403628;1979.218208807513;1983.8809671419617;2072.7409073865765;2415.1546958564536;9504.667336643184;9725.65960191397;9940.10539051489;11244.437778111062;12734.872651273461;1735.973960390595;1741.3992172700525;1835.7429959805638;1851.6389304085708;1867.057136146251;67163;71150;73116.26883731148;73351.63324183399;74668.62665686637;10062.094411503;10185.745356366002;10228.232882533839;10316.535755891004;10373.948130259301;14454.494092706705;14567.635809104857;14581.052231605125;14581.125132492085;14589.343479543446;6447.226332839996;6482.254540727782;6500.219973603103;6551.312112228243;6599.76900808479;5768.105943578752;5811.360750317462;5906.26171728534;5918.615290006136;5921.644701317935;4341.34879768031;4436.578525040116;4444.422225110715;4522.88692782683;4535.818567257273;1810.2939853457162;1847.7320788485595;1879.0980329441945;1879.821416965386;1913.6172765446688;821.5850995247481;823.1562648285436;865.6191275838611;880.6433394475248;903.3405613901889;15966.36134554628;16279.755803662954;16440.177991100398;16497.505074847773;17076.402325918534;877.9341549383822;881.9162179592928;907.5416914458289;909.7998440343114;918.9586468608928;9017.594208260643;9506.904930950603;9603.519824008772;10385.688429347132;10403.739906502398;407456;408053;409061;411463;418850;79630.6018469904;81058;81789.18210817935;81908.59045704734;83106.58446707728;945.4280160502934;960.188640598693;965.5075911285155;978.7895602445366;981.5975450065582;15336.463223787114;15752.763708544151;15938.203089845692;16199.190040498164;16209.189540522691;35559.82220088883;36487.45268820917;36653;38113.80943095267;38589.80705096456;44445.77777111094;45367.31949020701;53958.730206348715;54304.18543721926;54351;7342.118945726547;7403.333699967029;7510.39916806665;7528.096628404626;7700.5764682940735;10718.303310284806;10974.506147223554;11174.329540227613;11226.101911846868;11440.542378304775;8732.345074119594;9503.23974082058;9515.33392662507;9551.331406801457;9656.130948214695;9430.764230894283;9627.277954153327;9725.756856078655;9875.506224688881;10016.148627366756;9360.906390936003;9408.388454750419;9694.127528522185;9873.68222984779;10037.799244015225;6503.349665033648;6692.933070669231;6791.592504449749;6948.444124470131;7680.423968202519;9305.534723263945;9607.327487076118;9622.615095396106;9956.701298961043;10175.982401759764 22:53:00 INFO - PROCESS | 29429 | |3;mootools.html;1231.5812623707948;1398.3357904995125;1420.1266221273916;1546.4587394411958;1761.5596100974765;6513.8371540711505;7180.964095179524;7373.815654608624;7393.371563417116;7554.92445075549;7281.672324745399;7432.628368581562;7434;7445.181030086686;7447.366973807219;2157.0832396231617;2180.760116387196;2183.465051062492;2184.159098746978;2185.0276626901036;2122.246602456129;2152.3435352217566;2171.554831259588;2172.2071443922932;2177.6298029335053;2139.411661793004;2145.174107968428;2151.3115802943066;2153.54775497145;2201.9009144588454;2074.4502706782714;2079.46973521751;2083.1667333066744;2089.9373018809383;2090.1221486975505;2060.1553363121197;2133.136079887648;2141.6145093883097;2150.3978885911815;2151.107290474451;7299.5620262784405;7379.6679149438405;7430.14553326369;7437.888431673529;7442.18136005039;4298.914021719548;4472.753998530079;4476.574725401082;4477.597016268552;4482.798274077674;2960.067578712699;2970.0792754246036;2989.372231831318;3016.9849150754108;3018.6981301869855;891.3804905590617;911.5806728904712;913.6984795017695;914.29599208609;928.2063835420686;9977.850332245021;9989.700308990741;10003.099721025143;10028.047335503188;10042.949785251027;3894.883153505399;3920.2355540669296;4017.9397309040387;4019.0153412414147;4020.115574573589;7887.250711182429;7905.683772649029;7923.326517246099;7923.7226697065325;7925.365970722329;4292.420523229386;4296.914061718748;4302.333138363559;4308.18144552534;4309.396684464177;3542.6457354264367;3783.848646054182;3837.501124853807;3838.213166300902;4005.118873847749;3328.73370130389;3371.8145501997624;3438.0889064397957;3458.75788694794;3559.1991801844897;996.1482932092956;1008.4302369161547;1018.4805749068029;1019.7909428567126;1023.3399457349967;842.4103127810557;845.9450135741157;847.5719761520393;868.9608967596473;883.7923088074298;4923;4956.430010548761;5053.722045287471;5127.461616530285;5210.062188806051;392.75256588349157;396.179907591289;398.50784281412433;399.594411672153;414.241937254823;3545.7517973742106;3551.8046507441823;3612.8012959287494;3619.8552057917914;3712.0348709335767;9280.953595232115;9674;9780.755481113029;9781.46201958885;9785.168260697921;9594.088561586637;9657.758556036155;9674.03259674041;9684.128428441474;9694.9030509694;6955.582665040114;7228.602426866468;7232.92767072333;7350.63246837656;7355.190928997806;5131.538161565477;5143.331366922276;5284.339457567804;5337.066013447708;5443.020256353736;8552.102029287083;8798.736037918872;8820.867686984571;8841.204291613529;8854.734357969272;9055.773605659911;9196.908030919605;9226.677066302647;9251.907480925105;9386.67146649864;6063.454289114001;6280.183576135073;6464.4828413725845;6465.579737317059;6606.6036037837885;7391.1500177480275;7408.37028852532;7458.664360103699;7460.253974602496;7463.290987356192;6520.576162549419;6733.831654208684;6796.218434880052;6884.311568843075;6892.345227203407;6501.122348483083;6554.4756419487285;6597.637129957707;6632;6698.162729658793;6312.873742525216;6319.6840157991;6385;6388.872222555616;6393.616383016848;4610.585047345621;4641.489436162087;4676.836310729108;4755.976220118877;4786.210275304536;4205.285101532808;4522.4346956630425;4546.931796023128;4608.631309495166;4609.193391156601 22:53:00 INFO - PROCESS | 29429 | |4;prototype.html;284.77787325885816;294.18364039789583;300.88115194498187;301.67419187277744;301.91848200985737;4553.612942899849;4687.015726697389;4733.928991065132;4734.4792072872;4753.833615823447;4560.9087818243615;4733.502982186864;4758.976205118978;4774.570288674018;4779.8805029874275;3723.422869455239;3740.607236240183;3743.5133432653715;3749.650125954654;3758.7556808807476;3533.816171582523;3564.750467467278;3704.2961837250837;3739.9341187761493;3745.700343972476;3723.3989384564625;3726.751538234681;3732.059329148841;3737.4767532545466;3752.3620984432714;3666.8716594919315;3675.375186218336;3695.4087346024776;3728.322254985254;3733.4773131761576;3289.3585750778607;3301.636819949804;3318.668133186686;3323.88366407177;3325.451300535433;4341.327094300388;4480.33985378385;4489.887752806173;4490.326451032372;4499.6400287976885;4204.012057166564;4241.957580424218;4242.9787851060855;4256.361545768129;4299.141071187656;4748.667593268473;4897.118518666639;4902.7058376497525;4908.674657842363;4941.530554597343;3653.9634603654154;3655.214128962259;3660.68884144848;3669.94495082574;3675.3016926783826;5089.3638295213095;5128.461511541309;5138.229265610151;5142.691438513663;5171.431142574352;3744.2137151198554;3747.9063023424087;3765.4163604641326;3794.582595914447;3809.104860357814;3734.43153875373;3820.2741479118877;3829;3842.4620553122586;3860.3823388257742;4242.596953289432;4288.89277768052;4295.72077814941;4299.204647140289;4309.392817345523;4708.764561771898;4927.433345165352;4986.351774269322;5008.974955125201;5011.198208286657;1277.8530468995998;1319.577735124751;1320.7980737528803;1322.2529270961875;1338.4311667541451;851.3444647621297;851.8424091543085;866.5537248317122;873.6418068591847;881.1937077574077;3926.2736393767245;3974.4237085623;3985.27376902597;3987.501562304712;3997.360422332413;4495.460544734653;4520.751358675305;4543.909121817545;4550.88622784433;4587.3348364486965;3873.748206366577;3887.5334959805004;3989;3994.720369574102;3995.780232087205;2246.730392352928;2413.634638054716;2456.2140115162974;2457.63135529672;2476.86377249253;4294.432532125752;4304.289792184317;4345.717528360647;4400.163968845973;4405.700318406078;4558.361829343895;4574.862754117382;4612.423447069116;4630.861074167713;4653.906921861544;4120.835166593302;4244;4274.871753847327;4289.206496798102;4307.676924230633;4499.042916331407;4529.728216307032;4547.567516232376;4552.658550608651;4576.8626941191815;4444.177827101997;4491.742312152603;4491.955080449154;4498.460184777717;4504.369388285708;3970.1190644863827;4289.356596510548;4310.202612516695;4317.136572685413;4328.3074708047825;3448.706859916935;3481.9303613928087;3483.390406678872;3503.8072905990416;3504.6857428464327;4534.2971839364955;4585.77071146448;4592.563706447882;4595.494495605548;4921.121144130723;4752.667313288037;4938.827141050046;4959.553640172402;4977.328060711902;5012.370979431772;4955.5044495550155;4962.263207877214;4981.327520784647;4997.250412438163;5015.17249653803;4704.694194877322;4746.4822417984915;4888.1690112681645;4909.754512274443;4913.557779799835;4956.851294461172;4956.950430495793;4959.115271457684;4967.552920237196;5002.149634562061;4897.804087836446;4899.7260712214365;4917.336159618549;4918.631102667243;4933.136701077369 22:53:00 INFO - PROCESS | 29429 | |5;yui.html;662.3409707341197;669.9933000669994;771.3173841150583;774.1677696476289;775.806048487878;12028.518859245642;12071.939640301796;12193.1464797464;12224.205426647273;12544.498220071198;12221.572244971429;12613.621591352274;12933.41799619017;12955.805662915063;12967.481300747959;635.4916067146288;772.5326177662517;797.7686470923426;808.4502538273971;810.7973006748313;800.3197282310048;801.046754362307;802.3300544045729;803.6825453945688;804.1636697834246;838.2455789789177;844.0377969115183;847.076686411811;847.5719761520423;848.0459483081534;661.5699795133154;791.7347688524351;821.1131977464324;823.0288259853371;842.4313588327885;607.4138456389561;610.9755609775648;611.4222060153157;612.4640939178219;612.6875293600251;11121.944390278079;11243;11244.212905096647;11254.268472549258;11256.606018789304;11541.884581154165;11686.415679216005;11822.586209482626;11831.408429578572;11848.940755296255;10844.837327440095;10900.182486313559;11273.436328183558;11362.82955755656;11378.601748938747;4108.548059713429;4160.54234034256;4171.353440216772;4199.097194103252;4271.761189255112;44989.32516012262;45067;45118.09763804738;45123.097538049384;45159.3226101612;9707.271954603291;9715.9028409715;9846.852297215404;10243.282970192015;10245.334053286513;17536.386226482013;17797.822021779615;17823.10884455798;17823.821761782474;17829.46511604654;6402.455791257702;6454.128692626436;6481.902771458432;6523.160439037947;6841.62371069586;3578;3579.069441945061;3596.4425514045365;3613.674769270778;3668.2663467306647;2510.4853505031424;2626.185882376431;2630.8421494709996;2633.0784225520915;2633.2231991562535;393.48257041989694;491.3710450623208;498.29491269853474;507.54321110999655;510.8186593759162;602.6474512410289;608.9208402907682;611.3122736920964;612.7059011674421;620.6958590290725;8301.211384918422;8452;8486.660533578712;8496.872546911802;8637.568121593895;550.8705454218256;564.6301672404581;583.2446981159409;604.2356419129804;606.7815586388897;3894.182221733411;3926.0381206604566;3929.9214015719526;3972.3048466518244;3990.541087774943;60053.399466005656;60143.76118447995;61407.6929615358;61457;61460;55816.16275755786;56251.71874140603;56907.43092569104;57638.71180644154;58251;50313.49686503088;50367.74816125896;50421.49578504241;50646.49353506492;51697.99999999925;7849.646765895663;7896.32881205104;7978.84042319162;8014.278714915686;8175.67297308101;28379.432411351245;29545.556816648197;30922.53616195804;34187.829060854536;34964;39858.800705996284;39969.80015100022;40138;40199;40245.195096097326;8950.955245223733;10615.522301496605;10710.839337410102;11173.832392513956;11310.321380717183;9722;9807.460589667637;9876.555554999899;9904.455254961047;10110.140638045554;9656.565454554422;9765.707028789147;9878.802423951336;10102.545385457524;10207.7448063799;9405.746355757476;9445.905540944503;9557.092076252746;9697.127258546765;9813.803723925625;9112.316576256675;9112.31657625694;9281.303902207226;9449.858252126087;9451.291153163404;8362.540060296742;8373.37199710012;8374.665013399395;8484.109168537461;8688.78278043054;7741.458097933316;7773.68905243784;7949.284564389001;8006.799830004296;8352.457090289112 22:53:00 INFO - PROCESS | 29429 | __end_tp_report 22:53:00 INFO - PROCESS | 29429 | __start_cc_report 22:53:00 INFO - PROCESS | 29429 | _x_x_mozilla_cycle_collect,933 22:53:00 INFO - PROCESS | 29429 | __end_cc_report 22:53:00 INFO - PROCESS | 29429 | __startTimestamp1463809980584__endTimestamp 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | ------- Summary: start ------- 22:53:00 INFO - PROCESS | 29429 | Number of tests: 6 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#0] dojo.html Cycles:165 Average:4683.43 Median:4792.35 stddev:493.55 (10.3%) stddev-sans-first:469.12 22:53:00 INFO - PROCESS | 29429 | Values: 2670.7 2673.4 2697.1 2711.7 2716.3 4658.0 4687.3 4714.6 4778.5 4797.5 4623.9 4659.1 4666.9 4673.4 4677.5 4105.2 4193.5 4262.0 4347.3 4498.8 4401.1 4444.0 4464.6 4464.8 4486.4 4213.2 4251.1 4321.0 4361.4 4363.1 4304.0 4321.6 4322.2 4365.4 4388.1 4337.4 4362.3 4368.8 4404.2 4411.3 4560.8 4611.9 4612.7 4668.2 4669.8 5137.3 5157.7 5166.6 5166.7 5167.8 5127.4 5182.8 5191.7 5200.9 5205.1 4632.8 4691.6 4746.1 4748.9 4769.4 5240.7 5242.4 5274.4 5274.9 5275.3 4857.6 5012.1 5016.6 5060.9 5065.9 5000.4 5076.4 5081.5 5105.5 5121.3 4972.2 4982.0 4989.0 5001.7 5032.2 4945.3 4959.6 4963.9 4972.5 5020.5 4288.8 4315.1 4319.1 4338.2 4370.8 3750.2 3776.5 3786.3 3818.8 3833.1 4215.2 4257.7 4290.2 4336.2 4342.5 5070.2 5111.7 5123.0 5133.3 5172.2 4341.7 4346.5 4346.8 4386.4 4393.1 4419.2 4482.4 4507.0 4574.0 4582.0 4763.4 4777.0 4778.7 4819.3 4825.2 5092.3 5098.8 5099.8 5100.7 5107.1 4680.3 4704.8 4762.0 4769.8 4815.9 4983.2 5000.0 5003.4 5005.6 5053.0 5054.4 5083.4 5086.3 5090.5 5137.6 4792.4 4861.1 4929.3 4930.3 4966.8 4927.0 4959.8 4964.6 4964.8 5003.9 4750.2 4887.1 4891.3 4896.2 4942.4 4916.2 4950.9 4955.1 4976.2 4994.2 4873.0 4876.2 4886.3 4892.9 4928.1 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#1] ext.html Cycles:180 Average:46871.03 Median:20702.95 stddev:82641.38 (399.2%) stddev-sans-first:82800.19 22:53:00 INFO - PROCESS | 29429 | Values: 598.6 599.3 606.2 607.0 621.2 40239.4 41015.6 41087.6 44136.8 44579.0 40390.6 40978.2 41052.4 41147.0 41202.0 7690.9 7799.5 7826.3 7842.9 7878.6 7643.5 7749.8 7768.9 7771.3 7773.1 7750.8 7855.4 7877.2 7889.5 7908.0 7826.0 7874.3 7886.3 7891.9 7893.0 7597.1 7701.9 7713.8 7715.6 7721.0 37621.8 38019.6 38035.8 38076.6 38132.2 3054.3 3193.9 3577.5 3784.1 3807.6 29249.7 29290.9 29409.6 29480.7 30269.0 3503.8 3565.4 3565.5 3565.7 3591.4 257911.0 259916.0 259944.0 260117.7 260126.0 52929.0 52947.7 53259.5 53276.0 53359.7 47947.3 56429.7 57633.4 57735.0 63348.4 11887.9 12115.1 12141.6 12153.6 12186.4 7576.3 8662.0 8738.5 8788.5 8818.9 15419.8 15906.4 16022.4 16062.4 16101.4 6527.6 6585.9 6620.6 6744.0 6754.9 802.1 840.7 893.2 915.9 995.0 27106.5 27678.2 28465.1 28667.9 28734.4 1002.8 1017.6 1043.9 1046.4 1087.4 14509.9 14951.0 14993.9 15316.8 15332.2 370223.0 373197.0 376127.1 378863.0 378950.0 49026.3 49080.0 49190.3 49214.8 49231.3 1086.4 1211.3 1220.4 1224.5 1238.1 27102.0 28684.1 28860.1 28908.3 29143.4 40974.2 41485.6 41530.2 41620.4 41708.6 294957.0 296388.0 297059.0 297920.0 298372.0 29066.3 29403.3 35466.5 35637.6 40014.8 41294.4 41507.2 41719.4 42344.4 42510.4 34021.7 35125.6 36162.6 36199.1 36387.6 35335.6 35607.6 35681.8 35758.0 36104.8 34525.3 35129.6 35464.6 35775.0 35816.6 31132.0 32418.4 32478.7 32788.3 32843.3 24193.4 24890.8 24955.5 25241.2 25389.1 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#2] jquery.html Cycles:180 Average:24693.67 Median:4839.46 stddev:67954.54 (1404.2%) stddev-sans-first:68120.62 22:53:00 INFO - PROCESS | 29429 | Values: 368.6 374.2 374.4 381.4 386.7 15969.3 17224.0 17462.7 17750.6 17866.6 10593.2 11193.8 11223.7 11497.8 11597.0 4426.5 4633.2 5235.8 5237.7 5244.4 5014.5 5166.7 5172.6 5189.3 5209.3 5037.4 5069.9 5144.0 5160.5 5160.6 5068.6 5115.1 5118.9 5122.9 5158.4 4482.9 4622.4 4626.7 4640.3 4664.4 12598.6 12937.9 15812.0 20010.7 20257.6 1961.1 1979.2 1983.9 2072.7 2415.2 9504.7 9725.7 9940.1 11244.4 12734.9 1736.0 1741.4 1835.7 1851.6 1867.1 67163.0 71150.0 73116.3 73351.6 74668.6 10062.1 10185.7 10228.2 10316.5 10373.9 14454.5 14567.6 14581.1 14581.1 14589.3 6447.2 6482.3 6500.2 6551.3 6599.8 5768.1 5811.4 5906.3 5918.6 5921.6 4341.3 4436.6 4444.4 4522.9 4535.8 1810.3 1847.7 1879.1 1879.8 1913.6 821.6 823.2 865.6 880.6 903.3 15966.4 16279.8 16440.2 16497.5 17076.4 877.9 881.9 907.5 909.8 919.0 9017.6 9506.9 9603.5 10385.7 10403.7 407456.0 408053.0 409061.0 411463.0 418850.0 79630.6 81058.0 81789.2 81908.6 83106.6 945.4 960.2 965.5 978.8 981.6 15336.5 15752.8 15938.2 16199.2 16209.2 35559.8 36487.5 36653.0 38113.8 38589.8 44445.8 45367.3 53958.7 54304.2 54351.0 7342.1 7403.3 7510.4 7528.1 7700.6 10718.3 10974.5 11174.3 11226.1 11440.5 8732.3 9503.2 9515.3 9551.3 9656.1 9430.8 9627.3 9725.8 9875.5 10016.1 9360.9 9408.4 9694.1 9873.7 10037.8 6503.3 6692.9 6791.6 6948.4 7680.4 9305.5 9607.3 9622.6 9956.7 10176.0 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#3] mootools.html Cycles:180 Average:4984.29 Median:5129.50 stddev:2809.47 (54.8%) stddev-sans-first:2803.20 22:53:00 INFO - PROCESS | 29429 | Values: 1231.6 1398.3 1420.1 1546.5 1761.6 6513.8 7181.0 7373.8 7393.4 7554.9 7281.7 7432.6 7434.0 7445.2 7447.4 2157.1 2180.8 2183.5 2184.2 2185.0 2122.2 2152.3 2171.6 2172.2 2177.6 2139.4 2145.2 2151.3 2153.5 2201.9 2074.5 2079.5 2083.2 2089.9 2090.1 2060.2 2133.1 2141.6 2150.4 2151.1 7299.6 7379.7 7430.1 7437.9 7442.2 4298.9 4472.8 4476.6 4477.6 4482.8 2960.1 2970.1 2989.4 3017.0 3018.7 891.4 911.6 913.7 914.3 928.2 9977.9 9989.7 10003.1 10028.0 10042.9 3894.9 3920.2 4017.9 4019.0 4020.1 7887.3 7905.7 7923.3 7923.7 7925.4 4292.4 4296.9 4302.3 4308.2 4309.4 3542.6 3783.8 3837.5 3838.2 4005.1 3328.7 3371.8 3438.1 3458.8 3559.2 996.1 1008.4 1018.5 1019.8 1023.3 842.4 845.9 847.6 869.0 883.8 4923.0 4956.4 5053.7 5127.5 5210.1 392.8 396.2 398.5 399.6 414.2 3545.8 3551.8 3612.8 3619.9 3712.0 9281.0 9674.0 9780.8 9781.5 9785.2 9594.1 9657.8 9674.0 9684.1 9694.9 6955.6 7228.6 7232.9 7350.6 7355.2 5131.5 5143.3 5284.3 5337.1 5443.0 8552.1 8798.7 8820.9 8841.2 8854.7 9055.8 9196.9 9226.7 9251.9 9386.7 6063.5 6280.2 6464.5 6465.6 6606.6 7391.2 7408.4 7458.7 7460.3 7463.3 6520.6 6733.8 6796.2 6884.3 6892.3 6501.1 6554.5 6597.6 6632.0 6698.2 6312.9 6319.7 6385.0 6388.9 6393.6 4610.6 4641.5 4676.8 4756.0 4786.2 4205.3 4522.4 4546.9 4608.6 4609.2 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#4] prototype.html Cycles:180 Average:3981.42 Median:4322.72 stddev:1122.29 (26.0%) stddev-sans-first:1090.60 22:53:00 INFO - PROCESS | 29429 | Values: 284.8 294.2 300.9 301.7 301.9 4553.6 4687.0 4733.9 4734.5 4753.8 4560.9 4733.5 4759.0 4774.6 4779.9 3723.4 3740.6 3743.5 3749.7 3758.8 3533.8 3564.8 3704.3 3739.9 3745.7 3723.4 3726.8 3732.1 3737.5 3752.4 3666.9 3675.4 3695.4 3728.3 3733.5 3289.4 3301.6 3318.7 3323.9 3325.5 4341.3 4480.3 4489.9 4490.3 4499.6 4204.0 4242.0 4243.0 4256.4 4299.1 4748.7 4897.1 4902.7 4908.7 4941.5 3654.0 3655.2 3660.7 3669.9 3675.3 5089.4 5128.5 5138.2 5142.7 5171.4 3744.2 3747.9 3765.4 3794.6 3809.1 3734.4 3820.3 3829.0 3842.5 3860.4 4242.6 4288.9 4295.7 4299.2 4309.4 4708.8 4927.4 4986.4 5009.0 5011.2 1277.9 1319.6 1320.8 1322.3 1338.4 851.3 851.8 866.6 873.6 881.2 3926.3 3974.4 3985.3 3987.5 3997.4 4495.5 4520.8 4543.9 4550.9 4587.3 3873.7 3887.5 3989.0 3994.7 3995.8 2246.7 2413.6 2456.2 2457.6 2476.9 4294.4 4304.3 4345.7 4400.2 4405.7 4558.4 4574.9 4612.4 4630.9 4653.9 4120.8 4244.0 4274.9 4289.2 4307.7 4499.0 4529.7 4547.6 4552.7 4576.9 4444.2 4491.7 4492.0 4498.5 4504.4 3970.1 4289.4 4310.2 4317.1 4328.3 3448.7 3481.9 3483.4 3503.8 3504.7 4534.3 4585.8 4592.6 4595.5 4921.1 4752.7 4938.8 4959.6 4977.3 5012.4 4955.5 4962.3 4981.3 4997.3 5015.2 4704.7 4746.5 4888.2 4909.8 4913.6 4956.9 4957.0 4959.1 4967.6 5002.1 4897.8 4899.7 4917.3 4918.6 4933.1 22:53:00 INFO - PROCESS | 29429 | 22:53:00 INFO - PROCESS | 29429 | [#5] yui.html Cycles:180 Average:13370.15 Median:29417.12 stddev:16466.73 (56.0%) stddev-sans-first:16485.28 22:53:00 INFO - PROCESS | 29429 | Values: 662.3 670.0 771.3 774.2 775.8 12028.5 12071.9 12193.1 12224.2 12544.5 12221.6 12613.6 12933.4 12955.8 12967.5 635.5 772.5 797.8 808.5 810.8 800.3 801.0 802.3 803.7 804.2 838.2 844.0 847.1 847.6 848.0 661.6 791.7 821.1 823.0 842.4 607.4 611.0 611.4 612.5 612.7 11121.9 11243.0 11244.2 11254.3 11256.6 11541.9 11686.4 11822.6 11831.4 11848.9 10844.8 10900.2 11273.4 11362.8 11378.6 4108.5 4160.5 4171.4 4199.1 4271.8 44989.3 45067.0 45118.1 45123.1 45159.3 9707.3 9715.9 9846.9 10243.3 10245.3 17536.4 17797.8 17823.1 17823.8 17829.5 6402.5 6454.1 6481.9 6523.2 6841.6 3578.0 3579.1 3596.4 3613.7 3668.3 2510.5 2626.2 2630.8 2633.1 2633.2 393.5 491.4 498.3 507.5 510.8 602.6 608.9 611.3 612.7 620.7 8301.2 8452.0 8486.7 8496.9 8637.6 550.9 564.6 583.2 604.2 606.8 3894.2 3926.0 3929.9 3972.3 3990.5 60053.4 60143.8 61407.7 61457.0 61460.0 55816.2 56251.7 56907.4 57638.7 58251.0 50313.5 50367.7 50421.5 50646.5 51698.0 7849.6 7896.3 7978.8 8014.3 8175.7 28379.4 29545.6 30922.5 34187.8 34964.0 39858.8 39969.8 40138.0 40199.0 40245.2 8951.0 10615.5 10710.8 11173.8 11310.3 9722.0 9807.5 9876.6 9904.5 10110.1 9656.6 9765.7 9878.8 10102.5 10207.7 9405.7 9445.9 9557.1 9697.1 9813.8 9112.3 9112.3 9281.3 9449.9 9451.3 8362.5 8373.4 8374.7 8484.1 8688.8 7741.5 7773.7 7949.3 8006.8 8352.5 22:53:00 INFO - PROCESS | 29429 | -------- Summary: end -------- 22:53:00 INFO - PROCESS | 29429 | 22:53:01 INFO - TEST-INFO | 29429: exit 0 22:53:01 INFO - TEST-OK | dromaeo_css | took 1117932ms 22:53:01 INFO - TEST-START | kraken 22:53:01 INFO - operating with platform_type : linux_ 22:53:01 INFO - Initialising browser for kraken test... 22:53:01 INFO - TEST-INFO | started process 29610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjEHnt7/profile http://localhost:57712/getInfo.html) 22:53:05 INFO - PROCESS | 29610 | __metrics Screen width/height:1600/1200 22:53:05 INFO - PROCESS | 29610 | colorDepth:24 22:53:05 INFO - PROCESS | 29610 | Browser inner width/height: 1024/697 22:53:05 INFO - PROCESS | 29610 | __metrics 22:53:06 ERROR - PROCESS | 29610 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:53:07 INFO - TEST-INFO | 29610: exit 0 22:53:07 INFO - Browser initialized. 22:53:07 INFO - Running cycle 1/1 for kraken test... 22:53:07 INFO - TEST-INFO | started process 29692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjEHnt7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:53:19 INFO - PROCESS | 29692 | RSS: Main: 154927104 22:53:19 INFO - PROCESS | 29692 | 22:53:21 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57712/tests/kraken/driver.html?testName=audio-beat-detection) 22:53:22 INFO - PROCESS | 29692 | RSS: Main: 210305024 22:53:22 INFO - PROCESS | 29692 | 22:53:25 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57712/tests/kraken/driver.html?testName=audio-dft) 22:53:26 INFO - PROCESS | 29692 | RSS: Main: 197943296 22:53:26 INFO - PROCESS | 29692 | 22:53:29 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57712/tests/kraken/driver.html?testName=audio-fft) 22:53:30 INFO - PROCESS | 29692 | RSS: Main: 268095488 22:53:30 INFO - PROCESS | 29692 | 22:53:32 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57712/tests/kraken/driver.html?testName=audio-oscillator) 22:53:33 INFO - PROCESS | 29692 | RSS: Main: 177188864 22:53:33 INFO - PROCESS | 29692 | 22:53:35 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57712/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:53:36 INFO - PROCESS | 29692 | RSS: Main: 181035008 22:53:36 INFO - PROCESS | 29692 | 22:53:39 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57712/tests/kraken/driver.html?testName=imaging-darkroom) 22:53:40 INFO - PROCESS | 29692 | RSS: Main: 216596480 22:53:40 INFO - PROCESS | 29692 | 22:53:43 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57712/tests/kraken/driver.html?testName=imaging-desaturate) 22:53:44 INFO - PROCESS | 29692 | RSS: Main: 215183360 22:53:44 INFO - PROCESS | 29692 | 22:53:47 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57712/tests/kraken/driver.html?testName=json-parse-financial) 22:53:48 INFO - PROCESS | 29692 | RSS: Main: 215834624 22:53:48 INFO - PROCESS | 29692 | 22:53:49 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57712/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:53:50 INFO - PROCESS | 29692 | RSS: Main: 205430784 22:53:50 INFO - PROCESS | 29692 | 22:53:52 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:53:53 INFO - PROCESS | 29692 | RSS: Main: 208244736 22:53:53 INFO - PROCESS | 29692 | 22:53:55 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:53:56 INFO - PROCESS | 29692 | RSS: Main: 220086272 22:53:56 INFO - PROCESS | 29692 | 22:53:58 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:53:59 INFO - PROCESS | 29692 | RSS: Main: 222138368 22:53:59 INFO - PROCESS | 29692 | 22:54:02 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:54:03 INFO - PROCESS | 29692 | RSS: Main: 222240768 22:54:03 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | Cycle 1(1): loaded http://localhost:57712/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57712/tests/kraken/driver.html?testName=ai-astar) 22:54:05 INFO - PROCESS | 29692 | RSS: Main: 268673024 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | __start_tp_report 22:54:05 INFO - PROCESS | 29692 | _x_x_mozilla_page_load 22:54:05 INFO - PROCESS | 29692 | _x_x_mozilla_page_load_details 22:54:05 INFO - PROCESS | 29692 | |i|pagename|runs| 22:54:05 INFO - PROCESS | 29692 | |0;ai-astar;115;108;113;114;113;115;114;114;115;114 22:54:05 INFO - PROCESS | 29692 | |1;audio-beat-detection;161;130;130;229;128;132;158;136;168;131 22:54:05 INFO - PROCESS | 29692 | |2;audio-dft;160;160;160;160;161;162;162;163;159;160 22:54:05 INFO - PROCESS | 29692 | |3;audio-fft;75;76;75;149;76;77;75;76;139;75 22:54:05 INFO - PROCESS | 29692 | |4;audio-oscillator;94;94;170;93;93;93;132;93;93;141 22:54:05 INFO - PROCESS | 29692 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;117;117;116 22:54:05 INFO - PROCESS | 29692 | |6;imaging-darkroom;147;146;146;147;146;146;146;146;146;146 22:54:05 INFO - PROCESS | 29692 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 22:54:05 INFO - PROCESS | 29692 | |8;json-parse-financial;66;63;73;64;65;64;64;117;63;61 22:54:05 INFO - PROCESS | 29692 | |9;json-stringify-tinderbox;66;64;64;64;65;64;65;65;64;65 22:54:05 INFO - PROCESS | 29692 | |10;stanford-crypto-aes;83;88;84;83;84;85;84;83;87;86 22:54:05 INFO - PROCESS | 29692 | |11;stanford-crypto-ccm;137;129;137;131;128;129;127;127;265;139 22:54:05 INFO - PROCESS | 29692 | |12;stanford-crypto-pbkdf2;176;176;183;170;178;171;173;173;174;175 22:54:05 INFO - PROCESS | 29692 | |13;stanford-crypto-sha256-iterative;67;69;67;67;67;67;67;66;65;66 22:54:05 INFO - PROCESS | 29692 | __end_tp_report 22:54:05 INFO - PROCESS | 29692 | __start_cc_report 22:54:05 INFO - PROCESS | 29692 | _x_x_mozilla_cycle_collect,2108 22:54:05 INFO - PROCESS | 29692 | __end_cc_report 22:54:05 INFO - PROCESS | 29692 | __startTimestamp1463810045259__endTimestamp 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | ------- Summary: start ------- 22:54:05 INFO - PROCESS | 29692 | Number of tests: 14 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:2.07 (1.8%) stddev-sans-first:2.12 22:54:05 INFO - PROCESS | 29692 | Values: 115.0 108.0 113.0 114.0 113.0 115.0 114.0 114.0 115.0 114.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:147.00 stddev:31.47 (21.4%) stddev-sans-first:33.14 22:54:05 INFO - PROCESS | 29692 | Values: 161.0 130.0 130.0 229.0 128.0 132.0 158.0 136.0 168.0 131.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.25 (0.8%) stddev-sans-first:1.30 22:54:05 INFO - PROCESS | 29692 | Values: 160.0 160.0 160.0 160.0 161.0 162.0 162.0 163.0 159.0 160.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#3] audio-fft Cycles:10 Average:89.30 Median:75.50 stddev:28.93 (38.3%) stddev-sans-first:30.22 22:54:05 INFO - PROCESS | 29692 | Values: 75.0 76.0 75.0 149.0 76.0 77.0 75.0 76.0 139.0 75.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:93.00 stddev:27.89 (30.0%) stddev-sans-first:29.00 22:54:05 INFO - PROCESS | 29692 | Values: 94.0 94.0 170.0 93.0 93.0 93.0 132.0 93.0 93.0 141.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:54:05 INFO - PROCESS | 29692 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 22:54:05 INFO - PROCESS | 29692 | Values: 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 22:54:05 INFO - PROCESS | 29692 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#8] json-parse-financial Cycles:10 Average:70.00 Median:64.00 stddev:16.82 (26.3%) stddev-sans-first:17.78 22:54:05 INFO - PROCESS | 29692 | Values: 66.0 63.0 73.0 64.0 65.0 64.0 64.0 117.0 63.0 61.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 22:54:05 INFO - PROCESS | 29692 | Values: 66.0 64.0 64.0 64.0 65.0 64.0 65.0 65.0 64.0 65.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.50 stddev:1.77 (2.1%) stddev-sans-first:1.76 22:54:05 INFO - PROCESS | 29692 | Values: 83.0 88.0 84.0 83.0 84.0 85.0 84.0 83.0 87.0 86.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:134.00 stddev:42.44 (31.7%) stddev-sans-first:44.92 22:54:05 INFO - PROCESS | 29692 | Values: 137.0 129.0 137.0 131.0 128.0 129.0 127.0 127.0 265.0 139.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:175.50 stddev:3.73 (2.1%) stddev-sans-first:3.93 22:54:05 INFO - PROCESS | 29692 | Values: 176.0 176.0 183.0 170.0 178.0 171.0 173.0 173.0 174.0 175.0 22:54:05 INFO - PROCESS | 29692 | 22:54:05 INFO - PROCESS | 29692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.03 (1.5%) stddev-sans-first:1.09 22:54:05 INFO - PROCESS | 29692 | Values: 67.0 69.0 67.0 67.0 67.0 67.0 67.0 66.0 65.0 66.0 22:54:05 INFO - PROCESS | 29692 | -------- Summary: end -------- 22:54:05 INFO - PROCESS | 29692 | 22:54:06 INFO - TEST-INFO | 29692: exit 0 22:54:06 INFO - TEST-OK | kraken | took 65063ms 22:54:06 INFO - SUITE-END | took 1182s 22:54:06 INFO - Completed test suite (00:19:43) 22:54:06 INFO - javascript benchmark 22:54:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2670.6661667291587, 2673.438577898641, 2697.056030389364, 2711.7152698966606, 2716.2801857507766, 4657.976710116453, 4687.273472611746, 4714.622830173586, 4778.4504781950045, 4797.54423329784, 4623.861284161472, 4659.114768194049, 4666.859994200171, 4673.392458980336, 4677.53224677532, 4105.199486100211, 4193.538710741824, 4262.041040765818, 4347.304431290994, 4498.775061246941, 4401.05377343871, 4444.000099977496, 4464.620507256871, 4464.843730469434, 4486.371907932892, 4213.241616509027, 4251.128518653669, 4320.978395108035, 4361.367601697736, 4363.149185908749, 4304.0, 4321.611055005064, 4322.222000039991, 4365.43249377582, 4388.1223755249075, 4337.436133302682, 4362.280223763075, 4368.759718215497, 4404.207242696313, 4411.316245981878, 4560.794764235584, 4611.861644150681, 4612.654050946193, 4668.183067963093, 4669.766511674403, 5137.280780690707, 5157.664751791159, 5166.560842328407, 5166.664166829144, 5167.844964651066, 5127.435982041972, 5182.818601348972, 5191.688498690053, 5200.895982080375, 5205.141151709963, 4632.837850675211, 4691.577758001728, 4746.145693775154, 4748.905021899543, 4769.42766867978, 5240.659357141773, 5242.370915490165, 5274.393444753902, 5274.868128296824, 5275.340582427197, 4857.587105096107, 5012.097822391932, 5016.648834581617, 5060.873478163076, 5065.949340506548, 5000.39995200571, 5076.3908330999775, 5081.517255860687, 5105.540501354822, 5121.2574176744165, 4972.204447288416, 4982.0, 4989.0, 5001.724905130252, 5032.194848824171, 4945.2582112683385, 4959.578435832923, 4963.875903102451, 4972.453030166679, 5020.548150666385, 4288.764117973541, 4315.136972605492, 4319.07139964909, 4338.219120558329, 4370.825166993348, 3750.1937083527173, 3776.527934008249, 3786.2995345860554, 3818.770873747584, 3833.1183827719224, 4215.241256573785, 4257.74453532789, 4290.227759003409, 4336.176126535948, 4342.544032876503, 5070.2141168119, 5111.693298402108, 5122.974385128051, 5133.255677926754, 5172.224166375074, 4341.696081274281, 4346.543612920661, 4346.847860324873, 4386.363977223285, 4393.0994146199955, 4419.160359531678, 4482.350059241392, 4507.0, 4574.0, 4582.0, 4763.404574428197, 4776.952230477651, 4778.689385189951, 4819.325294458778, 4825.203841366135, 5092.287079808903, 5098.7705553250935, 5099.821506247264, 5100.719460429712, 5107.080725469303, 4680.297955306595, 4704.811807527661, 4762.0, 4769.833055843168, 4815.879603009953, 4983.152864013054, 5000.0, 5003.4246061701915, 5005.62457815658, 5052.9747351263, 5054.393472783289, 5083.440821509705, 5086.313347698014, 5090.516400941905, 5137.588992880485, 4792.353032340589, 4861.052094841472, 4929.285253638274, 4930.260460930889, 4966.751662416937, 4927.039227350632, 4959.801607935642, 4964.602831773522, 4964.801407943642, 5003.899922001467, 4750.239961606127, 4887.095887260868, 4891.290762839297, 4896.2410826321975, 4942.4069111705385, 4916.237983112624, 4950.9257361140335, 4955.108080545537, 4976.154053810933, 4994.1759609663995, 4873.049755297683, 4876.244182151772, 4886.315915771866, 4892.877678058077, 4928.063667903087], "value": 4652.351788073072, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.5570677698504, 599.2688919518187, 606.1544145916444, 606.996965015175, 621.2296752027485, 40239.39640905385, 41015.58984410159, 41087.58912410875, 44136.77931610346, 44579.0, 40390.59609403905, 40978.18043639125, 41052.38421423681, 41147.0, 41202.0, 7690.884636730453, 7799.454038217327, 7826.295633392993, 7842.882356764653, 7878.566678832662, 7643.541387516767, 7749.806254843617, 7768.922310776877, 7771.339436147934, 7773.144954055049, 7750.7674769756995, 7855.371570274393, 7877.212278772135, 7889.5266284022855, 7907.960460197691, 7825.960870195641, 7874.330681892046, 7886.290233878978, 7891.921080789233, 7893.0, 7597.126330472011, 7701.884471732928, 7713.807154821175, 7715.614219289013, 7721.0, 37621.81189094064, 38019.619803801885, 38035.809820950715, 38076.619233807585, 38132.23735525274, 3054.282243672735, 3193.8722451101926, 3577.5349204603235, 3784.0918179636965, 3807.6382743639588, 29249.70750292491, 29290.853545732134, 29409.55885661717, 29480.705192948226, 30269.0, 3503.789772613651, 3565.447355659877, 3565.5186549815703, 3565.6612621800887, 3591.3535563598543, 257911.0, 259916.0, 259944.0, 260117.69941150362, 260126.0, 52929.0, 52947.73526132422, 53259.46740532623, 53276.0, 53359.73320133452, 47947.280790788165, 56429.71785141048, 57633.423665762806, 57735.0, 63348.36651633517, 11887.88112118885, 12115.091368147425, 12141.575044873387, 12153.635390938285, 12186.390680465942, 7576.318131368093, 8662.0, 8738.475691458532, 8788.472691638523, 8818.853549038713, 15419.768703469457, 15906.443274485337, 16022.359105635876, 16062.43781467643, 16101.436449724204, 6527.640979746159, 6585.934140658628, 6620.63586502747, 6744.0, 6754.8649027020165, 802.1056521977953, 840.6679361652235, 893.2228960804142, 915.9358844880794, 995.0447570332561, 27106.457870842867, 27678.169654910384, 28465.146045618665, 28667.856660716563, 28734.425311494073, 1002.8144793213279, 1017.6183931025865, 1043.8642976413018, 1046.3669479964665, 1087.396494945297, 14509.927450362891, 14951.0, 14993.925030374996, 15316.846831531764, 15332.233388330538, 370223.0, 373197.0, 376127.11936440144, 378863.0, 378950.0, 49026.26460603094, 49080.0, 49190.262146067835, 49214.753926230136, 49231.26153107706, 1086.3536195963388, 1211.3337664284784, 1220.4020030185145, 1224.4918358880964, 1238.0652607281625, 27102.0, 28684.13947581576, 28860.134195974155, 28908.277293067, 29143.417131657672, 40974.1805163901, 41485.58514414938, 41530.1693966125, 41620.375694364004, 41708.58291417047, 294957.0, 296388.0, 297059.0, 297920.0, 298372.0, 29066.27334316659, 29403.264918377212, 35466.46800297946, 35637.64362356343, 40014.799926001346, 41294.38058429066, 41507.16985660209, 41719.374209386275, 42344.36483452689, 42510.36234456424, 34021.65978340185, 35125.64874351224, 36162.638373615926, 36199.09502262464, 36387.63612363948, 35335.64664353323, 35607.64392356147, 35681.82159089188, 35758.0, 36104.819475902455, 34525.30949380948, 35129.64870351264, 35464.64535354613, 35775.0, 35816.64183358133, 31132.0, 32418.351632966736, 32478.675213247567, 32788.344233115684, 32843.343133136725, 24193.39516512101, 24890.75109248957, 24955.500889981737, 25241.24276271715, 25389.11138110157], "value": 17496.985248831566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.5540495999839, 374.2384248055207, 374.4215187535258, 381.38406473545234, 386.6945113360445, 15969.281382337793, 17224.0, 17462.73805892911, 17750.55623609407, 17866.553336166588, 10593.205509586773, 11193.832092518618, 11223.719407014809, 11497.770044599103, 11597.0, 4426.490953540336, 4633.189191891414, 5235.816746413857, 5237.659552129118, 5244.39689435716, 5014.498550144975, 5166.689998600077, 5172.560332371753, 5189.325387699594, 5209.270702101709, 5037.395512538482, 5069.873253168682, 5144.048351055048, 5160.458151894053, 5160.587153027749, 5068.645194836381, 5115.104856650071, 5118.948810511922, 5122.897542049157, 5158.3552055993005, 4482.932756008662, 4622.445306563201, 4626.676132670715, 4640.257558790578, 4664.416947881515, 12598.622041338775, 12937.870621293854, 15812.0, 20010.69983950242, 20257.594848102955, 1961.08809403628, 1979.218208807513, 1983.8809671419617, 2072.7409073865765, 2415.1546958564536, 9504.667336643184, 9725.65960191397, 9940.10539051489, 11244.437778111062, 12734.872651273461, 1735.973960390595, 1741.3992172700525, 1835.7429959805638, 1851.6389304085708, 1867.057136146251, 67163.0, 71150.0, 73116.26883731148, 73351.63324183399, 74668.62665686637, 10062.094411503, 10185.745356366002, 10228.232882533839, 10316.535755891004, 10373.948130259301, 14454.494092706705, 14567.635809104857, 14581.052231605125, 14581.125132492085, 14589.343479543446, 6447.226332839996, 6482.254540727782, 6500.219973603103, 6551.312112228243, 6599.76900808479, 5768.105943578752, 5811.360750317462, 5906.26171728534, 5918.615290006136, 5921.644701317935, 4341.34879768031, 4436.578525040116, 4444.422225110715, 4522.88692782683, 4535.818567257273, 1810.2939853457162, 1847.7320788485595, 1879.0980329441945, 1879.821416965386, 1913.6172765446688, 821.5850995247481, 823.1562648285436, 865.6191275838611, 880.6433394475248, 903.3405613901889, 15966.36134554628, 16279.755803662954, 16440.177991100398, 16497.505074847773, 17076.402325918534, 877.9341549383822, 881.9162179592928, 907.5416914458289, 909.7998440343114, 918.9586468608928, 9017.594208260643, 9506.904930950603, 9603.519824008772, 10385.688429347132, 10403.739906502398, 407456.0, 408053.0, 409061.0, 411463.0, 418850.0, 79630.6018469904, 81058.0, 81789.18210817935, 81908.59045704734, 83106.58446707728, 945.4280160502934, 960.188640598693, 965.5075911285155, 978.7895602445366, 981.5975450065582, 15336.463223787114, 15752.763708544151, 15938.203089845692, 16199.190040498164, 16209.189540522691, 35559.82220088883, 36487.45268820917, 36653.0, 38113.80943095267, 38589.80705096456, 44445.77777111094, 45367.31949020701, 53958.730206348715, 54304.18543721926, 54351.0, 7342.118945726547, 7403.333699967029, 7510.39916806665, 7528.096628404626, 7700.5764682940735, 10718.303310284806, 10974.506147223554, 11174.329540227613, 11226.101911846868, 11440.542378304775, 8732.345074119594, 9503.23974082058, 9515.33392662507, 9551.331406801457, 9656.130948214695, 9430.764230894283, 9627.277954153327, 9725.756856078655, 9875.506224688881, 10016.148627366756, 9360.906390936003, 9408.388454750419, 9694.127528522185, 9873.68222984779, 10037.799244015225, 6503.349665033648, 6692.933070669231, 6791.592504449749, 6948.444124470131, 7680.423968202519, 9305.534723263945, 9607.327487076118, 9622.615095396106, 9956.701298961043, 10175.982401759764], "value": 7981.232457115036, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1231.5812623707948, 1398.3357904995125, 1420.1266221273916, 1546.4587394411958, 1761.5596100974765, 6513.8371540711505, 7180.964095179524, 7373.815654608624, 7393.371563417116, 7554.92445075549, 7281.672324745399, 7432.628368581562, 7434.0, 7445.181030086686, 7447.366973807219, 2157.0832396231617, 2180.760116387196, 2183.465051062492, 2184.159098746978, 2185.0276626901036, 2122.246602456129, 2152.3435352217566, 2171.554831259588, 2172.2071443922932, 2177.6298029335053, 2139.411661793004, 2145.174107968428, 2151.3115802943066, 2153.54775497145, 2201.9009144588454, 2074.4502706782714, 2079.46973521751, 2083.1667333066744, 2089.9373018809383, 2090.1221486975505, 2060.1553363121197, 2133.136079887648, 2141.6145093883097, 2150.3978885911815, 2151.107290474451, 7299.5620262784405, 7379.6679149438405, 7430.14553326369, 7437.888431673529, 7442.18136005039, 4298.914021719548, 4472.753998530079, 4476.574725401082, 4477.597016268552, 4482.798274077674, 2960.067578712699, 2970.0792754246036, 2989.372231831318, 3016.9849150754108, 3018.6981301869855, 891.3804905590617, 911.5806728904712, 913.6984795017695, 914.29599208609, 928.2063835420686, 9977.850332245021, 9989.700308990741, 10003.099721025143, 10028.047335503188, 10042.949785251027, 3894.883153505399, 3920.2355540669296, 4017.9397309040387, 4019.0153412414147, 4020.115574573589, 7887.250711182429, 7905.683772649029, 7923.326517246099, 7923.7226697065325, 7925.365970722329, 4292.420523229386, 4296.914061718748, 4302.333138363559, 4308.18144552534, 4309.396684464177, 3542.6457354264367, 3783.848646054182, 3837.501124853807, 3838.213166300902, 4005.118873847749, 3328.73370130389, 3371.8145501997624, 3438.0889064397957, 3458.75788694794, 3559.1991801844897, 996.1482932092956, 1008.4302369161547, 1018.4805749068029, 1019.7909428567126, 1023.3399457349967, 842.4103127810557, 845.9450135741157, 847.5719761520393, 868.9608967596473, 883.7923088074298, 4923.0, 4956.430010548761, 5053.722045287471, 5127.461616530285, 5210.062188806051, 392.75256588349157, 396.179907591289, 398.50784281412433, 399.594411672153, 414.241937254823, 3545.7517973742106, 3551.8046507441823, 3612.8012959287494, 3619.8552057917914, 3712.0348709335767, 9280.953595232115, 9674.0, 9780.755481113029, 9781.46201958885, 9785.168260697921, 9594.088561586637, 9657.758556036155, 9674.03259674041, 9684.128428441474, 9694.9030509694, 6955.582665040114, 7228.602426866468, 7232.92767072333, 7350.63246837656, 7355.190928997806, 5131.538161565477, 5143.331366922276, 5284.339457567804, 5337.066013447708, 5443.020256353736, 8552.102029287083, 8798.736037918872, 8820.867686984571, 8841.204291613529, 8854.734357969272, 9055.773605659911, 9196.908030919605, 9226.677066302647, 9251.907480925105, 9386.67146649864, 6063.454289114001, 6280.183576135073, 6464.4828413725845, 6465.579737317059, 6606.6036037837885, 7391.1500177480275, 7408.37028852532, 7458.664360103699, 7460.253974602496, 7463.290987356192, 6520.576162549419, 6733.831654208684, 6796.218434880052, 6884.311568843075, 6892.345227203407, 6501.122348483083, 6554.4756419487285, 6597.637129957707, 6632.0, 6698.162729658793, 6312.873742525216, 6319.6840157991, 6385.0, 6388.872222555616, 6393.616383016848, 4610.585047345621, 4641.489436162087, 4676.836310729108, 4755.976220118877, 4786.210275304536, 4205.285101532808, 4522.4346956630425, 4546.931796023128, 4608.631309495166, 4609.193391156601], "value": 3946.582187715283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.77787325885816, 294.18364039789583, 300.88115194498187, 301.67419187277744, 301.91848200985737, 4553.612942899849, 4687.015726697389, 4733.928991065132, 4734.4792072872, 4753.833615823447, 4560.9087818243615, 4733.502982186864, 4758.976205118978, 4774.570288674018, 4779.8805029874275, 3723.422869455239, 3740.607236240183, 3743.5133432653715, 3749.650125954654, 3758.7556808807476, 3533.816171582523, 3564.750467467278, 3704.2961837250837, 3739.9341187761493, 3745.700343972476, 3723.3989384564625, 3726.751538234681, 3732.059329148841, 3737.4767532545466, 3752.3620984432714, 3666.8716594919315, 3675.375186218336, 3695.4087346024776, 3728.322254985254, 3733.4773131761576, 3289.3585750778607, 3301.636819949804, 3318.668133186686, 3323.88366407177, 3325.451300535433, 4341.327094300388, 4480.33985378385, 4489.887752806173, 4490.326451032372, 4499.6400287976885, 4204.012057166564, 4241.957580424218, 4242.9787851060855, 4256.361545768129, 4299.141071187656, 4748.667593268473, 4897.118518666639, 4902.7058376497525, 4908.674657842363, 4941.530554597343, 3653.9634603654154, 3655.214128962259, 3660.68884144848, 3669.94495082574, 3675.3016926783826, 5089.3638295213095, 5128.461511541309, 5138.229265610151, 5142.691438513663, 5171.431142574352, 3744.2137151198554, 3747.9063023424087, 3765.4163604641326, 3794.582595914447, 3809.104860357814, 3734.43153875373, 3820.2741479118877, 3829.0, 3842.4620553122586, 3860.3823388257742, 4242.596953289432, 4288.89277768052, 4295.72077814941, 4299.204647140289, 4309.392817345523, 4708.764561771898, 4927.433345165352, 4986.351774269322, 5008.974955125201, 5011.198208286657, 1277.8530468995998, 1319.577735124751, 1320.7980737528803, 1322.2529270961875, 1338.4311667541451, 851.3444647621297, 851.8424091543085, 866.5537248317122, 873.6418068591847, 881.1937077574077, 3926.2736393767245, 3974.4237085623, 3985.27376902597, 3987.501562304712, 3997.360422332413, 4495.460544734653, 4520.751358675305, 4543.909121817545, 4550.88622784433, 4587.3348364486965, 3873.748206366577, 3887.5334959805004, 3989.0, 3994.720369574102, 3995.780232087205, 2246.730392352928, 2413.634638054716, 2456.2140115162974, 2457.63135529672, 2476.86377249253, 4294.432532125752, 4304.289792184317, 4345.717528360647, 4400.163968845973, 4405.700318406078, 4558.361829343895, 4574.862754117382, 4612.423447069116, 4630.861074167713, 4653.906921861544, 4120.835166593302, 4244.0, 4274.871753847327, 4289.206496798102, 4307.676924230633, 4499.042916331407, 4529.728216307032, 4547.567516232376, 4552.658550608651, 4576.8626941191815, 4444.177827101997, 4491.742312152603, 4491.955080449154, 4498.460184777717, 4504.369388285708, 3970.1190644863827, 4289.356596510548, 4310.202612516695, 4317.136572685413, 4328.3074708047825, 3448.706859916935, 3481.9303613928087, 3483.390406678872, 3503.8072905990416, 3504.6857428464327, 4534.2971839364955, 4585.77071146448, 4592.563706447882, 4595.494495605548, 4921.121144130723, 4752.667313288037, 4938.827141050046, 4959.553640172402, 4977.328060711902, 5012.370979431772, 4955.5044495550155, 4962.263207877214, 4981.327520784647, 4997.250412438163, 5015.17249653803, 4704.694194877322, 4746.4822417984915, 4888.1690112681645, 4909.754512274443, 4913.557779799835, 4956.851294461172, 4956.950430495793, 4959.115271457684, 4967.552920237196, 5002.149634562061, 4897.804087836446, 4899.7260712214365, 4917.336159618549, 4918.631102667243, 4933.136701077369], "value": 3632.7236343784007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.3409707341197, 669.9933000669994, 771.3173841150583, 774.1677696476289, 775.806048487878, 12028.518859245642, 12071.939640301796, 12193.1464797464, 12224.205426647273, 12544.498220071198, 12221.572244971429, 12613.621591352274, 12933.41799619017, 12955.805662915063, 12967.481300747959, 635.4916067146288, 772.5326177662517, 797.7686470923426, 808.4502538273971, 810.7973006748313, 800.3197282310048, 801.046754362307, 802.3300544045729, 803.6825453945688, 804.1636697834246, 838.2455789789177, 844.0377969115183, 847.076686411811, 847.5719761520423, 848.0459483081534, 661.5699795133154, 791.7347688524351, 821.1131977464324, 823.0288259853371, 842.4313588327885, 607.4138456389561, 610.9755609775648, 611.4222060153157, 612.4640939178219, 612.6875293600251, 11121.944390278079, 11243.0, 11244.212905096647, 11254.268472549258, 11256.606018789304, 11541.884581154165, 11686.415679216005, 11822.586209482626, 11831.408429578572, 11848.940755296255, 10844.837327440095, 10900.182486313559, 11273.436328183558, 11362.82955755656, 11378.601748938747, 4108.548059713429, 4160.54234034256, 4171.353440216772, 4199.097194103252, 4271.761189255112, 44989.32516012262, 45067.0, 45118.09763804738, 45123.097538049384, 45159.3226101612, 9707.271954603291, 9715.9028409715, 9846.852297215404, 10243.282970192015, 10245.334053286513, 17536.386226482013, 17797.822021779615, 17823.10884455798, 17823.821761782474, 17829.46511604654, 6402.455791257702, 6454.128692626436, 6481.902771458432, 6523.160439037947, 6841.62371069586, 3578.0, 3579.069441945061, 3596.4425514045365, 3613.674769270778, 3668.2663467306647, 2510.4853505031424, 2626.185882376431, 2630.8421494709996, 2633.0784225520915, 2633.2231991562535, 393.48257041989694, 491.3710450623208, 498.29491269853474, 507.54321110999655, 510.8186593759162, 602.6474512410289, 608.9208402907682, 611.3122736920964, 612.7059011674421, 620.6958590290725, 8301.211384918422, 8452.0, 8486.660533578712, 8496.872546911802, 8637.568121593895, 550.8705454218256, 564.6301672404581, 583.2446981159409, 604.2356419129804, 606.7815586388897, 3894.182221733411, 3926.0381206604566, 3929.9214015719526, 3972.3048466518244, 3990.541087774943, 60053.399466005656, 60143.76118447995, 61407.6929615358, 61457.0, 61460.0, 55816.16275755786, 56251.71874140603, 56907.43092569104, 57638.71180644154, 58251.0, 50313.49686503088, 50367.74816125896, 50421.49578504241, 50646.49353506492, 51697.99999999925, 7849.646765895663, 7896.32881205104, 7978.84042319162, 8014.278714915686, 8175.67297308101, 28379.432411351245, 29545.556816648197, 30922.53616195804, 34187.829060854536, 34964.0, 39858.800705996284, 39969.80015100022, 40138.0, 40199.0, 40245.195096097326, 8950.955245223733, 10615.522301496605, 10710.839337410102, 11173.832392513956, 11310.321380717183, 9722.0, 9807.460589667637, 9876.555554999899, 9904.455254961047, 10110.140638045554, 9656.565454554422, 9765.707028789147, 9878.802423951336, 10102.545385457524, 10207.7448063799, 9405.746355757476, 9445.905540944503, 9557.092076252746, 9697.127258546765, 9813.803723925625, 9112.316576256675, 9112.31657625694, 9281.303902207226, 9449.858252126087, 9451.291153163404, 8362.540060296742, 8373.37199710012, 8374.665013399395, 8484.109168537461, 8688.78278043054, 7741.458097933316, 7773.68905243784, 7949.284564389001, 8006.799830004296, 8352.457090289112], "value": 5894.372941033385, "unit": "score"}], "name": "dromaeo_css", "value": 6165.121956977467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 113.0, 114.0, 113.0, 115.0, 114.0, 114.0, 115.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 130.0, 229.0, 128.0, 132.0, 158.0, 136.0, 168.0, 131.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 161.0, 162.0, 162.0, 163.0, 159.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 149.0, 76.0, 77.0, 75.0, 76.0, 139.0, 75.0], "value": 89.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 170.0, 93.0, 93.0, 93.0, 132.0, 93.0, 93.0, 141.0], "value": 109.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.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": [66.0, 63.0, 73.0, 64.0, 65.0, 64.0, 64.0, 117.0, 63.0, 61.0], "value": 70.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 84.0, 83.0, 84.0, 85.0, 84.0, 83.0, 87.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 129.0, 137.0, 131.0, 128.0, 129.0, 127.0, 127.0, 265.0, 139.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 183.0, 170.0, 178.0, 171.0, 173.0, 173.0, 174.0, 175.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 65.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1587.2, "alertThreshold": 2.0}]} 22:54:06 INFO - Return code: 0 22:54:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:54:06 ERROR - # TBPL SUCCESS # 22:54:06 INFO - Running post-action listener: _resource_record_post_action 22:54:06 INFO - ##### 22:54:06 INFO - ##### Finished run-tests step (success) 22:54:06 INFO - ##### 22:54:06 INFO - Running post-run listener: _resource_record_post_run 22:54:06 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 532590592; Read time: 0; Write time: 461996 22:54:06 INFO - TinderboxPrint: CPU usage
13.2% 22:54:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:54:06 INFO - TinderboxPrint: I/O write bytes / time
532,590,592 / 461,996 22:54:06 INFO - TinderboxPrint: CPU idle
8,316.8 (86.8%) 22:54:06 INFO - TinderboxPrint: CPU user
1,220.0 (12.7%) 22:54:06 INFO - TinderboxPrint: Swap in / out
0 / 0 22:54:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236527616; Read time: 0; Write time: 222632 22:54:06 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 296062976; Read time: 0; Write time: 239364 22:54:07 INFO - Running post-run listener: _upload_blobber_files 22:54:07 INFO - Blob upload gear active. 22:54:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:54:07 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 22:54:07 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:54:07 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:54:07 INFO - (blobuploader) - INFO - Open directory for files ... 22:54:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:54:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:54:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:08 INFO - (blobuploader) - INFO - Done attempting. 22:54:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:54:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:54:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:09 INFO - (blobuploader) - INFO - Done attempting. 22:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:54:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:14 INFO - (blobuploader) - INFO - Done attempting. 22:54:14 INFO - (blobuploader) - INFO - Iteration through files over. 22:54:14 INFO - Return code: 0 22:54:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:54:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:54:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0b71f0d7f60adce7ebadc696b4a4e62aa1e4314d2e16b0cfce6d95a137f01a34522476f399b16f88a193c8db1f34ef351d3e381a029b145ccbf8726cc05938", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/892af94958095bf49f66aa62c9e0162365a244d486d4775b5dd2db63c1d2f8eadd8e00741a4954ae86f7fed055429dbd954f292fdf55eb421d06f38118d25015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5804bd9db29e1e5529c03ecb66fb0ddd74ea538d5cf0beba4ee37bb483b13dd37731c7e3ccd7bacd762bcf8cad021e29a03ccc7030710889abf0d0af63e4c81f"} 22:54:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:54:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:54:14 INFO - Contents: 22:54:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0b71f0d7f60adce7ebadc696b4a4e62aa1e4314d2e16b0cfce6d95a137f01a34522476f399b16f88a193c8db1f34ef351d3e381a029b145ccbf8726cc05938", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/892af94958095bf49f66aa62c9e0162365a244d486d4775b5dd2db63c1d2f8eadd8e00741a4954ae86f7fed055429dbd954f292fdf55eb421d06f38118d25015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5804bd9db29e1e5529c03ecb66fb0ddd74ea538d5cf0beba4ee37bb483b13dd37731c7e3ccd7bacd762bcf8cad021e29a03ccc7030710889abf0d0af63e4c81f"} 22:54:14 INFO - Running post-run listener: copy_logs_to_upload_dir 22:54:14 INFO - Copying logs to upload dir... 22:54:14 INFO - mkdir: /builds/slave/test/build/upload/logs 22:54:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.632227 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-05-20 22:54:14.183556) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:54:14.186985) ========= 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=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 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-Non-PGO/sha512/1a0b71f0d7f60adce7ebadc696b4a4e62aa1e4314d2e16b0cfce6d95a137f01a34522476f399b16f88a193c8db1f34ef351d3e381a029b145ccbf8726cc05938", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/892af94958095bf49f66aa62c9e0162365a244d486d4775b5dd2db63c1d2f8eadd8e00741a4954ae86f7fed055429dbd954f292fdf55eb421d06f38118d25015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5804bd9db29e1e5529c03ecb66fb0ddd74ea538d5cf0beba4ee37bb483b13dd37731c7e3ccd7bacd762bcf8cad021e29a03ccc7030710889abf0d0af63e4c81f"} build_url:https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/Sy-kGYeJQniD5T4K3vdyaw/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-Non-PGO/sha512/1a0b71f0d7f60adce7ebadc696b4a4e62aa1e4314d2e16b0cfce6d95a137f01a34522476f399b16f88a193c8db1f34ef351d3e381a029b145ccbf8726cc05938", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/892af94958095bf49f66aa62c9e0162365a244d486d4775b5dd2db63c1d2f8eadd8e00741a4954ae86f7fed055429dbd954f292fdf55eb421d06f38118d25015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5804bd9db29e1e5529c03ecb66fb0ddd74ea538d5cf0beba4ee37bb483b13dd37731c7e3ccd7bacd762bcf8cad021e29a03ccc7030710889abf0d0af63e4c81f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:54:14.212585) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:54:14.212934) ========= 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=ea34ab03e80221fdf844251c000001a3-1463808642.64272-945373475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:54:14.297343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:54:14.297666) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:54:14.297956) ========= ========= Total master_lag: 0.17 =========