builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-017 starttime: 1462923669.8 results: success (0) buildid: 20160510152113 builduid: 89bfaba7f8d641749e05e10cc94cb119 revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:09.804550) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:09.805163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:09.805532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:09.838811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:09.839617) ========= 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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 16:41:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.4M=0s 2016-05-10 16:41:10 (30.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.150103 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.007803) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.008572) ========= 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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019517 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.061574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.062211) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 674a552743785c28c75866969aad513bd8eaf6ae --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 674a552743785c28c75866969aad513bd8eaf6ae --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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 16:41:10,855 truncating revision to first 12 chars 2016-05-10 16:41:10,855 Setting DEBUG logging. 2016-05-10 16:41:10,855 attempt 1/10 2016-05-10 16:41:10,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/674a55274378?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 16:41:11,163 unpacking tar archive at: mozilla-central-674a55274378/testing/mozharness/ program finished with exit code 0 elapsedTime=0.614250 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.694516) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.695230) ========= script_repo_revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.696211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.696903) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 16:41:10.707615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-10 16:41:10.707947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:41:11 INFO - MultiFileLogger online at 20160510 16:41:11 in /builds/slave/test 16:41:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 16:41:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:41:11 INFO - {'append_to_log': False, 16:41:11 INFO - 'base_work_dir': '/builds/slave/test', 16:41:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:41:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:41:11 INFO - 'branch': 'Firefox-Non-PGO', 16:41:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:41:11 INFO - 'config_files': ('talos/linux_config.py',), 16:41:11 INFO - 'default_actions': ('clobber', 16:41:11 INFO - 'read-buildbot-config', 16:41:11 INFO - 'download-and-extract', 16:41:11 INFO - 'populate-webroot', 16:41:11 INFO - 'create-virtualenv', 16:41:11 INFO - 'install', 16:41:11 INFO - 'run-tests'), 16:41:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:41:11 INFO - 'download_minidump_stackwalk': True, 16:41:11 INFO - 'download_symbols': 'ondemand', 16:41:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:41:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:41:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:41:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:41:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:41:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:41:11 INFO - 'installer_path': 'installer.exe', 16:41:11 INFO - 'log_level': 'info', 16:41:11 INFO - 'log_name': 'talos', 16:41:11 INFO - 'log_to_console': True, 16:41:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:41:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:41:11 INFO - 'opt_config_files': (), 16:41:11 INFO - 'pip_index': False, 16:41:11 INFO - 'sps_profile': False, 16:41:11 INFO - 'sps_profile_interval': 0, 16:41:11 INFO - 'suite': 'dromaeojs-e10s', 16:41:11 INFO - 'system_bits': '32', 16:41:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:41:11 INFO - 'title': 'talos-linux64-ix-017', 16:41:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:41:11 INFO - 'use_talos_json': True, 16:41:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:41:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:41:11 INFO - 'work_dir': 'build'} 16:41:11 INFO - ##### 16:41:11 INFO - ##### Running clobber step. 16:41:11 INFO - ##### 16:41:11 INFO - Running pre-action listener: _resource_record_pre_action 16:41:11 INFO - Running main action method: clobber 16:41:11 INFO - rmtree: /builds/slave/test/build 16:41:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:41:12 INFO - Running post-action listener: _resource_record_post_action 16:41:12 INFO - ##### 16:41:12 INFO - ##### Finished clobber step (success) 16:41:12 INFO - ##### 16:41:12 INFO - ##### 16:41:12 INFO - ##### Running read-buildbot-config step. 16:41:12 INFO - ##### 16:41:12 INFO - Running pre-action listener: _resource_record_pre_action 16:41:12 INFO - Running main action method: read_buildbot_config 16:41:12 INFO - Using buildbot properties: 16:41:12 INFO - { 16:41:12 INFO - "project": "", 16:41:12 INFO - "product": "firefox", 16:41:12 INFO - "script_repo_revision": "production", 16:41:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:41:12 INFO - "repository": "", 16:41:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:41:12 INFO - "buildid": "20160510152113", 16:41:12 INFO - "pgo_build": "False", 16:41:12 INFO - "basedir": "/builds/slave/test", 16:41:12 INFO - "buildnumber": 310, 16:41:12 INFO - "slavename": "talos-linux64-ix-017", 16:41:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:41:12 INFO - "platform": "ubuntu64_hw", 16:41:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:41:12 INFO - "branch": "mozilla-central", 16:41:12 INFO - "repo_path": "mozilla-central", 16:41:12 INFO - "revision": "674a552743785c28c75866969aad513bd8eaf6ae", 16:41:12 INFO - "stage_platform": "linux64", 16:41:12 INFO - "builduid": "89bfaba7f8d641749e05e10cc94cb119", 16:41:12 INFO - "slavebuilddir": "test" 16:41:12 INFO - } 16:41:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:41:12 INFO - Running post-action listener: _resource_record_post_action 16:41:12 INFO - ##### 16:41:12 INFO - ##### Finished read-buildbot-config step (success) 16:41:12 INFO - ##### 16:41:12 INFO - ##### 16:41:12 INFO - ##### Running download-and-extract step. 16:41:12 INFO - ##### 16:41:12 INFO - Running pre-action listener: _resource_record_pre_action 16:41:12 INFO - Running main action method: download_and_extract 16:41:12 INFO - mkdir: /builds/slave/test/build/tests 16:41:12 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')]} 16:41:12 INFO - https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:41:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:41:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:41:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:12 INFO - Downloaded 1361 bytes. 16:41:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:41:12 INFO - Using the following test package requirements: 16:41:12 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:41:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:41:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:41:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'jsshell-linux-x86_64.zip'], 16:41:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:41:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:41:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:41:12 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:41:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:41:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:41:12 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:41:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:41:12 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')]} 16:41:12 INFO - https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:41:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:41:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:41:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:14 INFO - Downloaded 22328750 bytes. 16:41:14 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'] 16:41:14 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 16:41:15 INFO - Return code: 0 16:41:15 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 16:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:15 INFO - https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:41:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:41:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:41:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:16 INFO - Downloaded 22328750 bytes. 16:41:16 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'] 16:41:16 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 16:41:18 INFO - Return code: 0 16:41:18 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')]} 16:41:18 INFO - https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:19 INFO - Downloaded 10840152 bytes. 16:41:19 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'] 16:41:19 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 16:41:19 INFO - Return code: 0 16:41: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')]} 16:41:19 INFO - https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:41:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:41:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:41:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cZJ3eAESRgyyltx_LRggtQ/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 16:41:21 INFO - Downloaded 54676055 bytes. 16:41:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:41:21 INFO - mkdir: /builds/slave/test/properties 16:41:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:41:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:41:21 INFO - Contents: 16:41:21 INFO - build_url:https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:41:22 INFO - Running post-action listener: _resource_record_post_action 16:41:22 INFO - Running post-action listener: set_extra_try_arguments 16:41:22 INFO - ##### 16:41:22 INFO - ##### Finished download-and-extract step (success) 16:41:22 INFO - ##### 16:41:22 INFO - ##### 16:41:22 INFO - ##### Running populate-webroot step. 16:41:22 INFO - ##### 16:41:22 INFO - Running pre-action listener: _resource_record_pre_action 16:41:22 INFO - Running main action method: populate_webroot 16:41:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:41:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:41:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:41:22 INFO - '--tppagecycles', 16:41:22 INFO - '7'], 16:41:22 INFO - 'tests': ['tsvgm']}}, 16:41:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:41:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:41:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:41:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:41:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['damp', 'tps']}, 16:41:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['damp', 'tps']}, 16:41:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['dromaeo_dom']}, 16:41:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:41:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tsvgx', 16:41:22 INFO - 'tsvgr_opacity', 16:41:22 INFO - 'tart', 16:41:22 INFO - 'tscrollx', 16:41:22 INFO - 'cart']}, 16:41:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:41:22 INFO - 'tsvgr_opacity', 16:41:22 INFO - 'tart', 16:41:22 INFO - 'tscrollx', 16:41:22 INFO - 'cart']}, 16:41:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tp5o']}, 16:41:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['tp5o']}, 16:41:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s', 16:41:22 INFO - '--xperf_path', 16:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:22 INFO - 'tests': ['tp5n']}, 16:41:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--xperf_path', 16:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:22 INFO - 'tests': ['tp5n']}}, 16:41:22 INFO - 'talos.zip': {'path': '', 16:41:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:41:22 INFO - Running post-action listener: _resource_record_post_action 16:41:22 INFO - ##### 16:41:22 INFO - ##### Finished populate-webroot step (success) 16:41:22 INFO - ##### 16:41:22 INFO - ##### 16:41:22 INFO - ##### Running create-virtualenv step. 16:41:22 INFO - ##### 16:41:22 INFO - Running pre-action listener: _resource_record_pre_action 16:41:22 INFO - Running main action method: create_virtualenv 16:41:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:41:22 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 16:41:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:41:22 INFO - Using real prefix '/usr' 16:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:41:22 INFO - Installing distribute.............................................................................................................................................................................................done. 16:41:24 INFO - Installing pip.................done. 16:41:24 INFO - Return code: 0 16:41:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:24 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:24 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 16:41:24 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 16:41:24 INFO - Using env: {'DISPLAY': ':0', 16:41:24 INFO - 'HOME': '/home/cltbld', 16:41:24 INFO - 'LANG': 'en_US.UTF-8', 16:41:24 INFO - 'LANGUAGE': 'en_US:en', 16:41:24 INFO - 'LOGNAME': 'cltbld', 16:41:24 INFO - 'MAIL': '/var/mail/cltbld', 16:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:24 INFO - 'MOZ_NO_REMOTE': '1', 16:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:24 INFO - 'NO_EM_RESTART': '1', 16:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:24 INFO - 'PWD': '/builds/slave/test', 16:41:24 INFO - 'SHELL': '/bin/bash', 16:41:24 INFO - 'SHLVL': '1', 16:41:24 INFO - 'TERM': 'linux', 16:41:24 INFO - 'TMOUT': '86400', 16:41:24 INFO - 'USER': 'cltbld', 16:41:24 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:24 INFO - '_': '/tools/buildbot/bin/python'} 16:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:24 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 16:41:24 INFO - Cleaning up... 16:41:24 INFO - Return code: 0 16:41:24 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:41:24 INFO - Using env: {'DISPLAY': ':0', 16:41:24 INFO - 'HOME': '/home/cltbld', 16:41:24 INFO - 'LANG': 'en_US.UTF-8', 16:41:24 INFO - 'LANGUAGE': 'en_US:en', 16:41:24 INFO - 'LOGNAME': 'cltbld', 16:41:24 INFO - 'MAIL': '/var/mail/cltbld', 16:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:24 INFO - 'MOZ_NO_REMOTE': '1', 16:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:24 INFO - 'NO_EM_RESTART': '1', 16:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:24 INFO - 'PWD': '/builds/slave/test', 16:41:24 INFO - 'SHELL': '/bin/bash', 16:41:24 INFO - 'SHLVL': '1', 16:41:24 INFO - 'TERM': 'linux', 16:41:24 INFO - 'TMOUT': '86400', 16:41:24 INFO - 'USER': 'cltbld', 16:41:24 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:24 INFO - '_': '/tools/buildbot/bin/python'} 16:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:25 INFO - Downloading/unpacking psutil>=0.7.1 16:41:25 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 16:41:25 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 16:41:25 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 16:41:25 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 16:41:25 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 16:41:25 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 16:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:27 INFO - Installing collected packages: psutil 16:41:27 INFO - Running setup.py install for psutil 16:41:27 INFO - building 'psutil._psutil_linux' extension 16:41:27 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 16:41:27 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 16:41:27 INFO - building 'psutil._psutil_posix' extension 16:41:27 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 16:41:27 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 16:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:27 INFO - Successfully installed psutil 16:41:27 INFO - Cleaning up... 16:41:27 INFO - Return code: 0 16:41:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:41:27 INFO - Using env: {'DISPLAY': ':0', 16:41:27 INFO - 'HOME': '/home/cltbld', 16:41:27 INFO - 'LANG': 'en_US.UTF-8', 16:41:27 INFO - 'LANGUAGE': 'en_US:en', 16:41:27 INFO - 'LOGNAME': 'cltbld', 16:41:27 INFO - 'MAIL': '/var/mail/cltbld', 16:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:27 INFO - 'MOZ_NO_REMOTE': '1', 16:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:27 INFO - 'NO_EM_RESTART': '1', 16:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:27 INFO - 'PWD': '/builds/slave/test', 16:41:27 INFO - 'SHELL': '/bin/bash', 16:41:27 INFO - 'SHLVL': '1', 16:41:27 INFO - 'TERM': 'linux', 16:41:27 INFO - 'TMOUT': '86400', 16:41:27 INFO - 'USER': 'cltbld', 16:41:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:27 INFO - '_': '/tools/buildbot/bin/python'} 16:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:41:27 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 16:41:27 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 16:41:27 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 16:41:27 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 16:41:27 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 16:41:27 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 16:41:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:41:29 INFO - Installing collected packages: mozsystemmonitor 16:41:29 INFO - Running setup.py install for mozsystemmonitor 16:41:29 INFO - Successfully installed mozsystemmonitor 16:41:29 INFO - Cleaning up... 16:41:30 INFO - Return code: 0 16:41:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:30 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:30 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 16:41:30 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 16:41:30 INFO - Using env: {'DISPLAY': ':0', 16:41:30 INFO - 'HOME': '/home/cltbld', 16:41:30 INFO - 'LANG': 'en_US.UTF-8', 16:41:30 INFO - 'LANGUAGE': 'en_US:en', 16:41:30 INFO - 'LOGNAME': 'cltbld', 16:41:30 INFO - 'MAIL': '/var/mail/cltbld', 16:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:30 INFO - 'MOZ_NO_REMOTE': '1', 16:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:30 INFO - 'NO_EM_RESTART': '1', 16:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:30 INFO - 'PWD': '/builds/slave/test', 16:41:30 INFO - 'SHELL': '/bin/bash', 16:41:30 INFO - 'SHLVL': '1', 16:41:30 INFO - 'TERM': 'linux', 16:41:30 INFO - 'TMOUT': '86400', 16:41:30 INFO - 'USER': 'cltbld', 16:41:30 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:30 INFO - '_': '/tools/buildbot/bin/python'} 16:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:30 INFO - Downloading/unpacking blobuploader==1.2.4 16:41:30 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 16:41:30 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 16:41:30 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 16:41:30 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 16:41:30 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 16:41:30 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 16:41:32 INFO - Downloading blobuploader-1.2.4.tar.gz 16:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:41:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:41:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 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 16:41:32 INFO - Downloading docopt-0.6.1.tar.gz 16:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:41:32 INFO - Installing collected packages: blobuploader, requests, docopt 16:41:32 INFO - Running setup.py install for blobuploader 16:41:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:41:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:41:32 INFO - Running setup.py install for requests 16:41:33 INFO - Running setup.py install for docopt 16:41:33 INFO - Successfully installed blobuploader requests docopt 16:41:33 INFO - Cleaning up... 16:41:33 INFO - Return code: 0 16:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:33 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')]} 16:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:33 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')]} 16:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:33 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:33 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 16:41:33 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 16:41:33 INFO - Using env: {'DISPLAY': ':0', 16:41:33 INFO - 'HOME': '/home/cltbld', 16:41:33 INFO - 'LANG': 'en_US.UTF-8', 16:41:33 INFO - 'LANGUAGE': 'en_US:en', 16:41:33 INFO - 'LOGNAME': 'cltbld', 16:41:33 INFO - 'MAIL': '/var/mail/cltbld', 16:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:33 INFO - 'MOZ_NO_REMOTE': '1', 16:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:33 INFO - 'NO_EM_RESTART': '1', 16:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:33 INFO - 'PWD': '/builds/slave/test', 16:41:33 INFO - 'SHELL': '/bin/bash', 16:41:33 INFO - 'SHLVL': '1', 16:41:33 INFO - 'TERM': 'linux', 16:41:33 INFO - 'TMOUT': '86400', 16:41:33 INFO - 'USER': 'cltbld', 16:41:33 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:33 INFO - '_': '/tools/buildbot/bin/python'} 16:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:33 INFO - Running setup.py (path:/tmp/pip-ABEdLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:33 INFO - Running setup.py (path:/tmp/pip-df1ajy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:33 INFO - Running setup.py (path:/tmp/pip-jV2nsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:33 INFO - Running setup.py (path:/tmp/pip-U6woe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:33 INFO - Running setup.py (path:/tmp/pip-GMunHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:34 INFO - Running setup.py (path:/tmp/pip-ssN6uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:34 INFO - Running setup.py (path:/tmp/pip-wWpUNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:34 INFO - Running setup.py (path:/tmp/pip-QLJQ0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:34 INFO - Running setup.py (path:/tmp/pip-pnN0el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:34 INFO - Running setup.py (path:/tmp/pip-D9lSzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:34 INFO - Running setup.py (path:/tmp/pip-jgGsuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:34 INFO - Running setup.py (path:/tmp/pip-505sI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:34 INFO - Running setup.py (path:/tmp/pip-rJ__tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:34 INFO - Running setup.py (path:/tmp/pip-eV7LnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:34 INFO - Running setup.py (path:/tmp/pip-H8LsN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:34 INFO - Running setup.py (path:/tmp/pip-Ney24h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:34 INFO - Running setup.py (path:/tmp/pip-CMenB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:41:34 INFO - Running setup.py install for manifestparser 16:41:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for mozcrash 16:41:35 INFO - Running setup.py install for mozdebug 16:41:35 INFO - Running setup.py install for mozdevice 16:41:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for mozfile 16:41:35 INFO - Running setup.py install for mozhttpd 16:41:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for mozinfo 16:41:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for mozInstall 16:41:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for mozleak 16:41:35 INFO - Running setup.py install for mozlog 16:41:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:41:35 INFO - Running setup.py install for moznetwork 16:41:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Running setup.py install for mozprocess 16:41:36 INFO - Running setup.py install for mozprofile 16:41:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Running setup.py install for mozrunner 16:41:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Running setup.py install for mozscreenshot 16:41:36 INFO - Running setup.py install for moztest 16:41:36 INFO - Running setup.py install for mozversion 16:41:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:41:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:41:36 INFO - Cleaning up... 16:41:36 INFO - Return code: 0 16:41:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:36 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:36 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 16:41:36 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 16:41:36 INFO - Using env: {'DISPLAY': ':0', 16:41:36 INFO - 'HOME': '/home/cltbld', 16:41:36 INFO - 'LANG': 'en_US.UTF-8', 16:41:36 INFO - 'LANGUAGE': 'en_US:en', 16:41:36 INFO - 'LOGNAME': 'cltbld', 16:41:36 INFO - 'MAIL': '/var/mail/cltbld', 16:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:36 INFO - 'MOZ_NO_REMOTE': '1', 16:41:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:36 INFO - 'NO_EM_RESTART': '1', 16:41:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:36 INFO - 'PWD': '/builds/slave/test', 16:41:36 INFO - 'SHELL': '/bin/bash', 16:41:36 INFO - 'SHLVL': '1', 16:41:36 INFO - 'TERM': 'linux', 16:41:36 INFO - 'TMOUT': '86400', 16:41:36 INFO - 'USER': 'cltbld', 16:41:36 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:36 INFO - '_': '/tools/buildbot/bin/python'} 16:41:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:36 INFO - Running setup.py (path:/tmp/pip-DA_GI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:37 INFO - Running setup.py (path:/tmp/pip-DqyAJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:37 INFO - Running setup.py (path:/tmp/pip-oX3TD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:37 INFO - Running setup.py (path:/tmp/pip-psdfFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:37 INFO - Running setup.py (path:/tmp/pip-_Lw9vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:37 INFO - Running setup.py (path:/tmp/pip-mCI188-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:37 INFO - Running setup.py (path:/tmp/pip-bXTTQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:37 INFO - Running setup.py (path:/tmp/pip-9sua4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:37 INFO - Running setup.py (path:/tmp/pip-G1Fshe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:37 INFO - Running setup.py (path:/tmp/pip-n0xw87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:37 INFO - Running setup.py (path:/tmp/pip-ZYPnTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:37 INFO - Running setup.py (path:/tmp/pip-jGD3V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:37 INFO - Running setup.py (path:/tmp/pip-czISFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:37 INFO - Running setup.py (path:/tmp/pip-lQRpyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:37 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)) 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:37 INFO - Running setup.py (path:/tmp/pip-S_3AON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:38 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)) 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:38 INFO - Running setup.py (path:/tmp/pip-_3ojg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:38 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)) 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:38 INFO - Running setup.py (path:/tmp/pip-qheArM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:38 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)) 16:41:38 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)) 16:41:38 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)) 16:41:38 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)) 16:41:38 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)) 16:41:38 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)) 16:41:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:40 INFO - Downloading blessings-1.6.tar.gz 16:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:41:40 INFO - Installing collected packages: blessings 16:41:40 INFO - Running setup.py install for blessings 16:41:40 INFO - Successfully installed blessings 16:41:40 INFO - Cleaning up... 16:41:40 INFO - Return code: 0 16:41:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:41:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:40 INFO - Reading from file tmpfile_stdout 16:41:40 INFO - Current package versions: 16:41:40 INFO - argparse == 1.2.1 16:41:40 INFO - blessings == 1.6 16:41:40 INFO - blobuploader == 1.2.4 16:41:40 INFO - docopt == 0.6.1 16:41:40 INFO - manifestparser == 1.1 16:41:40 INFO - mozInstall == 1.12 16:41:40 INFO - mozcrash == 0.17 16:41:40 INFO - mozdebug == 0.1 16:41:40 INFO - mozdevice == 0.48 16:41:40 INFO - mozfile == 1.2 16:41:40 INFO - mozhttpd == 0.7 16:41:40 INFO - mozinfo == 0.9 16:41:40 INFO - mozleak == 0.1 16:41:40 INFO - mozlog == 3.1 16:41:40 INFO - moznetwork == 0.27 16:41:40 INFO - mozprocess == 0.22 16:41:40 INFO - mozprofile == 0.28 16:41:40 INFO - mozrunner == 6.11 16:41:40 INFO - mozscreenshot == 0.1 16:41:40 INFO - mozsystemmonitor == 0.0 16:41:40 INFO - moztest == 0.7 16:41:40 INFO - mozversion == 1.4 16:41:40 INFO - psutil == 3.1.1 16:41:40 INFO - requests == 1.2.3 16:41:40 INFO - wsgiref == 0.1.2 16:41:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:40 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:40 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 16:41:40 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 16:41:40 INFO - Using env: {'DISPLAY': ':0', 16:41:40 INFO - 'HOME': '/home/cltbld', 16:41:40 INFO - 'LANG': 'en_US.UTF-8', 16:41:40 INFO - 'LANGUAGE': 'en_US:en', 16:41:40 INFO - 'LOGNAME': 'cltbld', 16:41:40 INFO - 'MAIL': '/var/mail/cltbld', 16:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:40 INFO - 'MOZ_NO_REMOTE': '1', 16:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:40 INFO - 'NO_EM_RESTART': '1', 16:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:40 INFO - 'PWD': '/builds/slave/test', 16:41:40 INFO - 'SHELL': '/bin/bash', 16:41:40 INFO - 'SHLVL': '1', 16:41:40 INFO - 'TERM': 'linux', 16:41:40 INFO - 'TMOUT': '86400', 16:41:40 INFO - 'USER': 'cltbld', 16:41:40 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:40 INFO - '_': '/tools/buildbot/bin/python'} 16:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 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)) 16:41:40 INFO - Cleaning up... 16:41:40 INFO - Return code: 0 16:41:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:41:40 INFO - Using env: {'DISPLAY': ':0', 16:41:40 INFO - 'HOME': '/home/cltbld', 16:41:40 INFO - 'LANG': 'en_US.UTF-8', 16:41:40 INFO - 'LANGUAGE': 'en_US:en', 16:41:40 INFO - 'LOGNAME': 'cltbld', 16:41:40 INFO - 'MAIL': '/var/mail/cltbld', 16:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:40 INFO - 'MOZ_NO_REMOTE': '1', 16:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:40 INFO - 'NO_EM_RESTART': '1', 16:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:40 INFO - 'PWD': '/builds/slave/test', 16:41:40 INFO - 'SHELL': '/bin/bash', 16:41:40 INFO - 'SHLVL': '1', 16:41:40 INFO - 'TERM': 'linux', 16:41:40 INFO - 'TMOUT': '86400', 16:41:40 INFO - 'USER': 'cltbld', 16:41:40 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:40 INFO - '_': '/tools/buildbot/bin/python'} 16:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:41 INFO - Downloading/unpacking jsonschema 16:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:41:42 INFO - Downloading/unpacking functools32 (from jsonschema) 16:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:43 INFO - Downloading functools32-3.2.3-2.tar.gz 16:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:41:43 INFO - warning: no files found matching '*.txt' 16:41:43 INFO - no previously-included directories found matching 'build' 16:41:43 INFO - no previously-included directories found matching 'dist' 16:41:43 INFO - no previously-included directories found matching '.git*' 16:41:43 INFO - Installing collected packages: jsonschema, functools32 16:41:43 INFO - Running setup.py install for functools32 16:41:43 INFO - warning: no files found matching '*.txt' 16:41:43 INFO - no previously-included directories found matching 'build' 16:41:43 INFO - no previously-included directories found matching 'dist' 16:41:43 INFO - no previously-included directories found matching '.git*' 16:41:43 INFO - Successfully installed jsonschema functools32 16:41:43 INFO - Cleaning up... 16:41:43 INFO - Return code: 0 16:41:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:43 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 0x7fb3117b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, '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': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', '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 16:41:43 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 16:41:43 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 16:41:43 INFO - Using env: {'DISPLAY': ':0', 16:41:43 INFO - 'HOME': '/home/cltbld', 16:41:43 INFO - 'LANG': 'en_US.UTF-8', 16:41:43 INFO - 'LANGUAGE': 'en_US:en', 16:41:43 INFO - 'LOGNAME': 'cltbld', 16:41:43 INFO - 'MAIL': '/var/mail/cltbld', 16:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:43 INFO - 'MOZ_NO_REMOTE': '1', 16:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:43 INFO - 'NO_EM_RESTART': '1', 16:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:43 INFO - 'PWD': '/builds/slave/test', 16:41:43 INFO - 'SHELL': '/bin/bash', 16:41:43 INFO - 'SHLVL': '1', 16:41:43 INFO - 'TERM': 'linux', 16:41:43 INFO - 'TMOUT': '86400', 16:41:43 INFO - 'USER': 'cltbld', 16:41:43 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:43 INFO - '_': '/tools/buildbot/bin/python'} 16:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:43 INFO - Downloading/unpacking flake8 16:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:41:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:41:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:41:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:46 INFO - no previously-included directories found matching 'docs/_build' 16:41:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:41:46 INFO - Running setup.py install for pep8 16:41:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:46 INFO - no previously-included directories found matching 'docs/_build' 16:41:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:41:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:41:46 INFO - Cleaning up... 16:41:46 INFO - Return code: 0 16:41:46 INFO - Running post-action listener: _resource_record_post_action 16:41:46 INFO - Running post-action listener: _start_resource_monitoring 16:41:46 INFO - Starting resource monitoring. 16:41:46 INFO - ##### 16:41:46 INFO - ##### Finished create-virtualenv step (success) 16:41:46 INFO - ##### 16:41:46 INFO - ##### 16:41:46 INFO - ##### Running install step. 16:41:46 INFO - ##### 16:41:46 INFO - Running pre-action listener: _resource_record_pre_action 16:41:46 INFO - Running main action method: install 16:41:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:46 INFO - Reading from file tmpfile_stdout 16:41:46 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:46 INFO - Reading from file tmpfile_stdout 16:41:46 INFO - Output received: 16:41:46 INFO - Usage: mozinstall [options] installer 16:41:46 INFO - Options: 16:41:46 INFO - -h, --help show this help message and exit 16:41:46 INFO - -d DEST, --destination=DEST 16:41:46 INFO - Directory to install application into. [default: 16:41:46 INFO - "/builds/slave/test"] 16:41:46 INFO - --app=APP Application being installed. [default: firefox] 16:41:46 INFO - mkdir: /builds/slave/test/build/application 16:41:46 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'] 16:41:46 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 16:42:02 INFO - Reading from file tmpfile_stdout 16:42:02 INFO - Output received: 16:42:02 INFO - /builds/slave/test/build/application/firefox/firefox 16:42:02 INFO - Running post-action listener: _resource_record_post_action 16:42:02 INFO - ##### 16:42:02 INFO - ##### Finished install step (success) 16:42:02 INFO - ##### 16:42:02 INFO - ##### 16:42:02 INFO - ##### Running run-tests step. 16:42:02 INFO - ##### 16:42:02 INFO - Running pre-action listener: _resource_record_pre_action 16:42:02 INFO - Running main action method: run_tests 16:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:42:02 INFO - Python 2.7.3 16:42:02 INFO - Return code: 0 16:42:02 INFO - grabbing minidump binary from tooltool 16:42:02 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')]} 16:42:02 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 0x16609c0>, '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 0x16618c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1661d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:42:02 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 16:42:02 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 16:42:02 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 16:42:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:42:02 INFO - Return code: 0 16:42:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:42:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:42:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:42:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:42:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:42:03 INFO - Return code: 0 16:42:03 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:42:03 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:42:03 INFO - Using env: {'DISPLAY': ':0', 16:42:03 INFO - 'HOME': '/home/cltbld', 16:42:03 INFO - 'LANG': 'en_US.UTF-8', 16:42:03 INFO - 'LANGUAGE': 'en_US:en', 16:42:03 INFO - 'LOGNAME': 'cltbld', 16:42:03 INFO - 'MAIL': '/var/mail/cltbld', 16:42:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:42:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:03 INFO - 'MOZ_NO_REMOTE': '1', 16:42:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:42:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:42:03 INFO - 'NO_EM_RESTART': '1', 16:42:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:03 INFO - 'PWD': '/builds/slave/test', 16:42:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:42:03 INFO - 'SHELL': '/bin/bash', 16:42:03 INFO - 'SHLVL': '1', 16:42:03 INFO - 'TERM': 'linux', 16:42:03 INFO - 'TMOUT': '86400', 16:42:03 INFO - 'USER': 'cltbld', 16:42:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113', 16:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:03 INFO - '_': '/tools/buildbot/bin/python'} 16:42:03 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:42:03 INFO - mozversion application_buildid: 20160510152113 16:42:03 INFO - mozversion application_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 16:42:03 INFO - mozversion application_display_name: Nightly 16:42:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:42:03 INFO - mozversion application_name: Firefox 16:42:03 INFO - mozversion application_remotingname: firefox 16:42:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:42:03 INFO - mozversion application_vendor: Mozilla 16:42:03 INFO - mozversion application_version: 49.0a1 16:42:03 INFO - mozversion platform_buildid: 20160510152113 16:42:03 INFO - mozversion platform_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 16:42:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:42:03 INFO - mozversion platform_version: 49.0a1 16:42:03 INFO - using testdate: 1462923723 16:42:03 INFO - actual date: 1462923723 16:42:03 INFO - starting webserver on 'localhost:43112' 16:42:03 INFO - SUITE-START | Running 2 tests 16:42:03 INFO - TEST-START | dromaeo_css 16:42:03 INFO - operating with platform_type : linux_ 16:42:03 INFO - Initialising browser for dromaeo_css test... 16:42:03 INFO - TEST-INFO | started process 19376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGnxd8e/profile http://localhost:43112/getInfo.html) 16:42:07 INFO - PROCESS | 19376 | 16:42:07 INFO - PROCESS | 19376 | (process:19445): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:42:08 INFO - PROCESS | 19376 | __metrics Screen width/height:1600/1200 16:42:08 INFO - PROCESS | 19376 | colorDepth:24 16:42:08 INFO - PROCESS | 19376 | Browser inner width/height: 1024/697 16:42:08 INFO - PROCESS | 19376 | __metrics 16:42:08 ERROR - PROCESS | 19376 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 16:42:09 INFO - TEST-INFO | 19376: exit 0 16:42:09 INFO - Browser initialized. 16:42:09 INFO - Running cycle 1/1 for dromaeo_css test... 16:42:09 INFO - TEST-INFO | started process 19496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGnxd8e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:42:11 INFO - PROCESS | 19496 | 16:42:11 INFO - PROCESS | 19496 | (process:19555): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:42:21 INFO - PROCESS | 19496 | RSS: Main: 164614144 16:42:21 INFO - PROCESS | 19496 | 16:43:11 INFO - PROCESS | 19496 | 1462923791285 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] 16:45:23 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43112/tests/dromaeo/cssquery-ext.html) 16:45:24 INFO - PROCESS | 19496 | RSS: Main: 166170624 16:45:24 INFO - PROCESS | 19496 | 16:48:26 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-ext.html (next: http://localhost:43112/tests/dromaeo/cssquery-jquery.html) 16:48:27 INFO - PROCESS | 19496 | RSS: Main: 166514688 16:48:27 INFO - PROCESS | 19496 | 16:51:28 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43112/tests/dromaeo/cssquery-mootools.html) 16:51:29 INFO - PROCESS | 19496 | RSS: Main: 167485440 16:51:29 INFO - PROCESS | 19496 | 16:54:31 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43112/tests/dromaeo/cssquery-prototype.html) 16:54:32 INFO - PROCESS | 19496 | RSS: Main: 165457920 16:54:32 INFO - PROCESS | 19496 | 16:57:34 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43112/tests/dromaeo/cssquery-yui.html) 16:57:35 INFO - PROCESS | 19496 | RSS: Main: 166608896 16:57:35 INFO - PROCESS | 19496 | 17:00:37 INFO - PROCESS | 19496 | Cycle 1(1): loaded http://localhost:43112/tests/dromaeo/cssquery-yui.html (next: http://localhost:43112/tests/dromaeo/cssquery-dojo.html) 17:00:38 INFO - PROCESS | 19496 | RSS: Main: 168476672 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | __start_tp_report 17:00:38 INFO - PROCESS | 19496 | _x_x_mozilla_page_load 17:00:38 INFO - PROCESS | 19496 | _x_x_mozilla_page_load_details 17:00:38 INFO - PROCESS | 19496 | |i|pagename|runs| 17:00:38 INFO - PROCESS | 19496 | |0;dojo.html;2639.894404223831;2694.5823397373415;2704.7430494103064;2710.6747190337164;2715.940783094593;4785.736784476852;4788.401449818768;4825.541573550511;4905.533974272447;4931.6301277404245;4714.50497697742;4844.830430934918;4876.829310974116;4885.609151267899;4891.4374846892615;4391.868243952678;4447.444069491314;4521.751303678297;4538.568835960595;4539.568740969619;4462.1968045751755;4551.749653769041;4572.08558288342;4589.403377560912;4605.792739326738;4357.150355680644;4371.606555410029;4430.490493593245;4476.01527663915;4497.5952164305045;4433.534478879736;4437.267850804614;4452.532484089156;4480.171168333869;4528.705634133804;4461.955380446187;4464.843730469451;4475.082608065339;4485.977570112162;4521.683482156251;4709.788059537329;4712.976435117836;4737.194676904935;4765.356676848651;4822.204336284509;5210.76551555181;5228.08508511009;5239.083160446945;5242.5281724644965;5248.871492629103;5260.184671375943;5262.23697563855;5262.289590905217;5265.973670131663;5280.709560974145;4723.984343366193;4737.26572381278;4766.9274413257435;4781.545753153445;4783.736894470768;5232.241325007929;5244.659097158748;5255.054090263713;5260.947390526122;5316.9202461963105;4989.176785830297;5046.621503387188;5047.066292735855;5052.090623687698;5059.62052846038;5176.870578235575;5185.533302002837;5209.140491818884;5236.24074509194;5246.554042906396;5025.773840177201;5047.4447810740785;5059.797608095709;5108.540231379194;5123.436421993578;5077.26379674945;5091.0836049511445;5093.9490605094215;5094.235864620263;5103.744812759422;4316.762578058174;4363.367311739843;4364.694471387036;4370.868873933787;4375.7374557526655;3765.5293088363956;3778.7732736035377;3847.769133851978;3850.4801851750217;3866.052817059838;4332.870013899588;4369.9126017479475;4383.189110014594;4399.472063352418;4401.801918913657;5080.23796430531;5084.3898732152375;5086.135356989321;5086.21163719624;5125.589952803767;4374.78126093694;4381.627561657295;4386.758728269912;4432.933505997413;4447;4514.571115743999;4528.547145285445;4533.478649955297;4534.478534968521;4585.908281834345;4822.156122678517;4884.829030983828;4893.804247830047;4918.606511479073;4954.727489988085;5074.82238121664;5161.483851614808;5178.97410512945;5238;5284.075286824714;4977.9502204978935;5006.324146240211;5009.223570346602;5021.5229553192385;5023.070731914461;5249.501297376743;5262.89474210506;5272.05103081449;5273.630845840754;5300.973495132654;5016.59867210615;5046.34397528319;5209.557187639093;5230.450802665665;5348.064088784369;5093.694378337312;5118.641695081308;5125.974370128275;5132.461091585479;5152.330197074356;5073.644844860972;5101.056304583664;5134.358205224347;5139.357580302462;5181;4868.513148685103;4891.461939186758;4906.533879281463;5034.244863270393;5049.6465247432325;5133.640645154804;5135.3324067870935;5144.871378215575;5150.253213284128;5152.819651312186;4977.4773648767805;4982.601391888567;5014.17266151081;5028.9245661315845;5045.873853153554 17:00:38 INFO - PROCESS | 19496 | |1;ext.html;583.5156819839534;605.984975166596;614.5329549542347;622.1010639625741;640.2157357237388;35797.46303805445;40098.59901400985;40099.39850902235;40103.398449023254;40143.79928100363;39386.21227575454;40169.79915100428;40368.59631403693;40461;40482;7805.960970195141;7847.764567062969;7933.603319834014;7959.1244963054305;7970.083440404341;7760.146383897795;7780.105287891908;7822.178671239523;7871.370290376784;7871.488353257048;7836.098848632395;7875.212478752108;7909.446338756289;7912.446128770988;7926.167752386003;7761.107472640634;7780.533168009937;7814.1013783415065;7859.8035049123655;7878.290953814127;7659.6936122555035;7662.540247585162;7686.730964416274;7717.575533345664;7722.42081843864;37478.25043499115;37701.245975080616;37927;37964.24071518582;38061.429078563844;3195.536647186145;3219.356128774231;3222.935541289161;3342.699157075875;3482.495038219444;29446.116616501324;29642;29889.402211955858;30008.699913001026;30952.380952381052;2953.7932344735877;2996.3857409231055;2997.865096070682;3040.3767227718263;3072.170513961218;245669;246928;247713.52286477265;248833;249201.9999999982;33554;34199.829000854836;53194.202086968726;53732;53739.6565085876;35261.294774104375;35520.11199720028;35548.64451355505;35632.64367356293;35671.46492802558;13368.799468007988;13611.591652250449;13622.114562553403;13629.045966782429;13629.795553066711;10693.19801014927;10697.83953240702;10703.304285221435;10712.143028557697;10726.356418614909;8354.958225208835;8370.162983701703;8373.204545568162;8449.32405407566;8486.872696909551;3257.3159636476607;3282.720968717638;3294.9176270593425;3304.9173770655925;3341.665833416639;941.021337808685;960.870282511852;1041.9791604167874;1047.2983101322131;1060.6234786650627;28757.137285881458;28874.855625721735;28939.85530072336;29250.707492925223;29775.702242977728;916.1525588830306;1006.8389057750725;1065.5471424644495;1111.5109351885144;1138.4649214868998;14446.277686115653;14591.416343346147;14609.123452592878;14768.52314768536;14917.750460241159;354439;356900;362110;363388;364336;49672.50327496751;49935.50064499381;50073.74963125161;50085.24872126848;50202.246966295534;1258.4337048328289;1282.3011458755002;1283.557172775391;1286.6397408725384;1288.890444312225;25702.614460783476;27181.86409067942;27245;27424.45151096927;27600.861995689895;37650.43524347192;37767.244655106195;37776.81111594424;37777.81111094427;37798.81100594479;306899;310619;311974;312142;313423;25606;25664.8716756415;25687.229383118538;25850.61224081678;25972.610410843474;42764.14471710611;43135.784321078194;43191.35212971745;44151.33772993472;44335.11329773451;37007.6299237015;37397;37400.25199496049;37733.81133094317;38158;36806.26387472182;36820.447693284084;37178;37504.81247593745;37510.43734344042;31962.84018579985;35987.64012359948;37626;37679.81160094182;38318.23363532769;32504.83747581247;33700.66299337073;33876.83061584676;33946.3210735779;34116.58834116568;25509.61735574005;25530.74469255358;25669.743302566734;25939.09213177605;26440.074597389 17:00:38 INFO - PROCESS | 19496 | |2;jquery.html;380.72397511803945;385.4545817668002;386.05236581446593;400.87773229165094;407.7207113127508;15339.463118790845;17522.82477175231;18094.819051809478;18207.362742304023;18263.634727305463;17010.48968530943;17285.91357043213;17599;17623.559411014732;17712.64574708505;5049.722265275408;5183.688978661274;5217.165253559431;5229.163333866563;5241.68549887008;3677.4483827425966;5777.797777077803;6052.425019623129;6053.939460605382;6177.382261773809;5918.631304026471;5990.281166260055;6095.725692343833;6105.969470152643;6113.174721412619;6034.96982515089;6127.111568822518;6134.2332208473945;6145.877082458348;6148.231471066117;4660.650451216173;4952.306677065214;4979.253112033224;5266.552343050846;5332.226827110047;11773.470193841213;11871.52513899443;12000.939995300056;12990.740185196337;20478.488037799027;1822.8906265624105;1825.488863118329;1833.7982821889716;1874.9531261718566;1899.335232668555;8647.827043459096;8823.470591764515;8944.060873608243;11239.775204495865;12938.353082345846;1649.488658515864;1711.2213942656185;1734.3669560610413;1747.921343539531;1766.4612293250555;68288.6585567069;70205.2979470209;72186;72518.27481725167;72697.63651181763;9565.234781217487;9871.851922221173;10080.193584513221;10171.525128856423;10515.737106572396;14279.357428915724;14352.138871667734;14380.928095359666;14384.496542620958;14393.568192954228;7197.100362454693;7201.099862517185;7228.566286022856;7404.37062849653;7423.62881855905;6835.213950395768;6925.3074692530345;6939.791806245821;6962.373386395248;7009.894851577231;4396.780160992002;4415.66882483815;4453.576910193527;4485.125400546862;4490.281554951192;1630.5026966775247;1773.3527262549335;1868.4301288106985;1901.6386886491798;1913.3112079651314;816.4692949582819;855.6876739989859;930.55798495714;982.729749318943;1047.7694907120422;16562.917185414237;16583.83416165847;16725.330986760393;17003.74494382585;17011.574710632332;883.3507372081515;916.6333466613274;922.2529750901791;931.8415869302099;933.4819175357742;9483.76290592727;9498.240140788721;9539.284553658503;9594.136527712539;9674.22606191503;401178;406635;407836;410869;423536;77503;79411.60294198492;80158;80819;81704;935.6771913689767;946.6544711180508;950.9049095090573;967.4485543240286;967.4823969176457;14910.77633835515;15037.473688420852;15146.166960817261;15257.237138142826;15266.236688165325;34855;35303.47044794279;35474.64525354713;36396.27207455889;36602.084947876516;48452.273215901085;49070.263946040126;49120;49120.75439622779;49607;7433.776986690408;7709.267619576131;7714.652840622073;7732.7680169595005;7761.805954851174;8283.420160588701;8418.705345312885;8452.915470845213;8458.492490450346;8809.383343166173;6394.976803711384;7529.623518824146;7586.089669239726;7838.412119090977;8022.719204828037;6410.967945160244;7863.095743989515;7890.421915617015;7965.322947549524;8071.878921816295;8013.919860801318;8072;8103.189681031849;8252.958735206286;8308.127646597262;11325.433728313386;11485.253458525169;11607.08483100279;11733.530658773554;12267.447964841425;10797.676069717922;11260.662180134608;11429.199956003333;11461.541538338373;11625.18623696367 17:00:38 INFO - PROCESS | 19496 | |3;mootools.html;1508.1629695518982;1510.116581799647;1697.7708009418732;1834.2296235581055;1854.8794328368656;6507.186601674791;7140.928590714091;7659.8085047873765;8199.344052475803;8244.587770611468;7151.284871512846;7379.8155046123875;7383.852322953538;7394.889076663854;7406.259374062591;2158.201465457779;2195.2536137713173;2203.6474164133742;2208.7349518057854;2209.0832304593573;2148.5380643161716;2179.923702670407;2198.6152423325934;2199.439143018528;2199.7690242524545;2121.734783152106;2128.744550653924;2137.561799831031;2167.729033870766;2236.8769717665523;2116.174691870172;2125.2136709417537;2125.2667829598763;2130.6164890319737;2131.6269652810697;2066.9689954650694;2092.215419217793;2139.6576547752284;2150.5698860227862;2174.8912554372378;7399.482036257464;7413.8517229655645;7423.591702456362;7428.777136685908;7432.182459929403;4670.182718024332;4760.619150467989;4788.569028787391;4809.591184749301;4816.397950256218;2898.30440694234;2988.955165672495;3071.5546245794458;3092.597962264891;3099.3491366812996;887.8401887660218;928.5960607135918;928.9071092890724;945.2249155692335;960.7502049467118;10177.643782467578;10506.78986420275;10533.683989480327;10750.838737418944;10930.617428389969;4178.791060446965;4206.390073439334;4304.440422745023;4338.76136812472;4352.673549483801;8474.830503389898;8548.700795472128;8563.614637341334;8569.143085691505;8594.140585941355;4594.6783725139585;4615.23848564983;4617.630589552828;4630.073985202973;4637.721736695809;3755.8497660093835;4300.741955482681;4311.8706438806885;4396.07682386696;4440.178566965122;3495.4582039783368;3530.735194860396;3590.7486475946434;3618.0593045808278;3618.6924111450294;1051.222095649214;1065.0521036277719;1065.520515767892;1067.32225037102;1067.5089458849016;743.2678811370796;875.4790899414838;912.2610685344891;922.9723108306762;949;5326.307580014651;5486.506214440719;5534.418886016911;5669.943300566942;5696.51579615729;406.8942075060506;407.2526913114468;424.8194517330122;426.79856204783437;430.3866989539893;3877.41838724188;3907.1208977980295;3954.703397245218;3989.940150897739;4012.0371110933747;9198.49408282551;9626.326157169071;9821.656242031495;9849.310548261696;9899.65351212704;9618.951905240569;9687.41875487473;9729.416235025921;9742.95128524353;9826.508674566243;7308.59802710856;7369.22623124575;7410.777676669709;7529.397648188132;7549.433792465477;5448.673079615236;5496.972515137399;5498.835034948958;5547.306586676666;5663.85840353978;8901.910980890108;8988.370814043215;9071.501067441353;9090.727278181665;9163.633454661738;8937.195652391316;9394.624215031323;9399;9415.670451534163;9442.622295108118;5686;6500.187476565429;6525.445337146396;6938.167419909643;7222.241664625139;7275.818104547428;7475.850482990201;7510.737124200627;7537.698492060256;7572.8485430292185;6654.700538475683;6832.453403727789;6877.9656101721175;6898.758543450955;6941.271166527442;6688.899666504909;6701.32986701326;6771.62756048422;6777.728890844311;6965.895511567432;6300.464460520907;6309.558330916791;6368.299487056327;6386.457151142205;6411.4870810336;4592.311153327028;4739.47865734762;4770.570648641639;4824.758762061953;4876.902461950812;4772.976135119302;4798.880027999188;4828.03439312146;4852.126617208936;4917.360743103517 17:00:38 INFO - PROCESS | 19496 | |4;prototype.html;306.3184414677343;306.831242816451;309.16525381470035;309.8481743945466;311.6602902835908;4979.377577802775;5013.6741111827705;5028.566858445334;5040.697558146514;5044.319016932722;4954.826581069663;4959.752012399384;4981.25281207819;4988.551030407262;5005.696240481283;3639.2903383840094;3782.9621703782886;3904.6681032112306;3906.8046597670145;3909.257241124177;3886.892235712822;3896.902577435558;3912.7065470089856;3916.5887581803927;3931.267768900615;3878.1855810279735;3889.436031775391;3891.5524714657754;3893.240818040483;3917.099067214542;3790.7346485746007;3884.465636073735;3893.279743247508;3904.3557812960826;3904.960950390488;3442.8278586070596;3457.4986626938953;3459.4637831136215;3466.6360032196762;3472.861085556575;4729.976350118227;4737.194676904935;4741;4770.141374552579;4770.689905156154;4445.748442247532;4463.727837566278;4469.262571675669;4497.280435130363;4510.639148868083;5067.39191297043;5170.146925757245;5184.611154163415;5202.583793296527;5250.002499525078;3820.808959552039;3832.7317087803863;3835.42468629705;3835.9041023974623;3842.8270727817317;5413.2692086568595;5444.40111587725;5446.754896029688;5480.123180291134;5484.698341591171;3574.6067932527403;3850.9807450962567;3858.2862170498547;3862.1310205204168;3868.941965870514;3866.9806650966566;3871.6189543430587;3881.1461478475253;3887.067103895045;3896.7661940283674;4401.779911004437;4419.469663640384;4423.380726698265;4440.977795111004;4468.396766436489;5099;5258.2638430619745;5280.260763493114;5294.152935530374;5295.285136506599;1319.070055610792;1328.249539010456;1330.6074707961175;1332.433715670836;1332.826732524785;812.9105798362175;820.2084987986622;823.9629216685264;831.7546323834483;845.2097289034772;4501.527339629291;4587.9082418351445;4609.53904609543;4613.0543238636;4614.353990441341;5363.356397232357;5368.248445217672;5382.896506216216;5443.8094666686475;5448.83653490396;4523.344115103291;4581.633469322446;4616.561426664462;4619.983603607201;4620.607248383925;2860.2277385105904;2892.783041271913;2895.261708264421;2904.2158617173245;2909.8254104753782;5066.569341605931;5078.568321692624;5086.135356989321;5089.3638295213095;5118.539331460112;5347.652402593819;5418.155468379054;5472.425395333433;5527.723613819293;5565.387807341189;5051.87370315745;5070.619703522177;5077;5080.263361812516;5149.845504634867;5357.785688572413;5359.946400535945;5374.328208973879;5433.646812957145;5438.728063596884;5032.874178145576;5239.214117882349;5274.498922602346;5286.233496143114;5292.126799078109;5066.138756411493;5068.2397640352765;5074.949250507595;5084.466131056334;5090.338256026693;3772.698184145207;3778.9433158502093;3780.640839120279;3786.242751449666;3793.279276937373;5237.973810130925;5287.68273903567;5375.381831089318;5449.373322068013;5457.672539647634;5143.76549628094;5219.582433405242;5234.712090835041;5240.606954478506;5283.524482796567;5160.174372100446;5203.427622961547;5222.660527065728;5251.422343542131;5253.294553304974;4963.4540200577185;4992.72540010303;5136.2552429896705;5140.331756871583;5157.819476318311;5009.9248511271635;5103.157978933435;5256.421793602624;5270.051390749549;5279.234510995961;5064.72144032067;5068.569171620454;5085.491450855033;5112.491814914535;5132.153194722829 17:00:38 INFO - PROCESS | 19496 | |5;yui.html;705.2207310921432;780.7462574663234;818.4557269415839;840.1262686805723;845.6659619450318;11202.439878006098;11779.941100294507;11829.11281653875;11829.763404731912;11846.17076804624;10919.235653504258;10994.780104397907;11044.668659940195;11109.555617775279;11136.443177841116;679.7383007542082;850.4897061762955;862.357543629995;876.8860048193726;877.929765618749;854.5940678177878;856.4946681457939;857.0786404615033;857.8327226190895;863.7754183912165;910.8724778531011;911.8677791720197;914.5564401265381;914.6981496106268;917.6191880369638;899.7840518275632;900.6757567275776;905.3345790843757;906.125588806798;906.2840356118659;665.2549144957678;669.4878418010226;670.1054092786136;671.942884854788;673.6160388578512;11312.660620181408;11357.432128393548;11377.488013039432;11386.658400248005;11398;12383.442745076492;13067.934660326733;13270.73458530824;13276.137051091648;13522.864771352259;10427.217958653131;10439.686809395731;10500.36997780128;10667.19996000303;10714.785704285949;3368.5957685077947;3436.8453419596176;3440.36353274645;3447.793132412063;3533.09905973975;38763.61236387628;40007.19985600301;46830.29754553684;46982.53017469816;47135.5286447138;9412.294077944181;10143.18854491639;10231.693049208536;10273.537690803932;10290.073893349485;16712.665746684997;16758.664826703396;16882.831171688373;16886.40897568579;16991.49025529236;6692.364225398579;6839.829004274933;7067.823304417328;7130.144382674009;7146.927960805817;3762.6024335733796;3926.823292951824;3964.781936993493;3975.6223158799867;4004.979975100164;2751.2296556964084;2799.5520716685232;2804.4110736745106;2820.1539538138886;2828.858557072138;533.751805410486;536.6270442042743;541.5992165797281;549.7910793898373;555.7304707216997;533.3679589686234;563.5604228701619;627.1971875998729;632.4750457120579;647.9643619600968;7935.64289606969;7955.323797477164;8108.432409731262;8234.217749313806;8381.329493640495;508.09305389878887;538.0959987221428;621.2110619513193;658.4699317049725;679.4428568573722;3822.6559609635265;3862.5558060823364;3876.3022655922205;3920.6079392060565;3926.960730392717;48283;48931.75534122306;49257;49573.50426495761;49583;43025;45410.54589454063;45681.31478027832;45900.770496147976;47016.7649161752;41229.793851030554;41848.744537663915;42040.94897627523;42073.368899466535;42167.789161054614;7472;7581.924180758122;7662.923370766221;7675;7710.575918324322;27894.721052789213;28082.297942550784;28306.15081547557;28501.144965651063;28800.567991480562;36227.818860906584;36230.637693622724;36797.08007299732;37446.25107497889;37550.24899502049;9437.480938548446;9440;9682.370645907993;9723;9743.415395076318;10155.136813370946;10205.336653117523;10213;10548.683539493828;10554.472276386303;9989.700308990741;10052.246081544059;10062.647807326708;10077.798444031225;10086.546105425421;9148.222401095714;9388.765280868032;9787.755306117404;10266.281360304707;10743.89256107429;7903.604819758874;9647.179989700953;9654.324197306121;11264.718382040515;11611;9573.664921727706;9583.808323833624;9588.856167157359;9707.563159657691;9948.55231514598;9224.262059035127;9477.052294770469;9793;9844.753881153028;9877.111060004634 17:00:38 INFO - PROCESS | 19496 | __end_tp_report 17:00:38 INFO - PROCESS | 19496 | __start_cc_report 17:00:38 INFO - PROCESS | 19496 | _x_x_mozilla_cycle_collect,382 17:00:38 INFO - PROCESS | 19496 | __end_cc_report 17:00:38 INFO - PROCESS | 19496 | __startTimestamp1462924838125__endTimestamp 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | ------- Summary: start ------- 17:00:38 INFO - PROCESS | 19496 | Number of tests: 6 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#0] dojo.html Cycles:165 Average:4786.88 Median:4954.73 stddev:512.48 (10.3%) stddev-sans-first:485.59 17:00:38 INFO - PROCESS | 19496 | Values: 2639.9 2694.6 2704.7 2710.7 2715.9 4785.7 4788.4 4825.5 4905.5 4931.6 4714.5 4844.8 4876.8 4885.6 4891.4 4391.9 4447.4 4521.8 4538.6 4539.6 4462.2 4551.7 4572.1 4589.4 4605.8 4357.2 4371.6 4430.5 4476.0 4497.6 4433.5 4437.3 4452.5 4480.2 4528.7 4462.0 4464.8 4475.1 4486.0 4521.7 4709.8 4713.0 4737.2 4765.4 4822.2 5210.8 5228.1 5239.1 5242.5 5248.9 5260.2 5262.2 5262.3 5266.0 5280.7 4724.0 4737.3 4766.9 4781.5 4783.7 5232.2 5244.7 5255.1 5260.9 5316.9 4989.2 5046.6 5047.1 5052.1 5059.6 5176.9 5185.5 5209.1 5236.2 5246.6 5025.8 5047.4 5059.8 5108.5 5123.4 5077.3 5091.1 5093.9 5094.2 5103.7 4316.8 4363.4 4364.7 4370.9 4375.7 3765.5 3778.8 3847.8 3850.5 3866.1 4332.9 4369.9 4383.2 4399.5 4401.8 5080.2 5084.4 5086.1 5086.2 5125.6 4374.8 4381.6 4386.8 4432.9 4447.0 4514.6 4528.5 4533.5 4534.5 4585.9 4822.2 4884.8 4893.8 4918.6 4954.7 5074.8 5161.5 5179.0 5238.0 5284.1 4978.0 5006.3 5009.2 5021.5 5023.1 5249.5 5262.9 5272.1 5273.6 5301.0 5016.6 5046.3 5209.6 5230.5 5348.1 5093.7 5118.6 5126.0 5132.5 5152.3 5073.6 5101.1 5134.4 5139.4 5181.0 4868.5 4891.5 4906.5 5034.2 5049.6 5133.6 5135.3 5144.9 5150.3 5152.8 4977.5 4982.6 5014.2 5028.9 5045.9 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#1] ext.html Cycles:180 Average:45306.31 Median:196285.73 stddev:81500.05 (41.5%) stddev-sans-first:81659.51 17:00:38 INFO - PROCESS | 19496 | Values: 583.5 606.0 614.5 622.1 640.2 35797.5 40098.6 40099.4 40103.4 40143.8 39386.2 40169.8 40368.6 40461.0 40482.0 7806.0 7847.8 7933.6 7959.1 7970.1 7760.1 7780.1 7822.2 7871.4 7871.5 7836.1 7875.2 7909.4 7912.4 7926.2 7761.1 7780.5 7814.1 7859.8 7878.3 7659.7 7662.5 7686.7 7717.6 7722.4 37478.3 37701.2 37927.0 37964.2 38061.4 3195.5 3219.4 3222.9 3342.7 3482.5 29446.1 29642.0 29889.4 30008.7 30952.4 2953.8 2996.4 2997.9 3040.4 3072.2 245669.0 246928.0 247713.5 248833.0 249202.0 33554.0 34199.8 53194.2 53732.0 53739.7 35261.3 35520.1 35548.6 35632.6 35671.5 13368.8 13611.6 13622.1 13629.0 13629.8 10693.2 10697.8 10703.3 10712.1 10726.4 8355.0 8370.2 8373.2 8449.3 8486.9 3257.3 3282.7 3294.9 3304.9 3341.7 941.0 960.9 1042.0 1047.3 1060.6 28757.1 28874.9 28939.9 29250.7 29775.7 916.2 1006.8 1065.5 1111.5 1138.5 14446.3 14591.4 14609.1 14768.5 14917.8 354439.0 356900.0 362110.0 363388.0 364336.0 49672.5 49935.5 50073.7 50085.2 50202.2 1258.4 1282.3 1283.6 1286.6 1288.9 25702.6 27181.9 27245.0 27424.5 27600.9 37650.4 37767.2 37776.8 37777.8 37798.8 306899.0 310619.0 311974.0 312142.0 313423.0 25606.0 25664.9 25687.2 25850.6 25972.6 42764.1 43135.8 43191.4 44151.3 44335.1 37007.6 37397.0 37400.3 37733.8 38158.0 36806.3 36820.4 37178.0 37504.8 37510.4 31962.8 35987.6 37626.0 37679.8 38318.2 32504.8 33700.7 33876.8 33946.3 34116.6 25509.6 25530.7 25669.7 25939.1 26440.1 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#2] jquery.html Cycles:180 Average:24673.53 Median:5116.71 stddev:67701.30 (1323.1%) stddev-sans-first:67866.65 17:00:38 INFO - PROCESS | 19496 | Values: 380.7 385.5 386.1 400.9 407.7 15339.5 17522.8 18094.8 18207.4 18263.6 17010.5 17285.9 17599.0 17623.6 17712.6 5049.7 5183.7 5217.2 5229.2 5241.7 3677.4 5777.8 6052.4 6053.9 6177.4 5918.6 5990.3 6095.7 6106.0 6113.2 6035.0 6127.1 6134.2 6145.9 6148.2 4660.7 4952.3 4979.3 5266.6 5332.2 11773.5 11871.5 12000.9 12990.7 20478.5 1822.9 1825.5 1833.8 1875.0 1899.3 8647.8 8823.5 8944.1 11239.8 12938.4 1649.5 1711.2 1734.4 1747.9 1766.5 68288.7 70205.3 72186.0 72518.3 72697.6 9565.2 9871.9 10080.2 10171.5 10515.7 14279.4 14352.1 14380.9 14384.5 14393.6 7197.1 7201.1 7228.6 7404.4 7423.6 6835.2 6925.3 6939.8 6962.4 7009.9 4396.8 4415.7 4453.6 4485.1 4490.3 1630.5 1773.4 1868.4 1901.6 1913.3 816.5 855.7 930.6 982.7 1047.8 16562.9 16583.8 16725.3 17003.7 17011.6 883.4 916.6 922.3 931.8 933.5 9483.8 9498.2 9539.3 9594.1 9674.2 401178.0 406635.0 407836.0 410869.0 423536.0 77503.0 79411.6 80158.0 80819.0 81704.0 935.7 946.7 950.9 967.4 967.5 14910.8 15037.5 15146.2 15257.2 15266.2 34855.0 35303.5 35474.6 36396.3 36602.1 48452.3 49070.3 49120.0 49120.8 49607.0 7433.8 7709.3 7714.7 7732.8 7761.8 8283.4 8418.7 8452.9 8458.5 8809.4 6395.0 7529.6 7586.1 7838.4 8022.7 6411.0 7863.1 7890.4 7965.3 8071.9 8013.9 8072.0 8103.2 8253.0 8308.1 11325.4 11485.3 11607.1 11733.5 12267.4 10797.7 11260.7 11429.2 11461.5 11625.2 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#3] mootools.html Cycles:180 Average:5154.20 Median:5387.49 stddev:2867.28 (53.2%) stddev-sans-first:2862.24 17:00:38 INFO - PROCESS | 19496 | Values: 1508.2 1510.1 1697.8 1834.2 1854.9 6507.2 7140.9 7659.8 8199.3 8244.6 7151.3 7379.8 7383.9 7394.9 7406.3 2158.2 2195.3 2203.6 2208.7 2209.1 2148.5 2179.9 2198.6 2199.4 2199.8 2121.7 2128.7 2137.6 2167.7 2236.9 2116.2 2125.2 2125.3 2130.6 2131.6 2067.0 2092.2 2139.7 2150.6 2174.9 7399.5 7413.9 7423.6 7428.8 7432.2 4670.2 4760.6 4788.6 4809.6 4816.4 2898.3 2989.0 3071.6 3092.6 3099.3 887.8 928.6 928.9 945.2 960.8 10177.6 10506.8 10533.7 10750.8 10930.6 4178.8 4206.4 4304.4 4338.8 4352.7 8474.8 8548.7 8563.6 8569.1 8594.1 4594.7 4615.2 4617.6 4630.1 4637.7 3755.8 4300.7 4311.9 4396.1 4440.2 3495.5 3530.7 3590.7 3618.1 3618.7 1051.2 1065.1 1065.5 1067.3 1067.5 743.3 875.5 912.3 923.0 949.0 5326.3 5486.5 5534.4 5669.9 5696.5 406.9 407.3 424.8 426.8 430.4 3877.4 3907.1 3954.7 3989.9 4012.0 9198.5 9626.3 9821.7 9849.3 9899.7 9619.0 9687.4 9729.4 9743.0 9826.5 7308.6 7369.2 7410.8 7529.4 7549.4 5448.7 5497.0 5498.8 5547.3 5663.9 8901.9 8988.4 9071.5 9090.7 9163.6 8937.2 9394.6 9399.0 9415.7 9442.6 5686.0 6500.2 6525.4 6938.2 7222.2 7275.8 7475.9 7510.7 7537.7 7572.8 6654.7 6832.5 6878.0 6898.8 6941.3 6688.9 6701.3 6771.6 6777.7 6965.9 6300.5 6309.6 6368.3 6386.5 6411.5 4592.3 4739.5 4770.6 4824.8 4876.9 4773.0 4798.9 4828.0 4852.1 4917.4 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#4] prototype.html Cycles:180 Average:4339.64 Median:4990.64 stddev:1260.34 (25.3%) stddev-sans-first:1226.98 17:00:38 INFO - PROCESS | 19496 | Values: 306.3 306.8 309.2 309.8 311.7 4979.4 5013.7 5028.6 5040.7 5044.3 4954.8 4959.8 4981.3 4988.6 5005.7 3639.3 3783.0 3904.7 3906.8 3909.3 3886.9 3896.9 3912.7 3916.6 3931.3 3878.2 3889.4 3891.6 3893.2 3917.1 3790.7 3884.5 3893.3 3904.4 3905.0 3442.8 3457.5 3459.5 3466.6 3472.9 4730.0 4737.2 4741.0 4770.1 4770.7 4445.7 4463.7 4469.3 4497.3 4510.6 5067.4 5170.1 5184.6 5202.6 5250.0 3820.8 3832.7 3835.4 3835.9 3842.8 5413.3 5444.4 5446.8 5480.1 5484.7 3574.6 3851.0 3858.3 3862.1 3868.9 3867.0 3871.6 3881.1 3887.1 3896.8 4401.8 4419.5 4423.4 4441.0 4468.4 5099.0 5258.3 5280.3 5294.2 5295.3 1319.1 1328.2 1330.6 1332.4 1332.8 812.9 820.2 824.0 831.8 845.2 4501.5 4587.9 4609.5 4613.1 4614.4 5363.4 5368.2 5382.9 5443.8 5448.8 4523.3 4581.6 4616.6 4620.0 4620.6 2860.2 2892.8 2895.3 2904.2 2909.8 5066.6 5078.6 5086.1 5089.4 5118.5 5347.7 5418.2 5472.4 5527.7 5565.4 5051.9 5070.6 5077.0 5080.3 5149.8 5357.8 5359.9 5374.3 5433.6 5438.7 5032.9 5239.2 5274.5 5286.2 5292.1 5066.1 5068.2 5074.9 5084.5 5090.3 3772.7 3778.9 3780.6 3786.2 3793.3 5238.0 5287.7 5375.4 5449.4 5457.7 5143.8 5219.6 5234.7 5240.6 5283.5 5160.2 5203.4 5222.7 5251.4 5253.3 4963.5 4992.7 5136.3 5140.3 5157.8 5009.9 5103.2 5256.4 5270.1 5279.2 5064.7 5068.6 5085.5 5112.5 5132.2 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - PROCESS | 19496 | [#5] yui.html Cycles:180 Average:12302.36 Median:47076.15 stddev:13841.39 (29.4%) stddev-sans-first:13852.82 17:00:38 INFO - PROCESS | 19496 | Values: 705.2 780.7 818.5 840.1 845.7 11202.4 11779.9 11829.1 11829.8 11846.2 10919.2 10994.8 11044.7 11109.6 11136.4 679.7 850.5 862.4 876.9 877.9 854.6 856.5 857.1 857.8 863.8 910.9 911.9 914.6 914.7 917.6 899.8 900.7 905.3 906.1 906.3 665.3 669.5 670.1 671.9 673.6 11312.7 11357.4 11377.5 11386.7 11398.0 12383.4 13067.9 13270.7 13276.1 13522.9 10427.2 10439.7 10500.4 10667.2 10714.8 3368.6 3436.8 3440.4 3447.8 3533.1 38763.6 40007.2 46830.3 46982.5 47135.5 9412.3 10143.2 10231.7 10273.5 10290.1 16712.7 16758.7 16882.8 16886.4 16991.5 6692.4 6839.8 7067.8 7130.1 7146.9 3762.6 3926.8 3964.8 3975.6 4005.0 2751.2 2799.6 2804.4 2820.2 2828.9 533.8 536.6 541.6 549.8 555.7 533.4 563.6 627.2 632.5 648.0 7935.6 7955.3 8108.4 8234.2 8381.3 508.1 538.1 621.2 658.5 679.4 3822.7 3862.6 3876.3 3920.6 3927.0 48283.0 48931.8 49257.0 49573.5 49583.0 43025.0 45410.5 45681.3 45900.8 47016.8 41229.8 41848.7 42040.9 42073.4 42167.8 7472.0 7581.9 7662.9 7675.0 7710.6 27894.7 28082.3 28306.2 28501.1 28800.6 36227.8 36230.6 36797.1 37446.3 37550.2 9437.5 9440.0 9682.4 9723.0 9743.4 10155.1 10205.3 10213.0 10548.7 10554.5 9989.7 10052.2 10062.6 10077.8 10086.5 9148.2 9388.8 9787.8 10266.3 10743.9 7903.6 9647.2 9654.3 11264.7 11611.0 9573.7 9583.8 9588.9 9707.6 9948.6 9224.3 9477.1 9793.0 9844.8 9877.1 17:00:38 INFO - PROCESS | 19496 | -------- Summary: end -------- 17:00:38 INFO - PROCESS | 19496 | 17:00:38 INFO - TEST-INFO | 19496: exit 0 17:00:38 INFO - TEST-OK | dromaeo_css | took 1115167ms 17:00:38 INFO - TEST-START | kraken 17:00:38 INFO - operating with platform_type : linux_ 17:00:38 INFO - Initialising browser for kraken test... 17:00:38 INFO - TEST-INFO | started process 19726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWlEd9/profile http://localhost:43112/getInfo.html) 17:00:42 INFO - PROCESS | 19726 | 17:00:42 INFO - PROCESS | 19726 | (process:19794): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:00:43 INFO - PROCESS | 19726 | __metrics Screen width/height:1600/1200 17:00:43 INFO - PROCESS | 19726 | colorDepth:24 17:00:43 INFO - PROCESS | 19726 | Browser inner width/height: 1024/697 17:00:43 INFO - PROCESS | 19726 | __metrics 17:00:43 ERROR - PROCESS | 19726 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 17:00:44 INFO - TEST-INFO | 19726: exit 0 17:00:44 INFO - Browser initialized. 17:00:44 INFO - Running cycle 1/1 for kraken test... 17:00:44 INFO - TEST-INFO | started process 19846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWlEd9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:00:46 INFO - PROCESS | 19846 | 17:00:46 INFO - PROCESS | 19846 | (process:19906): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:00:56 INFO - PROCESS | 19846 | RSS: Main: 164233216 17:00:56 INFO - PROCESS | 19846 | 17:00:59 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43112/tests/kraken/driver.html?testName=audio-beat-detection) 17:01:00 INFO - PROCESS | 19846 | RSS: Main: 167456768 17:01:00 INFO - PROCESS | 19846 | 17:01:03 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43112/tests/kraken/driver.html?testName=audio-dft) 17:01:04 INFO - PROCESS | 19846 | RSS: Main: 167505920 17:01:04 INFO - PROCESS | 19846 | 17:01:06 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43112/tests/kraken/driver.html?testName=audio-fft) 17:01:07 INFO - PROCESS | 19846 | RSS: Main: 164036608 17:01:07 INFO - PROCESS | 19846 | 17:01:09 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43112/tests/kraken/driver.html?testName=audio-oscillator) 17:01:10 INFO - PROCESS | 19846 | RSS: Main: 164085760 17:01:10 INFO - PROCESS | 19846 | 17:01:12 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43112/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:01:13 INFO - PROCESS | 19846 | RSS: Main: 164626432 17:01:13 INFO - PROCESS | 19846 | 17:01:16 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43112/tests/kraken/driver.html?testName=imaging-darkroom) 17:01:17 INFO - PROCESS | 19846 | RSS: Main: 165392384 17:01:17 INFO - PROCESS | 19846 | 17:01:20 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43112/tests/kraken/driver.html?testName=imaging-desaturate) 17:01:21 INFO - PROCESS | 19846 | RSS: Main: 163307520 17:01:21 INFO - PROCESS | 19846 | 17:01:24 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43112/tests/kraken/driver.html?testName=json-parse-financial) 17:01:25 INFO - PROCESS | 19846 | RSS: Main: 163729408 17:01:25 INFO - PROCESS | 19846 | 17:01:27 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43112/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:01:27 INFO - PROCESS | 19846 | RSS: Main: 163807232 17:01:27 INFO - PROCESS | 19846 | 17:01:29 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:01:30 INFO - PROCESS | 19846 | RSS: Main: 164147200 17:01:30 INFO - PROCESS | 19846 | 17:01:32 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:01:33 INFO - PROCESS | 19846 | RSS: Main: 164577280 17:01:33 INFO - PROCESS | 19846 | 17:01:36 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:01:36 INFO - PROCESS | 19846 | RSS: Main: 164941824 17:01:36 INFO - PROCESS | 19846 | 17:01:39 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:01:40 INFO - PROCESS | 19846 | RSS: Main: 164511744 17:01:40 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | Cycle 1(1): loaded http://localhost:43112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43112/tests/kraken/driver.html?testName=ai-astar) 17:01:42 INFO - PROCESS | 19846 | RSS: Main: 166682624 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | __start_tp_report 17:01:42 INFO - PROCESS | 19846 | _x_x_mozilla_page_load 17:01:42 INFO - PROCESS | 19846 | _x_x_mozilla_page_load_details 17:01:42 INFO - PROCESS | 19846 | |i|pagename|runs| 17:01:42 INFO - PROCESS | 19846 | |0;ai-astar;112;116;109;115;117;115;109;114;114;115 17:01:42 INFO - PROCESS | 19846 | |1;audio-beat-detection;155;141;157;228;132;164;165;135;188;132 17:01:42 INFO - PROCESS | 19846 | |2;audio-dft;163;165;176;163;163;161;164;163;163;163 17:01:42 INFO - PROCESS | 19846 | |3;audio-fft;76;76;77;170;79;78;80;76;149;76 17:01:42 INFO - PROCESS | 19846 | |4;audio-oscillator;97;93;172;93;94;92;151;93;93;146 17:01:42 INFO - PROCESS | 19846 | |5;imaging-gaussian-blur;118;117;117;117;118;118;118;117;118;118 17:01:42 INFO - PROCESS | 19846 | |6;imaging-darkroom;148;148;147;147;147;147;147;147;148;147 17:01:42 INFO - PROCESS | 19846 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;96;95 17:01:42 INFO - PROCESS | 19846 | |8;json-parse-financial;69;71;69;68;70;71;69;68;69;71 17:01:42 INFO - PROCESS | 19846 | |9;json-stringify-tinderbox;65;65;66;67;66;65;67;66;65;65 17:01:42 INFO - PROCESS | 19846 | |10;stanford-crypto-aes;92;82;84;90;83;86;83;81;144;83 17:01:42 INFO - PROCESS | 19846 | |11;stanford-crypto-ccm;129;132;134;143;139;139;134;134;147;133 17:01:42 INFO - PROCESS | 19846 | |12;stanford-crypto-pbkdf2;174;176;176;177;176;177;176;177;177;177 17:01:42 INFO - PROCESS | 19846 | |13;stanford-crypto-sha256-iterative;71;68;69;67;73;68;67;69;68;71 17:01:42 INFO - PROCESS | 19846 | __end_tp_report 17:01:42 INFO - PROCESS | 19846 | __start_cc_report 17:01:42 INFO - PROCESS | 19846 | _x_x_mozilla_cycle_collect,928 17:01:42 INFO - PROCESS | 19846 | __end_cc_report 17:01:42 INFO - PROCESS | 19846 | __startTimestamp1462924902562__endTimestamp 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | ------- Summary: start ------- 17:01:42 INFO - PROCESS | 19846 | Number of tests: 14 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:2.76 (2.4%) stddev-sans-first:2.86 17:01:42 INFO - PROCESS | 19846 | Values: 112.0 116.0 109.0 115.0 117.0 115.0 109.0 114.0 114.0 115.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#1] audio-beat-detection Cycles:10 Average:159.70 Median:160.50 stddev:29.88 (18.6%) stddev-sans-first:31.65 17:01:42 INFO - PROCESS | 19846 | Values: 155.0 141.0 157.0 228.0 132.0 164.0 165.0 135.0 188.0 132.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#2] audio-dft Cycles:10 Average:164.40 Median:163.00 stddev:4.20 (2.6%) stddev-sans-first:4.42 17:01:42 INFO - PROCESS | 19846 | Values: 163.0 165.0 176.0 163.0 163.0 161.0 164.0 163.0 163.0 163.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.50 stddev:35.06 (45.8%) stddev-sans-first:36.60 17:01:42 INFO - PROCESS | 19846 | Values: 76.0 76.0 77.0 170.0 79.0 78.0 80.0 76.0 149.0 76.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#4] audio-oscillator Cycles:10 Average:112.40 Median:93.00 stddev:31.03 (33.4%) stddev-sans-first:32.41 17:01:42 INFO - PROCESS | 19846 | Values: 97.0 93.0 172.0 93.0 94.0 92.0 151.0 93.0 93.0 146.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:01:42 INFO - PROCESS | 19846 | Values: 118.0 117.0 117.0 117.0 118.0 118.0 118.0 117.0 118.0 118.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 17:01:42 INFO - PROCESS | 19846 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 148.0 147.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:01:42 INFO - PROCESS | 19846 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#8] json-parse-financial Cycles:10 Average:69.50 Median:69.50 stddev:1.18 (1.7%) stddev-sans-first:1.24 17:01:42 INFO - PROCESS | 19846 | Values: 69.0 71.0 69.0 68.0 70.0 71.0 69.0 68.0 69.0 71.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 17:01:42 INFO - PROCESS | 19846 | Values: 65.0 65.0 66.0 67.0 66.0 65.0 67.0 66.0 65.0 65.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:83.50 stddev:19.03 (22.8%) stddev-sans-first:20.17 17:01:42 INFO - PROCESS | 19846 | Values: 92.0 82.0 84.0 90.0 83.0 86.0 83.0 81.0 144.0 83.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:136.50 stddev:5.50 (4.0%) stddev-sans-first:5.14 17:01:42 INFO - PROCESS | 19846 | Values: 129.0 132.0 134.0 143.0 139.0 139.0 134.0 134.0 147.0 133.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:177.00 stddev:0.95 (0.5%) stddev-sans-first:0.53 17:01:42 INFO - PROCESS | 19846 | Values: 174.0 176.0 176.0 177.0 176.0 177.0 176.0 177.0 177.0 177.0 17:01:42 INFO - PROCESS | 19846 | 17:01:42 INFO - PROCESS | 19846 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.97 (2.9%) stddev-sans-first:1.96 17:01:42 INFO - PROCESS | 19846 | Values: 71.0 68.0 69.0 67.0 73.0 68.0 67.0 69.0 68.0 71.0 17:01:42 INFO - PROCESS | 19846 | -------- Summary: end -------- 17:01:42 INFO - PROCESS | 19846 | 17:01:43 INFO - TEST-INFO | 19846: exit 0 17:01:43 INFO - TEST-OK | kraken | took 64891ms 17:01:43 INFO - SUITE-END | took 1180s 17:01:44 INFO - Completed test suite (00:19:40) 17:01:44 INFO - javascript benchmark 17:01:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2639.894404223831, 2694.5823397373415, 2704.7430494103064, 2710.6747190337164, 2715.940783094593, 4785.736784476852, 4788.401449818768, 4825.541573550511, 4905.533974272447, 4931.6301277404245, 4714.50497697742, 4844.830430934918, 4876.829310974116, 4885.609151267899, 4891.4374846892615, 4391.868243952678, 4447.444069491314, 4521.751303678297, 4538.568835960595, 4539.568740969619, 4462.1968045751755, 4551.749653769041, 4572.08558288342, 4589.403377560912, 4605.792739326738, 4357.150355680644, 4371.606555410029, 4430.490493593245, 4476.01527663915, 4497.5952164305045, 4433.534478879736, 4437.267850804614, 4452.532484089156, 4480.171168333869, 4528.705634133804, 4461.955380446187, 4464.843730469451, 4475.082608065339, 4485.977570112162, 4521.683482156251, 4709.788059537329, 4712.976435117836, 4737.194676904935, 4765.356676848651, 4822.204336284509, 5210.76551555181, 5228.08508511009, 5239.083160446945, 5242.5281724644965, 5248.871492629103, 5260.184671375943, 5262.23697563855, 5262.289590905217, 5265.973670131663, 5280.709560974145, 4723.984343366193, 4737.26572381278, 4766.9274413257435, 4781.545753153445, 4783.736894470768, 5232.241325007929, 5244.659097158748, 5255.054090263713, 5260.947390526122, 5316.9202461963105, 4989.176785830297, 5046.621503387188, 5047.066292735855, 5052.090623687698, 5059.62052846038, 5176.870578235575, 5185.533302002837, 5209.140491818884, 5236.24074509194, 5246.554042906396, 5025.773840177201, 5047.4447810740785, 5059.797608095709, 5108.540231379194, 5123.436421993578, 5077.26379674945, 5091.0836049511445, 5093.9490605094215, 5094.235864620263, 5103.744812759422, 4316.762578058174, 4363.367311739843, 4364.694471387036, 4370.868873933787, 4375.7374557526655, 3765.5293088363956, 3778.7732736035377, 3847.769133851978, 3850.4801851750217, 3866.052817059838, 4332.870013899588, 4369.9126017479475, 4383.189110014594, 4399.472063352418, 4401.801918913657, 5080.23796430531, 5084.3898732152375, 5086.135356989321, 5086.21163719624, 5125.589952803767, 4374.78126093694, 4381.627561657295, 4386.758728269912, 4432.933505997413, 4447.0, 4514.571115743999, 4528.547145285445, 4533.478649955297, 4534.478534968521, 4585.908281834345, 4822.156122678517, 4884.829030983828, 4893.804247830047, 4918.606511479073, 4954.727489988085, 5074.82238121664, 5161.483851614808, 5178.97410512945, 5238.0, 5284.075286824714, 4977.9502204978935, 5006.324146240211, 5009.223570346602, 5021.5229553192385, 5023.070731914461, 5249.501297376743, 5262.89474210506, 5272.05103081449, 5273.630845840754, 5300.973495132654, 5016.59867210615, 5046.34397528319, 5209.557187639093, 5230.450802665665, 5348.064088784369, 5093.694378337312, 5118.641695081308, 5125.974370128275, 5132.461091585479, 5152.330197074356, 5073.644844860972, 5101.056304583664, 5134.358205224347, 5139.357580302462, 5181.0, 4868.513148685103, 4891.461939186758, 4906.533879281463, 5034.244863270393, 5049.6465247432325, 5133.640645154804, 5135.3324067870935, 5144.871378215575, 5150.253213284128, 5152.819651312186, 4977.4773648767805, 4982.601391888567, 5014.17266151081, 5028.9245661315845, 5045.873853153554], "value": 4753.5411330800725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.5156819839534, 605.984975166596, 614.5329549542347, 622.1010639625741, 640.2157357237388, 35797.46303805445, 40098.59901400985, 40099.39850902235, 40103.398449023254, 40143.79928100363, 39386.21227575454, 40169.79915100428, 40368.59631403693, 40461.0, 40482.0, 7805.960970195141, 7847.764567062969, 7933.603319834014, 7959.1244963054305, 7970.083440404341, 7760.146383897795, 7780.105287891908, 7822.178671239523, 7871.370290376784, 7871.488353257048, 7836.098848632395, 7875.212478752108, 7909.446338756289, 7912.446128770988, 7926.167752386003, 7761.107472640634, 7780.533168009937, 7814.1013783415065, 7859.8035049123655, 7878.290953814127, 7659.6936122555035, 7662.540247585162, 7686.730964416274, 7717.575533345664, 7722.42081843864, 37478.25043499115, 37701.245975080616, 37927.0, 37964.24071518582, 38061.429078563844, 3195.536647186145, 3219.356128774231, 3222.935541289161, 3342.699157075875, 3482.495038219444, 29446.116616501324, 29642.0, 29889.402211955858, 30008.699913001026, 30952.380952381052, 2953.7932344735877, 2996.3857409231055, 2997.865096070682, 3040.3767227718263, 3072.170513961218, 245669.0, 246928.0, 247713.52286477265, 248833.0, 249201.9999999982, 33554.0, 34199.829000854836, 53194.202086968726, 53732.0, 53739.6565085876, 35261.294774104375, 35520.11199720028, 35548.64451355505, 35632.64367356293, 35671.46492802558, 13368.799468007988, 13611.591652250449, 13622.114562553403, 13629.045966782429, 13629.795553066711, 10693.19801014927, 10697.83953240702, 10703.304285221435, 10712.143028557697, 10726.356418614909, 8354.958225208835, 8370.162983701703, 8373.204545568162, 8449.32405407566, 8486.872696909551, 3257.3159636476607, 3282.720968717638, 3294.9176270593425, 3304.9173770655925, 3341.665833416639, 941.021337808685, 960.870282511852, 1041.9791604167874, 1047.2983101322131, 1060.6234786650627, 28757.137285881458, 28874.855625721735, 28939.85530072336, 29250.707492925223, 29775.702242977728, 916.1525588830306, 1006.8389057750725, 1065.5471424644495, 1111.5109351885144, 1138.4649214868998, 14446.277686115653, 14591.416343346147, 14609.123452592878, 14768.52314768536, 14917.750460241159, 354439.0, 356900.0, 362110.0, 363388.0, 364336.0, 49672.50327496751, 49935.50064499381, 50073.74963125161, 50085.24872126848, 50202.246966295534, 1258.4337048328289, 1282.3011458755002, 1283.557172775391, 1286.6397408725384, 1288.890444312225, 25702.614460783476, 27181.86409067942, 27245.0, 27424.45151096927, 27600.861995689895, 37650.43524347192, 37767.244655106195, 37776.81111594424, 37777.81111094427, 37798.81100594479, 306899.0, 310619.0, 311974.0, 312142.0, 313423.0, 25606.0, 25664.8716756415, 25687.229383118538, 25850.61224081678, 25972.610410843474, 42764.14471710611, 43135.784321078194, 43191.35212971745, 44151.33772993472, 44335.11329773451, 37007.6299237015, 37397.0, 37400.25199496049, 37733.81133094317, 38158.0, 36806.26387472182, 36820.447693284084, 37178.0, 37504.81247593745, 37510.43734344042, 31962.84018579985, 35987.64012359948, 37626.0, 37679.81160094182, 38318.23363532769, 32504.83747581247, 33700.66299337073, 33876.83061584676, 33946.3210735779, 34116.58834116568, 25509.61735574005, 25530.74469255358, 25669.743302566734, 25939.09213177605, 26440.074597389], "value": 16603.752917060785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.72397511803945, 385.4545817668002, 386.05236581446593, 400.87773229165094, 407.7207113127508, 15339.463118790845, 17522.82477175231, 18094.819051809478, 18207.362742304023, 18263.634727305463, 17010.48968530943, 17285.91357043213, 17599.0, 17623.559411014732, 17712.64574708505, 5049.722265275408, 5183.688978661274, 5217.165253559431, 5229.163333866563, 5241.68549887008, 3677.4483827425966, 5777.797777077803, 6052.425019623129, 6053.939460605382, 6177.382261773809, 5918.631304026471, 5990.281166260055, 6095.725692343833, 6105.969470152643, 6113.174721412619, 6034.96982515089, 6127.111568822518, 6134.2332208473945, 6145.877082458348, 6148.231471066117, 4660.650451216173, 4952.306677065214, 4979.253112033224, 5266.552343050846, 5332.226827110047, 11773.470193841213, 11871.52513899443, 12000.939995300056, 12990.740185196337, 20478.488037799027, 1822.8906265624105, 1825.488863118329, 1833.7982821889716, 1874.9531261718566, 1899.335232668555, 8647.827043459096, 8823.470591764515, 8944.060873608243, 11239.775204495865, 12938.353082345846, 1649.488658515864, 1711.2213942656185, 1734.3669560610413, 1747.921343539531, 1766.4612293250555, 68288.6585567069, 70205.2979470209, 72186.0, 72518.27481725167, 72697.63651181763, 9565.234781217487, 9871.851922221173, 10080.193584513221, 10171.525128856423, 10515.737106572396, 14279.357428915724, 14352.138871667734, 14380.928095359666, 14384.496542620958, 14393.568192954228, 7197.100362454693, 7201.099862517185, 7228.566286022856, 7404.37062849653, 7423.62881855905, 6835.213950395768, 6925.3074692530345, 6939.791806245821, 6962.373386395248, 7009.894851577231, 4396.780160992002, 4415.66882483815, 4453.576910193527, 4485.125400546862, 4490.281554951192, 1630.5026966775247, 1773.3527262549335, 1868.4301288106985, 1901.6386886491798, 1913.3112079651314, 816.4692949582819, 855.6876739989859, 930.55798495714, 982.729749318943, 1047.7694907120422, 16562.917185414237, 16583.83416165847, 16725.330986760393, 17003.74494382585, 17011.574710632332, 883.3507372081515, 916.6333466613274, 922.2529750901791, 931.8415869302099, 933.4819175357742, 9483.76290592727, 9498.240140788721, 9539.284553658503, 9594.136527712539, 9674.22606191503, 401178.0, 406635.0, 407836.0, 410869.0, 423536.0, 77503.0, 79411.60294198492, 80158.0, 80819.0, 81704.0, 935.6771913689767, 946.6544711180508, 950.9049095090573, 967.4485543240286, 967.4823969176457, 14910.77633835515, 15037.473688420852, 15146.166960817261, 15257.237138142826, 15266.236688165325, 34855.0, 35303.47044794279, 35474.64525354713, 36396.27207455889, 36602.084947876516, 48452.273215901085, 49070.263946040126, 49120.0, 49120.75439622779, 49607.0, 7433.776986690408, 7709.267619576131, 7714.652840622073, 7732.7680169595005, 7761.805954851174, 8283.420160588701, 8418.705345312885, 8452.915470845213, 8458.492490450346, 8809.383343166173, 6394.976803711384, 7529.623518824146, 7586.089669239726, 7838.412119090977, 8022.719204828037, 6410.967945160244, 7863.095743989515, 7890.421915617015, 7965.322947549524, 8071.878921816295, 8013.919860801318, 8072.0, 8103.189681031849, 8252.958735206286, 8308.127646597262, 11325.433728313386, 11485.253458525169, 11607.08483100279, 11733.530658773554, 12267.447964841425, 10797.676069717922, 11260.662180134608, 11429.199956003333, 11461.541538338373, 11625.18623696367], "value": 8116.2954141707805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.1629695518982, 1510.116581799647, 1697.7708009418732, 1834.2296235581055, 1854.8794328368656, 6507.186601674791, 7140.928590714091, 7659.8085047873765, 8199.344052475803, 8244.587770611468, 7151.284871512846, 7379.8155046123875, 7383.852322953538, 7394.889076663854, 7406.259374062591, 2158.201465457779, 2195.2536137713173, 2203.6474164133742, 2208.7349518057854, 2209.0832304593573, 2148.5380643161716, 2179.923702670407, 2198.6152423325934, 2199.439143018528, 2199.7690242524545, 2121.734783152106, 2128.744550653924, 2137.561799831031, 2167.729033870766, 2236.8769717665523, 2116.174691870172, 2125.2136709417537, 2125.2667829598763, 2130.6164890319737, 2131.6269652810697, 2066.9689954650694, 2092.215419217793, 2139.6576547752284, 2150.5698860227862, 2174.8912554372378, 7399.482036257464, 7413.8517229655645, 7423.591702456362, 7428.777136685908, 7432.182459929403, 4670.182718024332, 4760.619150467989, 4788.569028787391, 4809.591184749301, 4816.397950256218, 2898.30440694234, 2988.955165672495, 3071.5546245794458, 3092.597962264891, 3099.3491366812996, 887.8401887660218, 928.5960607135918, 928.9071092890724, 945.2249155692335, 960.7502049467118, 10177.643782467578, 10506.78986420275, 10533.683989480327, 10750.838737418944, 10930.617428389969, 4178.791060446965, 4206.390073439334, 4304.440422745023, 4338.76136812472, 4352.673549483801, 8474.830503389898, 8548.700795472128, 8563.614637341334, 8569.143085691505, 8594.140585941355, 4594.6783725139585, 4615.23848564983, 4617.630589552828, 4630.073985202973, 4637.721736695809, 3755.8497660093835, 4300.741955482681, 4311.8706438806885, 4396.07682386696, 4440.178566965122, 3495.4582039783368, 3530.735194860396, 3590.7486475946434, 3618.0593045808278, 3618.6924111450294, 1051.222095649214, 1065.0521036277719, 1065.520515767892, 1067.32225037102, 1067.5089458849016, 743.2678811370796, 875.4790899414838, 912.2610685344891, 922.9723108306762, 949.0, 5326.307580014651, 5486.506214440719, 5534.418886016911, 5669.943300566942, 5696.51579615729, 406.8942075060506, 407.2526913114468, 424.8194517330122, 426.79856204783437, 430.3866989539893, 3877.41838724188, 3907.1208977980295, 3954.703397245218, 3989.940150897739, 4012.0371110933747, 9198.49408282551, 9626.326157169071, 9821.656242031495, 9849.310548261696, 9899.65351212704, 9618.951905240569, 9687.41875487473, 9729.416235025921, 9742.95128524353, 9826.508674566243, 7308.59802710856, 7369.22623124575, 7410.777676669709, 7529.397648188132, 7549.433792465477, 5448.673079615236, 5496.972515137399, 5498.835034948958, 5547.306586676666, 5663.85840353978, 8901.910980890108, 8988.370814043215, 9071.501067441353, 9090.727278181665, 9163.633454661738, 8937.195652391316, 9394.624215031323, 9399.0, 9415.670451534163, 9442.622295108118, 5686.0, 6500.187476565429, 6525.445337146396, 6938.167419909643, 7222.241664625139, 7275.818104547428, 7475.850482990201, 7510.737124200627, 7537.698492060256, 7572.8485430292185, 6654.700538475683, 6832.453403727789, 6877.9656101721175, 6898.758543450955, 6941.271166527442, 6688.899666504909, 6701.32986701326, 6771.62756048422, 6777.728890844311, 6965.895511567432, 6300.464460520907, 6309.558330916791, 6368.299487056327, 6386.457151142205, 6411.4870810336, 4592.311153327028, 4739.47865734762, 4770.570648641639, 4824.758762061953, 4876.902461950812, 4772.976135119302, 4798.880027999188, 4828.03439312146, 4852.126617208936, 4917.360743103517], "value": 4095.3594404606574, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3184414677343, 306.831242816451, 309.16525381470035, 309.8481743945466, 311.6602902835908, 4979.377577802775, 5013.6741111827705, 5028.566858445334, 5040.697558146514, 5044.319016932722, 4954.826581069663, 4959.752012399384, 4981.25281207819, 4988.551030407262, 5005.696240481283, 3639.2903383840094, 3782.9621703782886, 3904.6681032112306, 3906.8046597670145, 3909.257241124177, 3886.892235712822, 3896.902577435558, 3912.7065470089856, 3916.5887581803927, 3931.267768900615, 3878.1855810279735, 3889.436031775391, 3891.5524714657754, 3893.240818040483, 3917.099067214542, 3790.7346485746007, 3884.465636073735, 3893.279743247508, 3904.3557812960826, 3904.960950390488, 3442.8278586070596, 3457.4986626938953, 3459.4637831136215, 3466.6360032196762, 3472.861085556575, 4729.976350118227, 4737.194676904935, 4741.0, 4770.141374552579, 4770.689905156154, 4445.748442247532, 4463.727837566278, 4469.262571675669, 4497.280435130363, 4510.639148868083, 5067.39191297043, 5170.146925757245, 5184.611154163415, 5202.583793296527, 5250.002499525078, 3820.808959552039, 3832.7317087803863, 3835.42468629705, 3835.9041023974623, 3842.8270727817317, 5413.2692086568595, 5444.40111587725, 5446.754896029688, 5480.123180291134, 5484.698341591171, 3574.6067932527403, 3850.9807450962567, 3858.2862170498547, 3862.1310205204168, 3868.941965870514, 3866.9806650966566, 3871.6189543430587, 3881.1461478475253, 3887.067103895045, 3896.7661940283674, 4401.779911004437, 4419.469663640384, 4423.380726698265, 4440.977795111004, 4468.396766436489, 5099.0, 5258.2638430619745, 5280.260763493114, 5294.152935530374, 5295.285136506599, 1319.070055610792, 1328.249539010456, 1330.6074707961175, 1332.433715670836, 1332.826732524785, 812.9105798362175, 820.2084987986622, 823.9629216685264, 831.7546323834483, 845.2097289034772, 4501.527339629291, 4587.9082418351445, 4609.53904609543, 4613.0543238636, 4614.353990441341, 5363.356397232357, 5368.248445217672, 5382.896506216216, 5443.8094666686475, 5448.83653490396, 4523.344115103291, 4581.633469322446, 4616.561426664462, 4619.983603607201, 4620.607248383925, 2860.2277385105904, 2892.783041271913, 2895.261708264421, 2904.2158617173245, 2909.8254104753782, 5066.569341605931, 5078.568321692624, 5086.135356989321, 5089.3638295213095, 5118.539331460112, 5347.652402593819, 5418.155468379054, 5472.425395333433, 5527.723613819293, 5565.387807341189, 5051.87370315745, 5070.619703522177, 5077.0, 5080.263361812516, 5149.845504634867, 5357.785688572413, 5359.946400535945, 5374.328208973879, 5433.646812957145, 5438.728063596884, 5032.874178145576, 5239.214117882349, 5274.498922602346, 5286.233496143114, 5292.126799078109, 5066.138756411493, 5068.2397640352765, 5074.949250507595, 5084.466131056334, 5090.338256026693, 3772.698184145207, 3778.9433158502093, 3780.640839120279, 3786.242751449666, 3793.279276937373, 5237.973810130925, 5287.68273903567, 5375.381831089318, 5449.373322068013, 5457.672539647634, 5143.76549628094, 5219.582433405242, 5234.712090835041, 5240.606954478506, 5283.524482796567, 5160.174372100446, 5203.427622961547, 5222.660527065728, 5251.422343542131, 5253.294553304974, 4963.4540200577185, 4992.72540010303, 5136.2552429896705, 5140.331756871583, 5157.819476318311, 5009.9248511271635, 5103.157978933435, 5256.421793602624, 5270.051390749549, 5279.234510995961, 5064.72144032067, 5068.569171620454, 5085.491450855033, 5112.491814914535, 5132.153194722829], "value": 3933.7418483952097, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2207310921432, 780.7462574663234, 818.4557269415839, 840.1262686805723, 845.6659619450318, 11202.439878006098, 11779.941100294507, 11829.11281653875, 11829.763404731912, 11846.17076804624, 10919.235653504258, 10994.780104397907, 11044.668659940195, 11109.555617775279, 11136.443177841116, 679.7383007542082, 850.4897061762955, 862.357543629995, 876.8860048193726, 877.929765618749, 854.5940678177878, 856.4946681457939, 857.0786404615033, 857.8327226190895, 863.7754183912165, 910.8724778531011, 911.8677791720197, 914.5564401265381, 914.6981496106268, 917.6191880369638, 899.7840518275632, 900.6757567275776, 905.3345790843757, 906.125588806798, 906.2840356118659, 665.2549144957678, 669.4878418010226, 670.1054092786136, 671.942884854788, 673.6160388578512, 11312.660620181408, 11357.432128393548, 11377.488013039432, 11386.658400248005, 11398.0, 12383.442745076492, 13067.934660326733, 13270.73458530824, 13276.137051091648, 13522.864771352259, 10427.217958653131, 10439.686809395731, 10500.36997780128, 10667.19996000303, 10714.785704285949, 3368.5957685077947, 3436.8453419596176, 3440.36353274645, 3447.793132412063, 3533.09905973975, 38763.61236387628, 40007.19985600301, 46830.29754553684, 46982.53017469816, 47135.5286447138, 9412.294077944181, 10143.18854491639, 10231.693049208536, 10273.537690803932, 10290.073893349485, 16712.665746684997, 16758.664826703396, 16882.831171688373, 16886.40897568579, 16991.49025529236, 6692.364225398579, 6839.829004274933, 7067.823304417328, 7130.144382674009, 7146.927960805817, 3762.6024335733796, 3926.823292951824, 3964.781936993493, 3975.6223158799867, 4004.979975100164, 2751.2296556964084, 2799.5520716685232, 2804.4110736745106, 2820.1539538138886, 2828.858557072138, 533.751805410486, 536.6270442042743, 541.5992165797281, 549.7910793898373, 555.7304707216997, 533.3679589686234, 563.5604228701619, 627.1971875998729, 632.4750457120579, 647.9643619600968, 7935.64289606969, 7955.323797477164, 8108.432409731262, 8234.217749313806, 8381.329493640495, 508.09305389878887, 538.0959987221428, 621.2110619513193, 658.4699317049725, 679.4428568573722, 3822.6559609635265, 3862.5558060823364, 3876.3022655922205, 3920.6079392060565, 3926.960730392717, 48283.0, 48931.75534122306, 49257.0, 49573.50426495761, 49583.0, 43025.0, 45410.54589454063, 45681.31478027832, 45900.770496147976, 47016.7649161752, 41229.793851030554, 41848.744537663915, 42040.94897627523, 42073.368899466535, 42167.789161054614, 7472.0, 7581.924180758122, 7662.923370766221, 7675.0, 7710.575918324322, 27894.721052789213, 28082.297942550784, 28306.15081547557, 28501.144965651063, 28800.567991480562, 36227.818860906584, 36230.637693622724, 36797.08007299732, 37446.25107497889, 37550.24899502049, 9437.480938548446, 9440.0, 9682.370645907993, 9723.0, 9743.415395076318, 10155.136813370946, 10205.336653117523, 10213.0, 10548.683539493828, 10554.472276386303, 9989.700308990741, 10052.246081544059, 10062.647807326708, 10077.798444031225, 10086.546105425421, 9148.222401095714, 9388.765280868032, 9787.755306117404, 10266.281360304707, 10743.89256107429, 7903.604819758874, 9647.179989700953, 9654.324197306121, 11264.718382040515, 11611.0, 9573.664921727706, 9583.808323833624, 9588.856167157359, 9707.563159657691, 9948.55231514598, 9224.262059035127, 9477.052294770469, 9793.0, 9844.753881153028, 9877.111060004634], "value": 5892.222704928246, "unit": "score"}], "name": "dromaeo_css", "value": 6270.959647764334, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 109.0, 115.0, 117.0, 115.0, 109.0, 114.0, 114.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 141.0, 157.0, 228.0, 132.0, 164.0, 165.0, 135.0, 188.0, 132.0], "value": 159.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 176.0, 163.0, 163.0, 161.0, 164.0, 163.0, 163.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 170.0, 79.0, 78.0, 80.0, 76.0, 149.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 172.0, 93.0, 94.0, 92.0, 151.0, 93.0, 93.0, 146.0], "value": 112.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 68.0, 70.0, 71.0, 69.0, 68.0, 69.0, 71.0], "value": 69.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 67.0, 66.0, 65.0, 67.0, 66.0, 65.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 82.0, 84.0, 90.0, 83.0, 86.0, 83.0, 81.0, 144.0, 83.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 132.0, 134.0, 143.0, 139.0, 139.0, 134.0, 134.0, 147.0, 133.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 176.0, 177.0, 176.0, 177.0, 176.0, 177.0, 177.0, 177.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 69.0, 67.0, 73.0, 68.0, 67.0, 69.0, 68.0, 71.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1611.7, "alertThreshold": 2.0}]} 17:01:44 INFO - Return code: 0 17:01:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:01:44 ERROR - # TBPL SUCCESS # 17:01:44 INFO - Running post-action listener: _resource_record_post_action 17:01:44 INFO - ##### 17:01:44 INFO - ##### Finished run-tests step (success) 17:01:44 INFO - ##### 17:01:44 INFO - Running post-run listener: _resource_record_post_run 17:01:45 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539996160; Read time: 20; Write time: 460424 17:01:45 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 244105216; Read time: 0; Write time: 256916 17:01:45 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295292928; Read time: 20; Write time: 203508 17:01:45 INFO - Running post-run listener: _upload_blobber_files 17:01:45 INFO - Blob upload gear active. 17:01:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:01:45 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 17:01:45 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'] 17:01:45 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 17:01:45 INFO - (blobuploader) - INFO - Open directory for files ... 17:01:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:01:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:01:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:46 INFO - (blobuploader) - INFO - Done attempting. 17:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:01:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:47 INFO - (blobuploader) - INFO - Done attempting. 17:01:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:01:47 INFO - Return code: 0 17:01:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:01:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:01:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/67402a337c5fa01daa970bdf2dd617c2bf840448463ad7c15aea75d4335dcc695378fc8fbfb7f815c2d8eafba8b89c84e408f0ab8c706f72c62bdbb8c4a44bd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4b84decfe19978a4abf4300b41383be0f21d770eb5b07cdd4d9c7fe0ce918356b303df8d5a9dbc6be22b1f953d7734b0c883a75519be30c73f25c1eb9b35a71"} 17:01:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:01:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:01:47 INFO - Contents: 17:01:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/67402a337c5fa01daa970bdf2dd617c2bf840448463ad7c15aea75d4335dcc695378fc8fbfb7f815c2d8eafba8b89c84e408f0ab8c706f72c62bdbb8c4a44bd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4b84decfe19978a4abf4300b41383be0f21d770eb5b07cdd4d9c7fe0ce918356b303df8d5a9dbc6be22b1f953d7734b0c883a75519be30c73f25c1eb9b35a71"} 17:01:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:01:47 INFO - Copying logs to upload dir... 17:01:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:01:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.173241 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-10 17:01:46.893753) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 17:01:46.899924) ========= 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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/67402a337c5fa01daa970bdf2dd617c2bf840448463ad7c15aea75d4335dcc695378fc8fbfb7f815c2d8eafba8b89c84e408f0ab8c706f72c62bdbb8c4a44bd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4b84decfe19978a4abf4300b41383be0f21d770eb5b07cdd4d9c7fe0ce918356b303df8d5a9dbc6be22b1f953d7734b0c883a75519be30c73f25c1eb9b35a71"} build_url:https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011405 build_url: 'https://queue.taskcluster.net/v1/task/cZJ3eAESRgyyltx_LRggtQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/67402a337c5fa01daa970bdf2dd617c2bf840448463ad7c15aea75d4335dcc695378fc8fbfb7f815c2d8eafba8b89c84e408f0ab8c706f72c62bdbb8c4a44bd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4b84decfe19978a4abf4300b41383be0f21d770eb5b07cdd4d9c7fe0ce918356b303df8d5a9dbc6be22b1f953d7734b0c883a75519be30c73f25c1eb9b35a71"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 17:01:46.923901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:01:46.924189) ========= 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=0333a256a5e4f052382dd180000001b1-1462923584.419096-291309113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:01:47.009736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 17:01:47.010108) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 17:01:47.010414) ========= ========= Total master_lag: 0.18 =========