builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1462881475.6 results: success (0) buildid: 20160510031712 builduid: 86163a5b79e0486c9de34d5d04f80478 revision: 8b4e5ab50377a26e6610f21a0f8233467de19e9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.595750) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.596159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.596427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.604975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.605227) ========= 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=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 04:57:56-- 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% 21.9M=0.001s 2016-05-10 04:57:56 (21.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076631 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.693394) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.694130) ========= 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=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009574 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:55.743915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 04:57:55.744689) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8b4e5ab50377a26e6610f21a0f8233467de19e9b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8b4e5ab50377a26e6610f21a0f8233467de19e9b --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=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 04:57:56,315 truncating revision to first 12 chars 2016-05-10 04:57:56,315 Setting DEBUG logging. 2016-05-10 04:57:56,315 attempt 1/10 2016-05-10 04:57:56,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8b4e5ab50377?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 04:57:57,632 unpacking tar archive at: fx-team-8b4e5ab50377/testing/mozharness/ program finished with exit code 0 elapsedTime=1.549003 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 04:57:57.306858) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:57.307227) ========= script_repo_revision: 8b4e5ab50377a26e6610f21a0f8233467de19e9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:57.307680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:57.308454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 04:57:57.316588) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-10 04:57:57.316925) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:57:57 INFO - MultiFileLogger online at 20160510 04:57:57 in /builds/slave/test 04:57:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:57:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:57:57 INFO - {'append_to_log': False, 04:57:57 INFO - 'base_work_dir': '/builds/slave/test', 04:57:57 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:57:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:57:57 INFO - 'branch': 'Fx-Team-Non-PGO', 04:57:57 INFO - 'buildbot_json_path': 'buildprops.json', 04:57:57 INFO - 'config_files': ('talos/linux_config.py',), 04:57:57 INFO - 'default_actions': ('clobber', 04:57:57 INFO - 'read-buildbot-config', 04:57:57 INFO - 'download-and-extract', 04:57:57 INFO - 'populate-webroot', 04:57:57 INFO - 'create-virtualenv', 04:57:57 INFO - 'install', 04:57:57 INFO - 'run-tests'), 04:57:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:57:57 INFO - 'download_minidump_stackwalk': True, 04:57:57 INFO - 'download_symbols': 'ondemand', 04:57:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:57:57 INFO - 'tooltool.py': '/tools/tooltool.py', 04:57:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:57:57 INFO - '/tools/misc-python/virtualenv.py')}, 04:57:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:57:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:57:57 INFO - 'installer_path': 'installer.exe', 04:57:57 INFO - 'log_level': 'info', 04:57:57 INFO - 'log_name': 'talos', 04:57:57 INFO - 'log_to_console': True, 04:57:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:57:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:57:57 INFO - 'opt_config_files': (), 04:57:57 INFO - 'pip_index': False, 04:57:57 INFO - 'sps_profile': False, 04:57:57 INFO - 'sps_profile_interval': 0, 04:57:57 INFO - 'suite': 'dromaeojs-e10s', 04:57:57 INFO - 'system_bits': '32', 04:57:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:57:57 INFO - 'title': 'talos-linux64-ix-004', 04:57:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:57:57 INFO - 'use_talos_json': True, 04:57:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:57:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:57:57 INFO - 'work_dir': 'build'} 04:57:57 INFO - ##### 04:57:57 INFO - ##### Running clobber step. 04:57:57 INFO - ##### 04:57:57 INFO - Running pre-action listener: _resource_record_pre_action 04:57:57 INFO - Running main action method: clobber 04:57:57 INFO - rmtree: /builds/slave/test/build 04:57:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:57:58 INFO - Running post-action listener: _resource_record_post_action 04:57:58 INFO - ##### 04:57:58 INFO - ##### Finished clobber step (success) 04:57:58 INFO - ##### 04:57:58 INFO - ##### 04:57:58 INFO - ##### Running read-buildbot-config step. 04:57:58 INFO - ##### 04:57:58 INFO - Running pre-action listener: _resource_record_pre_action 04:57:58 INFO - Running main action method: read_buildbot_config 04:57:58 INFO - Using buildbot properties: 04:57:58 INFO - { 04:57:58 INFO - "project": "", 04:57:58 INFO - "product": "firefox", 04:57:58 INFO - "script_repo_revision": "production", 04:57:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 04:57:58 INFO - "repository": "", 04:57:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 04:57:58 INFO - "buildid": "20160510031712", 04:57:58 INFO - "pgo_build": "False", 04:57:58 INFO - "basedir": "/builds/slave/test", 04:57:58 INFO - "buildnumber": 1275, 04:57:58 INFO - "slavename": "talos-linux64-ix-004", 04:57:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:57:58 INFO - "platform": "ubuntu64_hw", 04:57:58 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:57:58 INFO - "branch": "fx-team", 04:57:58 INFO - "repo_path": "integration/fx-team", 04:57:58 INFO - "revision": "8b4e5ab50377a26e6610f21a0f8233467de19e9b", 04:57:58 INFO - "stage_platform": "linux64", 04:57:58 INFO - "builduid": "86163a5b79e0486c9de34d5d04f80478", 04:57:58 INFO - "slavebuilddir": "test" 04:57:58 INFO - } 04:57:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 04:57:58 INFO - Running post-action listener: _resource_record_post_action 04:57:58 INFO - ##### 04:57:58 INFO - ##### Finished read-buildbot-config step (success) 04:57:58 INFO - ##### 04:57:58 INFO - ##### 04:57:58 INFO - ##### Running download-and-extract step. 04:57:58 INFO - ##### 04:57:58 INFO - Running pre-action listener: _resource_record_pre_action 04:57:58 INFO - Running main action method: download_and_extract 04:57:58 INFO - mkdir: /builds/slave/test/build/tests 04:57:58 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')]} 04:57:58 INFO - https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:57:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:57:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:57:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:57:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:57:59 INFO - Downloaded 1361 bytes. 04:57:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:57:59 INFO - Using the following test package requirements: 04:57:59 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:57:59 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:57:59 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:57:59 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'jsshell-linux-x86_64.zip'], 04:57:59 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:57:59 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:57:59 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:57:59 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:57:59 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:57:59 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:57:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:57:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:59 INFO - https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:57:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:57:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:57:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:57:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:01 INFO - Downloaded 22325903 bytes. 04:58:01 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'] 04:58:01 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 04:58:02 INFO - Return code: 0 04:58:02 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 04:58: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')]} 04:58:02 INFO - https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:58:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:58:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:58:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:03 INFO - Downloaded 22325903 bytes. 04:58:03 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'] 04:58:03 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 04:58:04 INFO - Return code: 0 04:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:04 INFO - https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:58:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:58:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:58:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:05 INFO - Downloaded 10840152 bytes. 04:58:05 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'] 04:58:05 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 04:58:05 INFO - Return code: 0 04:58:05 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')]} 04:58:05 INFO - https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:58:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:58:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:58:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K2CToNDwTTutH_93JVw51g/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 04:58:07 INFO - Downloaded 54672570 bytes. 04:58:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:58:07 INFO - mkdir: /builds/slave/test/properties 04:58:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:58:07 INFO - Writing to file /builds/slave/test/properties/build_url 04:58:07 INFO - Contents: 04:58:07 INFO - build_url:https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:58:08 INFO - Running post-action listener: _resource_record_post_action 04:58:08 INFO - Running post-action listener: set_extra_try_arguments 04:58:08 INFO - ##### 04:58:08 INFO - ##### Finished download-and-extract step (success) 04:58:08 INFO - ##### 04:58:08 INFO - ##### 04:58:08 INFO - ##### Running populate-webroot step. 04:58:08 INFO - ##### 04:58:08 INFO - Running pre-action listener: _resource_record_pre_action 04:58:08 INFO - Running main action method: populate_webroot 04:58:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:58:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:58:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:58:08 INFO - '--tppagecycles', 04:58:08 INFO - '7'], 04:58:08 INFO - 'tests': ['tsvgm']}}, 04:58:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:58:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:58:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:58:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:58:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['damp', 'tps']}, 04:58:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'tests': ['damp', 'tps']}, 04:58:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['dromaeo_dom']}, 04:58:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:58:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['a11yr', 04:58:08 INFO - 'ts_paint', 04:58:08 INFO - 'tpaint', 04:58:08 INFO - 'sessionrestore', 04:58:08 INFO - 'sessionrestore_no_auto_restore', 04:58:08 INFO - 'tabpaint']}, 04:58:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['tsvgx', 04:58:08 INFO - 'tsvgr_opacity', 04:58:08 INFO - 'tart', 04:58:08 INFO - 'tscrollx', 04:58:08 INFO - 'cart']}, 04:58:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:58:08 INFO - 'tsvgr_opacity', 04:58:08 INFO - 'tart', 04:58:08 INFO - 'tscrollx', 04:58:08 INFO - 'cart']}, 04:58:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'talos_options': ['--disable-e10s'], 04:58:08 INFO - 'tests': ['tp5o']}, 04:58:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'tests': ['tp5o']}, 04:58:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'talos_options': ['--disable-e10s', 04:58:08 INFO - '--xperf_path', 04:58:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:08 INFO - 'tests': ['tp5n']}, 04:58:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:08 INFO - 'talos_options': ['--xperf_path', 04:58:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:08 INFO - 'tests': ['tp5n']}}, 04:58:08 INFO - 'talos.zip': {'path': '', 04:58:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:58:08 INFO - Running post-action listener: _resource_record_post_action 04:58:08 INFO - ##### 04:58:08 INFO - ##### Finished populate-webroot step (success) 04:58:08 INFO - ##### 04:58:08 INFO - ##### 04:58:08 INFO - ##### Running create-virtualenv step. 04:58:08 INFO - ##### 04:58:08 INFO - Running pre-action listener: _resource_record_pre_action 04:58:08 INFO - Running main action method: create_virtualenv 04:58:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:58:08 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 04:58:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:58:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:58:08 INFO - Using real prefix '/usr' 04:58:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:58:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:58:11 INFO - Installing pip.................done. 04:58:11 INFO - Return code: 0 04:58:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:11 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:11 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 04:58:11 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 04:58:11 INFO - Using env: {'DISPLAY': ':0', 04:58:11 INFO - 'HOME': '/home/cltbld', 04:58:11 INFO - 'LANG': 'en_US.UTF-8', 04:58:11 INFO - 'LANGUAGE': 'en_US:en', 04:58:11 INFO - 'LOGNAME': 'cltbld', 04:58:11 INFO - 'MAIL': '/var/mail/cltbld', 04:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:11 INFO - 'MOZ_NO_REMOTE': '1', 04:58:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:11 INFO - 'NO_EM_RESTART': '1', 04:58:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:11 INFO - 'PWD': '/builds/slave/test', 04:58:11 INFO - 'SHELL': '/bin/bash', 04:58:11 INFO - 'SHLVL': '1', 04:58:11 INFO - 'TERM': 'linux', 04:58:11 INFO - 'TMOUT': '86400', 04:58:11 INFO - 'USER': 'cltbld', 04:58:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:11 INFO - '_': '/tools/buildbot/bin/python'} 04:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:11 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 04:58:11 INFO - Cleaning up... 04:58:11 INFO - Return code: 0 04:58:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:11 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:11 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 04:58:11 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 04:58:11 INFO - Using env: {'DISPLAY': ':0', 04:58:11 INFO - 'HOME': '/home/cltbld', 04:58:11 INFO - 'LANG': 'en_US.UTF-8', 04:58:11 INFO - 'LANGUAGE': 'en_US:en', 04:58:11 INFO - 'LOGNAME': 'cltbld', 04:58:11 INFO - 'MAIL': '/var/mail/cltbld', 04:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:11 INFO - 'MOZ_NO_REMOTE': '1', 04:58:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:11 INFO - 'NO_EM_RESTART': '1', 04:58:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:11 INFO - 'PWD': '/builds/slave/test', 04:58:11 INFO - 'SHELL': '/bin/bash', 04:58:11 INFO - 'SHLVL': '1', 04:58:11 INFO - 'TERM': 'linux', 04:58:11 INFO - 'TMOUT': '86400', 04:58:11 INFO - 'USER': 'cltbld', 04:58:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:11 INFO - '_': '/tools/buildbot/bin/python'} 04:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:11 INFO - Downloading/unpacking psutil>=0.7.1 04:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:58:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:13 INFO - Installing collected packages: psutil 04:58:13 INFO - Running setup.py install for psutil 04:58:13 INFO - building 'psutil._psutil_linux' extension 04:58:13 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 04:58:13 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 04:58:13 INFO - building 'psutil._psutil_posix' extension 04:58:13 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 04:58:14 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 04:58:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:14 INFO - Successfully installed psutil 04:58:14 INFO - Cleaning up... 04:58:14 INFO - Return code: 0 04:58:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:58:14 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')]} 04:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:14 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')]} 04:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:14 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:14 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 04:58:14 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 04:58:14 INFO - Using env: {'DISPLAY': ':0', 04:58:14 INFO - 'HOME': '/home/cltbld', 04:58:14 INFO - 'LANG': 'en_US.UTF-8', 04:58:14 INFO - 'LANGUAGE': 'en_US:en', 04:58:14 INFO - 'LOGNAME': 'cltbld', 04:58:14 INFO - 'MAIL': '/var/mail/cltbld', 04:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:14 INFO - 'MOZ_NO_REMOTE': '1', 04:58:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:14 INFO - 'NO_EM_RESTART': '1', 04:58:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:14 INFO - 'PWD': '/builds/slave/test', 04:58:14 INFO - 'SHELL': '/bin/bash', 04:58:14 INFO - 'SHLVL': '1', 04:58:14 INFO - 'TERM': 'linux', 04:58:14 INFO - 'TMOUT': '86400', 04:58:14 INFO - 'USER': 'cltbld', 04:58:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:14 INFO - '_': '/tools/buildbot/bin/python'} 04:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:58:14 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 04:58:14 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 04:58:14 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 04:58:14 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 04:58:14 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 04:58:14 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 04:58:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:58:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:58:16 INFO - Installing collected packages: mozsystemmonitor 04:58:16 INFO - Running setup.py install for mozsystemmonitor 04:58:16 INFO - Successfully installed mozsystemmonitor 04:58:16 INFO - Cleaning up... 04:58:16 INFO - Return code: 0 04:58:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:58:16 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')]} 04:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:16 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')]} 04:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:16 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:16 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 04:58:16 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 04:58:16 INFO - Using env: {'DISPLAY': ':0', 04:58:16 INFO - 'HOME': '/home/cltbld', 04:58:16 INFO - 'LANG': 'en_US.UTF-8', 04:58:16 INFO - 'LANGUAGE': 'en_US:en', 04:58:16 INFO - 'LOGNAME': 'cltbld', 04:58:16 INFO - 'MAIL': '/var/mail/cltbld', 04:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:16 INFO - 'MOZ_NO_REMOTE': '1', 04:58:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:16 INFO - 'NO_EM_RESTART': '1', 04:58:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:16 INFO - 'PWD': '/builds/slave/test', 04:58:16 INFO - 'SHELL': '/bin/bash', 04:58:16 INFO - 'SHLVL': '1', 04:58:16 INFO - 'TERM': 'linux', 04:58:16 INFO - 'TMOUT': '86400', 04:58:16 INFO - 'USER': 'cltbld', 04:58:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:16 INFO - '_': '/tools/buildbot/bin/python'} 04:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:16 INFO - Downloading/unpacking blobuploader==1.2.4 04:58:16 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 04:58:16 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 04:58:16 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 04:58:16 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 04:58:16 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 04:58:16 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 04:58:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:58:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:58:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:18 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 04:58:19 INFO - Downloading docopt-0.6.1.tar.gz 04:58:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:58:19 INFO - Installing collected packages: blobuploader, requests, docopt 04:58:19 INFO - Running setup.py install for blobuploader 04:58:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:58:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:58:19 INFO - Running setup.py install for requests 04:58:19 INFO - Running setup.py install for docopt 04:58:19 INFO - Successfully installed blobuploader requests docopt 04:58:19 INFO - Cleaning up... 04:58:19 INFO - Return code: 0 04:58:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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')]} 04:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:19 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:19 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 04:58:19 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 04:58:19 INFO - Using env: {'DISPLAY': ':0', 04:58:19 INFO - 'HOME': '/home/cltbld', 04:58:19 INFO - 'LANG': 'en_US.UTF-8', 04:58:19 INFO - 'LANGUAGE': 'en_US:en', 04:58:19 INFO - 'LOGNAME': 'cltbld', 04:58:19 INFO - 'MAIL': '/var/mail/cltbld', 04:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:19 INFO - 'MOZ_NO_REMOTE': '1', 04:58:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:19 INFO - 'NO_EM_RESTART': '1', 04:58:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:19 INFO - 'PWD': '/builds/slave/test', 04:58:19 INFO - 'SHELL': '/bin/bash', 04:58:19 INFO - 'SHLVL': '1', 04:58:19 INFO - 'TERM': 'linux', 04:58:19 INFO - 'TMOUT': '86400', 04:58:19 INFO - 'USER': 'cltbld', 04:58:19 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:19 INFO - '_': '/tools/buildbot/bin/python'} 04:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:58:19 INFO - Running setup.py (path:/tmp/pip-a4mQrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:58:19 INFO - Running setup.py (path:/tmp/pip-ZjsOPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:58:19 INFO - Running setup.py (path:/tmp/pip-8JYcbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:58:19 INFO - Running setup.py (path:/tmp/pip-hjLKzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:58:20 INFO - Running setup.py (path:/tmp/pip-1JIiEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:58:20 INFO - Running setup.py (path:/tmp/pip-jVP_Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:58:20 INFO - Running setup.py (path:/tmp/pip-oCP4cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:58:20 INFO - Running setup.py (path:/tmp/pip-yAX68u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:58:20 INFO - Running setup.py (path:/tmp/pip-m1LoLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:58:20 INFO - Running setup.py (path:/tmp/pip-zME0Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:58:20 INFO - Running setup.py (path:/tmp/pip-p8LlJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:58:20 INFO - Running setup.py (path:/tmp/pip-kdzxHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:58:20 INFO - Running setup.py (path:/tmp/pip-sVFxzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:58:20 INFO - Running setup.py (path:/tmp/pip-neyY2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:20 INFO - Running setup.py (path:/tmp/pip-MbdImF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:58:20 INFO - Running setup.py (path:/tmp/pip-I4qoih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:58:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:58:20 INFO - Running setup.py (path:/tmp/pip-8E_f_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:58:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:58:20 INFO - Running setup.py install for manifestparser 04:58:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Running setup.py install for mozcrash 04:58:20 INFO - Running setup.py install for mozdebug 04:58:21 INFO - Running setup.py install for mozdevice 04:58:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozfile 04:58:21 INFO - Running setup.py install for mozhttpd 04:58:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozinfo 04:58:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozInstall 04:58:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozleak 04:58:21 INFO - Running setup.py install for mozlog 04:58:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for moznetwork 04:58:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozprocess 04:58:21 INFO - Running setup.py install for mozprofile 04:58:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:58:21 INFO - Running setup.py install for mozrunner 04:58:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:58:22 INFO - Running setup.py install for mozscreenshot 04:58:22 INFO - Running setup.py install for moztest 04:58:22 INFO - Running setup.py install for mozversion 04:58:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:58:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:58:22 INFO - Cleaning up... 04:58:22 INFO - Return code: 0 04:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:22 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:22 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 04:58:22 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 04:58:22 INFO - Using env: {'DISPLAY': ':0', 04:58:22 INFO - 'HOME': '/home/cltbld', 04:58:22 INFO - 'LANG': 'en_US.UTF-8', 04:58:22 INFO - 'LANGUAGE': 'en_US:en', 04:58:22 INFO - 'LOGNAME': 'cltbld', 04:58:22 INFO - 'MAIL': '/var/mail/cltbld', 04:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:22 INFO - 'MOZ_NO_REMOTE': '1', 04:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:22 INFO - 'NO_EM_RESTART': '1', 04:58:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:22 INFO - 'PWD': '/builds/slave/test', 04:58:22 INFO - 'SHELL': '/bin/bash', 04:58:22 INFO - 'SHLVL': '1', 04:58:22 INFO - 'TERM': 'linux', 04:58:22 INFO - 'TMOUT': '86400', 04:58:22 INFO - 'USER': 'cltbld', 04:58:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:22 INFO - '_': '/tools/buildbot/bin/python'} 04:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:58:22 INFO - Running setup.py (path:/tmp/pip-u9yt0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:58:22 INFO - Running setup.py (path:/tmp/pip-HaWgrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:58:22 INFO - Running setup.py (path:/tmp/pip-1Q7iqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:58:22 INFO - Running setup.py (path:/tmp/pip-FMceK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:58:22 INFO - Running setup.py (path:/tmp/pip-X8HBLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:58:22 INFO - Running setup.py (path:/tmp/pip-lIftDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:58:22 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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:58:22 INFO - Running setup.py (path:/tmp/pip-om4CxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:58:23 INFO - Running setup.py (path:/tmp/pip-AAYGkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:58:23 INFO - Running setup.py (path:/tmp/pip-EmcUuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:58:23 INFO - Running setup.py (path:/tmp/pip-GnZChb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:58:23 INFO - Running setup.py (path:/tmp/pip-37XfPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:58:23 INFO - Running setup.py (path:/tmp/pip-Mcpuin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:58:23 INFO - Running setup.py (path:/tmp/pip-stSoRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:58:23 INFO - Running setup.py (path:/tmp/pip-4ZZrHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:23 INFO - Running setup.py (path:/tmp/pip-JbR42s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:58:23 INFO - Running setup.py (path:/tmp/pip-tzNk4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:58:23 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)) 04:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:58:23 INFO - Running setup.py (path:/tmp/pip-BtOuYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:58:23 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)) 04:58:23 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)) 04:58:23 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)) 04:58:23 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)) 04:58:23 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)) 04:58:23 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)) 04:58:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:58:23 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 04:58:23 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 04:58:23 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 04:58:23 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 04:58:23 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 04:58:23 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 04:58:25 INFO - Downloading blessings-1.6.tar.gz 04:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:58:25 INFO - Installing collected packages: blessings 04:58:25 INFO - Running setup.py install for blessings 04:58:25 INFO - Successfully installed blessings 04:58:25 INFO - Cleaning up... 04:58:25 INFO - Return code: 0 04:58:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:58:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:58:25 INFO - Reading from file tmpfile_stdout 04:58:25 INFO - Current package versions: 04:58:25 INFO - argparse == 1.2.1 04:58:25 INFO - blessings == 1.6 04:58:25 INFO - blobuploader == 1.2.4 04:58:25 INFO - docopt == 0.6.1 04:58:25 INFO - manifestparser == 1.1 04:58:25 INFO - mozInstall == 1.12 04:58:25 INFO - mozcrash == 0.17 04:58:25 INFO - mozdebug == 0.1 04:58:25 INFO - mozdevice == 0.48 04:58:25 INFO - mozfile == 1.2 04:58:25 INFO - mozhttpd == 0.7 04:58:25 INFO - mozinfo == 0.9 04:58:25 INFO - mozleak == 0.1 04:58:25 INFO - mozlog == 3.1 04:58:25 INFO - moznetwork == 0.27 04:58:25 INFO - mozprocess == 0.22 04:58:25 INFO - mozprofile == 0.28 04:58:25 INFO - mozrunner == 6.11 04:58:25 INFO - mozscreenshot == 0.1 04:58:25 INFO - mozsystemmonitor == 0.0 04:58:25 INFO - moztest == 0.7 04:58:25 INFO - mozversion == 1.4 04:58:25 INFO - psutil == 3.1.1 04:58:25 INFO - requests == 1.2.3 04:58:25 INFO - wsgiref == 0.1.2 04:58:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:25 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:25 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 04:58:25 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 04:58:25 INFO - Using env: {'DISPLAY': ':0', 04:58:25 INFO - 'HOME': '/home/cltbld', 04:58:25 INFO - 'LANG': 'en_US.UTF-8', 04:58:25 INFO - 'LANGUAGE': 'en_US:en', 04:58:25 INFO - 'LOGNAME': 'cltbld', 04:58:25 INFO - 'MAIL': '/var/mail/cltbld', 04:58:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:25 INFO - 'MOZ_NO_REMOTE': '1', 04:58:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:25 INFO - 'NO_EM_RESTART': '1', 04:58:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:25 INFO - 'PWD': '/builds/slave/test', 04:58:25 INFO - 'SHELL': '/bin/bash', 04:58:25 INFO - 'SHLVL': '1', 04:58:25 INFO - 'TERM': 'linux', 04:58:25 INFO - 'TMOUT': '86400', 04:58:25 INFO - 'USER': 'cltbld', 04:58:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:25 INFO - '_': '/tools/buildbot/bin/python'} 04:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 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)) 04:58:26 INFO - Cleaning up... 04:58:26 INFO - Return code: 0 04:58:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:58:26 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')]} 04:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:26 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')]} 04:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:26 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:26 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 04:58:26 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 04:58:26 INFO - Using env: {'DISPLAY': ':0', 04:58:26 INFO - 'HOME': '/home/cltbld', 04:58:26 INFO - 'LANG': 'en_US.UTF-8', 04:58:26 INFO - 'LANGUAGE': 'en_US:en', 04:58:26 INFO - 'LOGNAME': 'cltbld', 04:58:26 INFO - 'MAIL': '/var/mail/cltbld', 04:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:26 INFO - 'MOZ_NO_REMOTE': '1', 04:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:26 INFO - 'NO_EM_RESTART': '1', 04:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:26 INFO - 'PWD': '/builds/slave/test', 04:58:26 INFO - 'SHELL': '/bin/bash', 04:58:26 INFO - 'SHLVL': '1', 04:58:26 INFO - 'TERM': 'linux', 04:58:26 INFO - 'TMOUT': '86400', 04:58:26 INFO - 'USER': 'cltbld', 04:58:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:26 INFO - '_': '/tools/buildbot/bin/python'} 04:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:26 INFO - Downloading/unpacking jsonschema 04:58:26 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 04:58:26 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 04:58:26 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 04:58:26 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 04:58:26 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 04:58:26 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 04:58:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:58:29 INFO - Downloading/unpacking functools32 (from jsonschema) 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:58:29 INFO - warning: no files found matching '*.txt' 04:58:29 INFO - no previously-included directories found matching 'build' 04:58:29 INFO - no previously-included directories found matching 'dist' 04:58:29 INFO - no previously-included directories found matching '.git*' 04:58:29 INFO - Installing collected packages: jsonschema, functools32 04:58:29 INFO - Running setup.py install for functools32 04:58:29 INFO - warning: no files found matching '*.txt' 04:58:29 INFO - no previously-included directories found matching 'build' 04:58:29 INFO - no previously-included directories found matching 'dist' 04:58:29 INFO - no previously-included directories found matching '.git*' 04:58:29 INFO - Successfully installed jsonschema functools32 04:58:29 INFO - Cleaning up... 04:58:29 INFO - Return code: 0 04:58:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:58:29 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')]} 04:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:29 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')]} 04:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:29 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 0x7f7934df4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', '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 04:58:29 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 04:58:29 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 04:58:29 INFO - Using env: {'DISPLAY': ':0', 04:58:29 INFO - 'HOME': '/home/cltbld', 04:58:29 INFO - 'LANG': 'en_US.UTF-8', 04:58:29 INFO - 'LANGUAGE': 'en_US:en', 04:58:29 INFO - 'LOGNAME': 'cltbld', 04:58:29 INFO - 'MAIL': '/var/mail/cltbld', 04:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:29 INFO - 'MOZ_NO_REMOTE': '1', 04:58:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:29 INFO - 'NO_EM_RESTART': '1', 04:58:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:29 INFO - 'PWD': '/builds/slave/test', 04:58:29 INFO - 'SHELL': '/bin/bash', 04:58:29 INFO - 'SHLVL': '1', 04:58:29 INFO - 'TERM': 'linux', 04:58:29 INFO - 'TMOUT': '86400', 04:58:29 INFO - 'USER': 'cltbld', 04:58:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:29 INFO - '_': '/tools/buildbot/bin/python'} 04:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:29 INFO - Downloading/unpacking flake8 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:29 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 04:58:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:58:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:58:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:31 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 04:58:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:58:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:32 INFO - no previously-included directories found matching 'docs/_build' 04:58:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:58:32 INFO - Running setup.py install for pep8 04:58:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:32 INFO - no previously-included directories found matching 'docs/_build' 04:58:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:58:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:58:32 INFO - Cleaning up... 04:58:32 INFO - Return code: 0 04:58:32 INFO - Running post-action listener: _resource_record_post_action 04:58:32 INFO - Running post-action listener: _start_resource_monitoring 04:58:32 INFO - Starting resource monitoring. 04:58:32 INFO - ##### 04:58:32 INFO - ##### Finished create-virtualenv step (success) 04:58:32 INFO - ##### 04:58:32 INFO - ##### 04:58:32 INFO - ##### Running install step. 04:58:32 INFO - ##### 04:58:32 INFO - Running pre-action listener: _resource_record_pre_action 04:58:32 INFO - Running main action method: install 04:58:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:58:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:58:32 INFO - Reading from file tmpfile_stdout 04:58:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:58:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:58:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:58:32 INFO - Reading from file tmpfile_stdout 04:58:32 INFO - Output received: 04:58:32 INFO - Usage: mozinstall [options] installer 04:58:32 INFO - Options: 04:58:32 INFO - -h, --help show this help message and exit 04:58:32 INFO - -d DEST, --destination=DEST 04:58:32 INFO - Directory to install application into. [default: 04:58:32 INFO - "/builds/slave/test"] 04:58:32 INFO - --app=APP Application being installed. [default: firefox] 04:58:32 INFO - mkdir: /builds/slave/test/build/application 04:58:32 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'] 04:58:32 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 04:58:48 INFO - Reading from file tmpfile_stdout 04:58:48 INFO - Output received: 04:58:48 INFO - /builds/slave/test/build/application/firefox/firefox 04:58:48 INFO - Running post-action listener: _resource_record_post_action 04:58:48 INFO - ##### 04:58:48 INFO - ##### Finished install step (success) 04:58:48 INFO - ##### 04:58:48 INFO - ##### 04:58:48 INFO - ##### Running run-tests step. 04:58:48 INFO - ##### 04:58:48 INFO - Running pre-action listener: _resource_record_pre_action 04:58:48 INFO - Running main action method: run_tests 04:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:58:48 INFO - Python 2.7.3 04:58:48 INFO - Return code: 0 04:58:48 INFO - grabbing minidump binary from tooltool 04:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c529c0>, '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 0x2c538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c53d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:58:48 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 04:58:48 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 04:58:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:58:48 INFO - Return code: 0 04:58:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:58:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:58:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:58:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:58:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:58:49 INFO - Return code: 0 04:58:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 04:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 04:58:49 INFO - Using env: {'DISPLAY': ':0', 04:58:49 INFO - 'HOME': '/home/cltbld', 04:58:49 INFO - 'LANG': 'en_US.UTF-8', 04:58:49 INFO - 'LANGUAGE': 'en_US:en', 04:58:49 INFO - 'LOGNAME': 'cltbld', 04:58:49 INFO - 'MAIL': '/var/mail/cltbld', 04:58:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:58:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:49 INFO - 'MOZ_NO_REMOTE': '1', 04:58:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:58:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:58:49 INFO - 'NO_EM_RESTART': '1', 04:58:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:49 INFO - 'PWD': '/builds/slave/test', 04:58:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:58:49 INFO - 'SHELL': '/bin/bash', 04:58:49 INFO - 'SHLVL': '1', 04:58:49 INFO - 'TERM': 'linux', 04:58:49 INFO - 'TMOUT': '86400', 04:58:49 INFO - 'USER': 'cltbld', 04:58:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762', 04:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:49 INFO - '_': '/tools/buildbot/bin/python'} 04:58:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 04:58:49 INFO - mozversion application_buildid: 20160510031712 04:58:49 INFO - mozversion application_changeset: 8b4e5ab50377a26e6610f21a0f8233467de19e9b 04:58:49 INFO - mozversion application_display_name: Nightly 04:58:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:58:49 INFO - mozversion application_name: Firefox 04:58:49 INFO - mozversion application_remotingname: firefox 04:58:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:58:49 INFO - mozversion application_vendor: Mozilla 04:58:49 INFO - mozversion application_version: 49.0a1 04:58:49 INFO - mozversion platform_buildid: 20160510031712 04:58:49 INFO - mozversion platform_changeset: 8b4e5ab50377a26e6610f21a0f8233467de19e9b 04:58:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:58:49 INFO - mozversion platform_version: 49.0a1 04:58:49 INFO - using testdate: 1462881529 04:58:49 INFO - actual date: 1462881529 04:58:49 INFO - starting webserver on 'localhost:50674' 04:58:49 INFO - SUITE-START | Running 2 tests 04:58:49 INFO - TEST-START | dromaeo_css 04:58:49 INFO - operating with platform_type : linux_ 04:58:49 INFO - Initialising browser for dromaeo_css test... 04:58:49 INFO - TEST-INFO | started process 31375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIut2AP/profile http://localhost:50674/getInfo.html) 04:58:53 INFO - PROCESS | 31375 | 04:58:53 INFO - PROCESS | 31375 | (process:31440): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:58:54 INFO - PROCESS | 31375 | __metrics Screen width/height:1600/1200 04:58:54 INFO - PROCESS | 31375 | colorDepth:24 04:58:54 INFO - PROCESS | 31375 | Browser inner width/height: 1024/697 04:58:54 INFO - PROCESS | 31375 | __metrics 04:58:55 ERROR - PROCESS | 31375 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 04:58:55 INFO - TEST-INFO | 31375: exit 0 04:58:55 INFO - Browser initialized. 04:58:55 INFO - Running cycle 1/1 for dromaeo_css test... 04:58:55 INFO - TEST-INFO | started process 31491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIut2AP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:58:57 INFO - PROCESS | 31491 | 04:58:57 INFO - PROCESS | 31491 | (process:31551): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:59:07 INFO - PROCESS | 31491 | RSS: Main: 163688448 04:59:07 INFO - PROCESS | 31491 | 04:59:56 INFO - PROCESS | 31491 | 1462881596983 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] 05:02:08 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50674/tests/dromaeo/cssquery-ext.html) 05:02:09 INFO - PROCESS | 31491 | RSS: Main: 166363136 05:02:09 INFO - PROCESS | 31491 | 05:05:12 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-ext.html (next: http://localhost:50674/tests/dromaeo/cssquery-jquery.html) 05:05:12 INFO - PROCESS | 31491 | RSS: Main: 166199296 05:05:12 INFO - PROCESS | 31491 | 05:08:14 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50674/tests/dromaeo/cssquery-mootools.html) 05:08:15 INFO - PROCESS | 31491 | RSS: Main: 167292928 05:08:15 INFO - PROCESS | 31491 | 05:11:16 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50674/tests/dromaeo/cssquery-prototype.html) 05:11:17 INFO - PROCESS | 31491 | RSS: Main: 164868096 05:11:17 INFO - PROCESS | 31491 | 05:14:20 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50674/tests/dromaeo/cssquery-yui.html) 05:14:20 INFO - PROCESS | 31491 | RSS: Main: 166531072 05:14:20 INFO - PROCESS | 31491 | 05:17:22 INFO - PROCESS | 31491 | Cycle 1(1): loaded http://localhost:50674/tests/dromaeo/cssquery-yui.html (next: http://localhost:50674/tests/dromaeo/cssquery-dojo.html) 05:17:23 INFO - PROCESS | 31491 | RSS: Main: 168681472 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | __start_tp_report 05:17:23 INFO - PROCESS | 31491 | _x_x_mozilla_page_load 05:17:23 INFO - PROCESS | 31491 | _x_x_mozilla_page_load_details 05:17:23 INFO - PROCESS | 31491 | |i|pagename|runs| 05:17:23 INFO - PROCESS | 31491 | |0;dojo.html;2600.4929038837427;2611.216635009499;2636.538605743995;2649.973500264997;2671.025075847316;4622.422197225347;4655.3482512448245;4682.6019788318035;4711.222648263032;4714.222153344698;4627.467841198263;4650.209464391053;4663.207254766689;4676.205045142326;4706.058788242357;4243.257429949762;4320.351947207912;4376.146651402978;4377.562243775613;4398.582134697199;4345.087531618364;4376.146651402978;4376.277914144162;4421.88945276369;4443.066955939256;4130.586941305875;4261.083866968585;4264.97640566265;4295.4415925929425;4322.243607368698;4300.806463709109;4348.456442944632;4382.189294980439;4404.273294906337;4404.647628189737;4293.742375457483;4295.806688699016;4338.869833904988;4364.803583838735;4400.251957167289;4565.908681826378;4622.86131416058;4639.327297541838;4660.627149828039;4720.1031803957485;5006.098902197603;5050.141475949061;5067.163917953533;5077.009983053306;5130.7691153897795;5005.174146265862;5161.3806343238675;5172.094883395391;5200.167973124319;5274.103402421598;4789.784459699322;4825.372701548777;4853.393325834236;4866.78099485524;4869.537393947569;5267.394249661338;5273.077211488051;5279.366476022902;5300.992811365828;5323.467653234723;4844.2007068833955;4970.17992031318;4985.401751789808;5011.6241281904;5092.28707980883;5018.422881368616;5031.547160755549;5077.822276220315;5095.108356037753;5110.872228194325;5054.393472783289;5081.0854046272025;5084.542391184811;5095.8980820383385;5110.977804439052;4949.925751113737;5033.144365457881;5045.041442125985;5060.342155519759;5112.309838171799;4359.956400436018;4372.147431250875;4403.625691816167;4421.469423669116;4432.091421258634;3759.2105657811617;3763.7741735495956;3804.1250512382308;3842.9039274018373;3856.8650097246464;4323.870283891489;4325.264705000094;4369.934450983238;4416.447944006999;4422.248217802981;5044.218146187347;5089.796408143633;5106.387233531926;5114.207297868837;5135.948640513622;4373.343998400265;4381.868543943687;4382.890427739332;4434.600885920283;4456.598906098467;4482.843100491467;4507.887302817456;4533.274676051882;4567.657425693086;4580.702254353457;4688.601468875184;4694.34279200919;4713.057388522309;4715.363425937455;4770.642201834807;5002.924956125734;5016.54851063406;5023.070731914608;5043.722595257296;5078;4677.836275730333;4775.737334446639;4778.116048531033;4807.6394270429155;4815.181419158681;5058.215976523645;5075.28945947575;5122.410922743932;5126.53861152498;5126.79492820283;4944.629152813482;5155.304033955368;5171.45699701541;5183.481651834786;5199.376074871039;4778.283257511401;4796.25658023007;4852.563269305639;4896.877578060577;4924.606031517542;4903.901921961612;4922.975385123052;4945.406551213733;4948.505149485023;4975.353204083591;4796.664233503621;4827.058723548875;4877.95122048775;4886.998165376018;4946.258061290835;4908.263760435963;4931.48219436954;4941.085899108532;4968.478309777566;4988.900221995613;4907.950920490749;4917.655764096622;4931.964287499665;4952.702837829742;4969.254611808257 05:17:23 INFO - PROCESS | 31491 | |1;ext.html;566.0716425062897;577.4946921443736;587.1369087441461;589.1986897818969;596.0373995996467;36565.451518227244;40473;40671.59328406715;40700.389494157615;40899;40145;40478.797606012005;40496.39255411171;40556.59443405565;40687.18625627486;7704.576248306327;7846.843063138734;7847.254510821463;7885.921140788605;7896.131425543185;7746.845063098763;7757.263060009318;7847;7858.057033156029;7864.5674487903425;7634.198409167041;7648.311651951323;7650.655720492591;7675;7712.498687585289;7778.144404115543;7793.103793063785;7799.1031031430975;7821.335186509125;7834.373250139975;7712.421568382394;7741.109772376192;7766.18455062216;7821.726239581587;7828.686852525892;37605.43591846125;37967;38004.80997595022;38419.80790096032;38574.22851542953;3012.487877060883;3340.4600449429054;3349.112485191426;3498.7201023918024;3568.571771387424;29331.413371732448;29643.70356296431;29975.850120749474;30085.247868803235;30205;3148.4332820092372;3179.4753865612147;3248.902532924016;3274.279658475132;3282.376348493802;244697;245613;246374;247740;248574;27700.861495692392;27715.307117321823;28466.715332846405;42939.57060429418;43682.563174367846;35132.824335878155;35635.643643563235;35664.46503302453;35710.28579428397;35818.46272305917;13134.146280491737;13192.538261161006;13211.33943302831;13254.801177982337;13260.867391325963;11735.647930562096;11820.940895295469;11827.467763950643;11846.703832404259;11877.881221187678;8216.055153657286;8224.671013159406;8241;8275.634520304082;8290.129536398592;3018.4868572342752;3021.425929073513;3041.391721655678;3041.5437684347526;3080.430120427728;964.9421034737938;978.4862946952765;1061.601899287767;1066.4134725900724;1117.1732917640886;27434.725652743615;28427;28692.856535717605;29201.415971680446;29327.413451731274;1023.242800327767;1058.4231593781408;1090.5419723716059;1092.7432053467428;1102.2725001498973;13622.250776207404;13714.657133571542;13912;13935.721285574435;14134.434622615187;358313;359297;359545.8090838169;359772;362449;51093.23360149601;51619.22571161436;51686.74156629193;51806;52233;1260.7415479826614;1269.6571925580226;1297.4291311822767;1307.3136603283353;1319.0173320875817;26792.33019174536;27244.863775680995;27375.589366159922;27422.862885685445;27787.16638500848;42155;42417.36373954331;42621.147577048905;42681.573184269;42741.35887961745;311667;313076;313583;314611;315064;25429.491410172064;25645.230643080737;25652.486950260518;25854.353641159123;25887.741122589287;35390.46914296236;35932.10169745777;36116.09709757277;36121;36240;37172.628273718;37219.81390093032;37236.441453378764;37541.81229093837;37781.81109094437;36176.63823361733;36450.45324320084;36499.635003650685;36557.817210913774;36889.26221475502;36745.448818267214;36787.816060919526;36866.8156659215;36992.07519812026;37198.81235036561;32801.179970500925;33330.5000424989;33411;33593.16017099592;33674.326513470085;25216.243512694648;25221.873890630428;25262.368440788392;25321.62017569701;25549.48901021932 05:17:23 INFO - PROCESS | 31491 | |2;jquery.html;374.71334429161686;378.70839453620715;384.41184986969927;387.1849756263066;393.52580140930183;14895.10629362239;16204.432844850433;17157;17207.827921720796;17251.396201132964;10540.736481587965;11085.168612354086;11091.944540277307;11424.71438214043;11439.370834604093;4678.181318269306;4811.35046768687;4831.782569784351;4838.758062096881;4867.975660121694;4678.345031695549;4805.20714082176;4808.447028591721;4821.915069109457;4836.9516304837025;4820.879478013043;4838.975805120969;4846.9030619387595;5278.5249327560705;5280.102382594968;5122.948770512303;5244.685318880879;5266.7366631668265;5272.156454967187;5298.523132918037;4555.06621142665;4689.780657029197;4723.315119307716;4751.239801631722;4795.544423279783;12718.173318734345;12764;12768.361581920866;12991.22052676833;16752.41366552177;2025.8987050647409;2056.249468943825;2208.950748394516;2403.411164264749;2455.5579995600783;8813.162749538784;9235.676751313738;9725.124738773544;11598.652040438801;11828.763424731544;1721.5610019445087;1730.8874923130081;1735.9045252511107;1747.580580660645;1834.4404956488265;64742;67286.65426691438;67286.66356668185;69447.30552694459;70038;10466.738331541772;10511;10514.264001519974;10579.471026448648;10674.573017079385;14189.219592922282;14476.34856431463;14576.489822856149;14638.268086595628;14675;7330.157031941395;7433.145188303306;7447.88828167578;7465.104187497426;7475.663595138232;7395.889061664079;7421.0723659542555;7447.1063472382575;7475.401967842559;7475.775726728207;4452.510223875371;4509.210888094571;4527.501974782771;4609.1703493371515;4676.18166820805;1850.7408962745226;1885.5003424092627;1931.8840869547591;1945.5233467800479;1994.6110508451004;812.2293400387213;926.0924294191595;978.3885071830106;1002.7092143278368;1030.2081359515646;16267.837321626868;16633.83366166347;16682.165891705365;16743.995360278423;16756.16219189036;917.2249449215402;930.4138392812484;943.990560094404;1031.0256807317014;1042.7028296935337;7839.149894833392;9473.905260947302;9480.099390557885;9504.809903801885;9528.809423811623;381626;384356;388153;390300;393239;42666;43377.783111084245;43391.349129763075;57428;71198.2880171202;977.9902200978041;978.3982850546862;980.6126580001003;999.6501224571342;1002.2733518199393;14392.496262630757;14547.563573092824;14677.48628797987;14683.632909177357;14723.411063557765;24310;31121.53317700191;33090.33819323552;34678.82660586681;35832.64167358293;48359.5164048355;48719.26921096115;49105;49404.25893611527;49437.25844112269;7746.405371689406;7817.179196184319;7927.405444591794;7977.28204461578;8045.436819422584;10646.787064258826;10970.527239371231;11057.612983545538;11182.273152245078;11258.09935205198;9094.499802510662;9566.617335306788;9582.616695332388;9816.16562592159;10325.535350909377;9351.906480935375;9419.764505887408;9474.336796424184;9996.100350968447;10222.897771022492;9873.111419972238;9941.204703623549;10001.249906257206;10070.942551032036;10135.885052644056;7619.784076376272;7677.347425468898;7758.069031716004;7832.295093441618;7900.486468379537;8008.638531449551;9577.712668619952;9922.751931201778;10006.09945104944;10187.388756674623 05:17:23 INFO - PROCESS | 31491 | |3;mootools.html;1275.5471807508338;1384.639993601664;1394.3725323604378;1728.4900954218513;1753.8070812210647;6627.271000189975;6873.79378618641;7251.70993160273;7255.927440725598;7345.66944487498;7455.627218639073;7711.961440192791;7740.845183096335;7795.53226806392;7846;1917.5206198450421;1921.289123024483;1926.306529649325;1931.9613607727908;1943.7861835198116;2199.274239500961;2210.53578748463;2213.7896899794496;2221.3669104305277;2222.3221917315213;2186.180182431588;2191.824654027682;2193.0021840062814;2195.2865318771383;2213.092632020872;2135.765065842756;2136.6154092263387;2140.9723332800263;2147.076756994492;2149.752778430477;2081.458820706612;2136.369770917583;2147.3128598848375;2162.7729088445803;2182.0726191368763;7718.807029824299;7877.172896845806;7893.881591776128;7895.092064412609;7897.526148431112;4540.205464043779;4689.554492323258;4698.647601429906;4702.177119004161;4730.408698912636;2831.787615928793;2891.5373540233672;3015.381846721417;3017.230606195406;3018.170003249102;889.8487257166297;923.0169869089399;923.3628796130648;928.5960607135918;953.609020301673;10409.583616655325;10483.10893574047;10544.789104217949;10573.418461984587;10643.787124257471;4279.786010699452;4296.591823776736;4423.181711383404;4445.799939002753;4454.643628509712;8936.955315223382;8946.015938246788;8954.597043133075;9005.14451127142;9024.593893274818;4701.294805779161;4785.545373189541;4797.944452220506;4819.903601927942;4832.44426890905;3697.5378077740284;4039.0912044790275;4210.263203939298;4343.5439278875265;4360.4331436913635;3654.7258955578436;3705.1107734144152;3733.9253214936093;3796.544414670202;3883.1845312484693;1198.269055875915;1211.6970757310673;1213.5934461955344;1219.0491416694992;1225.3628113380992;817.5666896544722;883.7702197428715;890.5413711938357;900.8963969143632;904.421170450912;5113;5269.789208431696;5271.393789714155;5344.465553444625;5439.020976224318;842.8314337132598;881.7971866470748;927.1840780113457;927.8422668146296;935.4293880732747;3520.419130843382;3617.529721136288;3706.129059670975;3734.6452087051684;3795.772253664789;9079.546022698838;9512.904870951203;9566.08678264336;9566.66516671913;9576.138147566753;9224.907750922539;9293.39592926471;9407.247420206368;9409.388389754644;9439.433633981984;7331.486795924234;7344.889826652605;7400.259974002664;7431;7492.812679682943;4924.2613607957655;5091.87270318245;5110.259012443249;5181.5595674368105;5227;8232;8896.911030889609;8924.464532128093;8969.058248884034;8993.055729148346;8283.83432331362;8603.741887743377;8682.565871706516;8850.33622478304;9016.954915225644;5649.237352957298;6016.7292471838;6057.4851137653795;6743.629100399525;6918.481113916577;7075.752348667772;7140.571565706075;7155.42756579462;7177.533460325062;7205.315495027964;6191.133241346125;6449.935500644933;6471.611703297817;6484.5460817742305;6551.050097735706;6258.655773932477;6265.592736472115;6280.214973128359;6330.461910737639;6388.361163883575;5907.261592300963;5964.343922168645;5964.612300200473;5965.373635768182;5995.970020149872;4323.762193079411;4391.626711729411;4430.091831174537;4466.821327146878;4514.909701806011;4284.485861696617;4441.733495990251;4473.843415480443;4484.775761211991;4519.593236608721 05:17:23 INFO - PROCESS | 31491 | |4;prototype.html;303.8936372269706;310.4210647143079;313.9089663997441;318.9362127574484;319.74260720120293;4736.3369128322065;4753.33453316536;4789.425268967724;4900.754962251886;4912.582430493412;4813.638977076725;4838.564529192372;4840.515948405157;4879.146149423855;4910.9263361049525;3781.9977705907954;3824.445455408964;3836.175222327199;3837.827297771607;3838.5777564467885;3721.6650501454865;3800.0689830991446;3813.1801662642524;3820.6561409473143;3852.5184351956004;3756.079760458678;3768.5477742670782;3770.26479836432;3789.5831458539537;3790.4314352847155;3750.3372605450163;3758.624137586247;3769.3968964965748;3780.1494663700587;3789.962100379002;3348.363810875926;3359.2945481448924;3365.562476878015;3390.186355274741;3406.5230867678547;4371.9725864421835;4627.259638457831;4640.303954406832;4659.906801863944;4681.414823147106;4234.110836724291;4244.236037513247;4245.872623821259;4259.254630439661;4266.104118135165;4995.426440671177;5005.57452616528;5020.590116743947;5031.195008798574;5088.938979713222;3620.7827530348263;3621.384364657988;3625.981870090659;3628.11111277736;3630.455431685242;5047.596192304607;5250.501202385767;5279.128943724281;5286.920696189561;5327.920081198785;3619.8778378702686;3659.5059666945153;3662.8901132966053;3670.0274427276795;3682.263547290499;3706.3513885070006;3713.925721485555;3716.1267102230954;3731.962680373216;3741.8587330864348;4119;4198.706090573692;4234.767087810138;4236.978815105905;4255.808373655382;5103.642745007888;5115.974420127875;5118.820841270538;5135.87160320995;5153.639245252796;1202.2666173634077;1268.5052829396543;1288.2592509307185;1293.5925183567147;1294.3916359311108;802.7917983434893;814.8411059843393;831.301706566487;837.677494164751;838.7316058861228;3876.457295978565;3884.1649045455365;3907.7459965102175;3922.490076290064;3935.8032098394965;4448.399466072039;4489.483709373399;4503.4555476155865;4506;4527.728336299832;3880.4373365861793;3922.882313530599;3934.6852251819787;3952.3281042222893;3988.202359528106;2354.705661792276;2383.690120284352;2414.4205390706106;2424.1878970544713;2425.50277193175;4232.873013809591;4276.273033584298;4279.828806847691;4286.357046443059;4314.50383205929;4483.417155769729;4539.818407263739;4607.193741095362;4608.700434471749;4631.51369106239;3962.465067215889;4156.56356082613;4176.43618111551;4268.978655106766;4302.333138363559;4479.619232365286;4534.274516077412;4540.818367265272;4561.608709343682;4586.243269860569;4463.754493502888;4476.798544065459;4488.5287044859815;4491.3487544306545;4556.498785133566;4223;4239.385289132997;4248.957510424856;4313.352997050468;4489.461264648263;3221.355728854285;3643.9089022773583;3683;3709.3508635989133;3726.2733766916276;5065.848024559269;5240.606954478353;5247.711375874211;5323.908598737172;5329.57363410934;4881.925976285212;5106.157484015096;5109.467159852104;5110.514501122388;5119.897602048012;5106.872328191825;5107.744612769272;5108.948910510847;5127.230915362726;5128.666636668605;4803.447603525499;4965.4041515018425;4965.726885021359;5015.122353588181;5031.7169121873685;5019.7490125494305;5108.540231379194;5121.334226550524;5142.202958541432;5157.871053223699;5008.123578373843;5019.422766381913;5019.472955339783;5035.571976382049;5041.6470847040355 05:17:23 INFO - PROCESS | 31491 | |5;yui.html;732.4067505320687;738.6602163005017;749.6064566102796;781.9335356494697;795.2326005404783;11860.051195904329;11977.4011299435;12390.504379824797;12450.066245031614;12506;12304;12320.4455799489;12339.259644421341;12355.814662780065;12364.319962402064;670.6244503078267;838.3000194837302;847.9576021198916;850.6172222499869;859.3468963587687;855.6021450025737;855.7304449098546;858.8153546987396;859.4413631139747;867.5011868175793;907.8411278026352;910.5538286239761;910.6357457017212;912.739869137296;915.3363811236867;908.9454632722058;909.5225006871412;910.7632015675906;911.8768966189579;912.1881525442361;667.5627464011031;668.8428219368444;670.0786418674322;670.6914819183132;670.7518218259227;11587.188896777228;11626.709332266677;11653.009494193004;11679.299242045505;11695.064394848478;12397.938010309981;12565.18326308787;12625.116241863074;12650.936745316307;12976.805347919788;9428;9541.475218862959;9639.951800241024;9668.371555848847;9917.355371900803;4012.7391719538427;4045.6763458923215;4130.855420060284;4147.129102888397;4232.682548808852;41996.58003419957;42421.78789106035;42424.151516969796;42429.1514169718;42712.99999999969;9133.49765762876;9183.77040573991;9279.118483744034;9298.256139508823;9333.206677432494;16254.349826007065;16379.181040947906;16470;16481.25834337458;16512.431319024672;6581.670916454158;6650.733970641217;6662.600243985376;6735.461163106939;6751.0886030386255;3465.1163953191776;3524.6122926478065;3575.9987203583037;3602.819859007039;3605.1708107135505;2715.877785499618;2796.2170592234206;2808.7331703488135;2823.223613506302;2838.318803487148;523.2386877093819;535.8499620106377;539.7436217796516;541.5423966748092;542.2110828744168;456.45681638849663;509.571959553969;516.1921592707475;580.9099589563624;594.2779522879636;7082.114735658043;7446.404287657083;7514.887276690854;7671.808204794925;7937.087234968052;476.9642276829252;575.1746244139673;580.2717589425245;592.6236839606795;597.2683462758173;3241.9351612967607;3393.50794134854;3414.3342048300838;3420.1449637590604;3423.2297733009873;49611.25583116256;50476;51883.74058129685;52090.47909520932;52555.47444525582;44226.778866105466;45582.54417455849;46312.53687463082;46718;47403.5259647406;42549.14901701949;43224;43326.35010474845;43350.56649433528;44607.21571137118;6980.546264492792;7211.891821622785;7265.12818461788;7289.781306560812;7306.378957788647;27894.44211115807;28094.71905280921;28348.85825570859;29126.70873291325;29291.41417171687;34154.48768268428;34924.47613285854;37453.4381984265;37945.43081853719;38104;8703.43427677199;8792.384533082879;8823.602937867683;8865.246454051477;8944;10661.466926653482;10741.570337186426;10800.729981750519;10830.566777329133;10872.238943274211;9642.469664168257;10086.646967356108;10312;10619;10779.353238805697;9819.116279534876;9907.900920990698;9965.551550180404;9980.451075190935;10291.125254353465;9788.9021109788;9811.460369679737;10144.239182061227;10191.133753631024;10365.9481702591;8347.300207389584;8405.873911891204;8468;8662.523561203941;8738.388312818295;8477.321814254721;8831.470111793313;8833.779155521164;8838.337124715543;9031.142041506046 05:17:23 INFO - PROCESS | 31491 | __end_tp_report 05:17:23 INFO - PROCESS | 31491 | __start_cc_report 05:17:23 INFO - PROCESS | 31491 | _x_x_mozilla_cycle_collect,322 05:17:23 INFO - PROCESS | 31491 | __end_cc_report 05:17:23 INFO - PROCESS | 31491 | __startTimestamp1462882643243__endTimestamp 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | ------- Summary: start ------- 05:17:23 INFO - PROCESS | 31491 | Number of tests: 6 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#0] dojo.html Cycles:165 Average:4689.45 Median:4807.64 stddev:498.49 (10.4%) stddev-sans-first:472.32 05:17:23 INFO - PROCESS | 31491 | Values: 2600.5 2611.2 2636.5 2650.0 2671.0 4622.4 4655.3 4682.6 4711.2 4714.2 4627.5 4650.2 4663.2 4676.2 4706.1 4243.3 4320.4 4376.1 4377.6 4398.6 4345.1 4376.1 4376.3 4421.9 4443.1 4130.6 4261.1 4265.0 4295.4 4322.2 4300.8 4348.5 4382.2 4404.3 4404.6 4293.7 4295.8 4338.9 4364.8 4400.3 4565.9 4622.9 4639.3 4660.6 4720.1 5006.1 5050.1 5067.2 5077.0 5130.8 5005.2 5161.4 5172.1 5200.2 5274.1 4789.8 4825.4 4853.4 4866.8 4869.5 5267.4 5273.1 5279.4 5301.0 5323.5 4844.2 4970.2 4985.4 5011.6 5092.3 5018.4 5031.5 5077.8 5095.1 5110.9 5054.4 5081.1 5084.5 5095.9 5111.0 4949.9 5033.1 5045.0 5060.3 5112.3 4360.0 4372.1 4403.6 4421.5 4432.1 3759.2 3763.8 3804.1 3842.9 3856.9 4323.9 4325.3 4369.9 4416.4 4422.2 5044.2 5089.8 5106.4 5114.2 5135.9 4373.3 4381.9 4382.9 4434.6 4456.6 4482.8 4507.9 4533.3 4567.7 4580.7 4688.6 4694.3 4713.1 4715.4 4770.6 5002.9 5016.5 5023.1 5043.7 5078.0 4677.8 4775.7 4778.1 4807.6 4815.2 5058.2 5075.3 5122.4 5126.5 5126.8 4944.6 5155.3 5171.5 5183.5 5199.4 4778.3 4796.3 4852.6 4896.9 4924.6 4903.9 4923.0 4945.4 4948.5 4975.4 4796.7 4827.1 4878.0 4887.0 4946.3 4908.3 4931.5 4941.1 4968.5 4988.9 4908.0 4917.7 4932.0 4952.7 4969.3 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#1] ext.html Cycles:180 Average:44972.72 Median:35764.37 stddev:81652.06 (228.3%) stddev-sans-first:81813.05 05:17:23 INFO - PROCESS | 31491 | Values: 566.1 577.5 587.1 589.2 596.0 36565.5 40473.0 40671.6 40700.4 40899.0 40145.0 40478.8 40496.4 40556.6 40687.2 7704.6 7846.8 7847.3 7885.9 7896.1 7746.8 7757.3 7847.0 7858.1 7864.6 7634.2 7648.3 7650.7 7675.0 7712.5 7778.1 7793.1 7799.1 7821.3 7834.4 7712.4 7741.1 7766.2 7821.7 7828.7 37605.4 37967.0 38004.8 38419.8 38574.2 3012.5 3340.5 3349.1 3498.7 3568.6 29331.4 29643.7 29975.9 30085.2 30205.0 3148.4 3179.5 3248.9 3274.3 3282.4 244697.0 245613.0 246374.0 247740.0 248574.0 27700.9 27715.3 28466.7 42939.6 43682.6 35132.8 35635.6 35664.5 35710.3 35818.5 13134.1 13192.5 13211.3 13254.8 13260.9 11735.6 11820.9 11827.5 11846.7 11877.9 8216.1 8224.7 8241.0 8275.6 8290.1 3018.5 3021.4 3041.4 3041.5 3080.4 964.9 978.5 1061.6 1066.4 1117.2 27434.7 28427.0 28692.9 29201.4 29327.4 1023.2 1058.4 1090.5 1092.7 1102.3 13622.3 13714.7 13912.0 13935.7 14134.4 358313.0 359297.0 359545.8 359772.0 362449.0 51093.2 51619.2 51686.7 51806.0 52233.0 1260.7 1269.7 1297.4 1307.3 1319.0 26792.3 27244.9 27375.6 27422.9 27787.2 42155.0 42417.4 42621.1 42681.6 42741.4 311667.0 313076.0 313583.0 314611.0 315064.0 25429.5 25645.2 25652.5 25854.4 25887.7 35390.5 35932.1 36116.1 36121.0 36240.0 37172.6 37219.8 37236.4 37541.8 37781.8 36176.6 36450.5 36499.6 36557.8 36889.3 36745.4 36787.8 36866.8 36992.1 37198.8 32801.2 33330.5 33411.0 33593.2 33674.3 25216.2 25221.9 25262.4 25321.6 25549.5 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#2] jquery.html Cycles:180 Average:22873.47 Median:4582.12 stddev:63566.96 (1387.3%) stddev-sans-first:63722.83 05:17:23 INFO - PROCESS | 31491 | Values: 374.7 378.7 384.4 387.2 393.5 14895.1 16204.4 17157.0 17207.8 17251.4 10540.7 11085.2 11091.9 11424.7 11439.4 4678.2 4811.4 4831.8 4838.8 4868.0 4678.3 4805.2 4808.4 4821.9 4837.0 4820.9 4839.0 4846.9 5278.5 5280.1 5122.9 5244.7 5266.7 5272.2 5298.5 4555.1 4689.8 4723.3 4751.2 4795.5 12718.2 12764.0 12768.4 12991.2 16752.4 2025.9 2056.2 2209.0 2403.4 2455.6 8813.2 9235.7 9725.1 11598.7 11828.8 1721.6 1730.9 1735.9 1747.6 1834.4 64742.0 67286.7 67286.7 69447.3 70038.0 10466.7 10511.0 10514.3 10579.5 10674.6 14189.2 14476.3 14576.5 14638.3 14675.0 7330.2 7433.1 7447.9 7465.1 7475.7 7395.9 7421.1 7447.1 7475.4 7475.8 4452.5 4509.2 4527.5 4609.2 4676.2 1850.7 1885.5 1931.9 1945.5 1994.6 812.2 926.1 978.4 1002.7 1030.2 16267.8 16633.8 16682.2 16744.0 16756.2 917.2 930.4 944.0 1031.0 1042.7 7839.1 9473.9 9480.1 9504.8 9528.8 381626.0 384356.0 388153.0 390300.0 393239.0 42666.0 43377.8 43391.3 57428.0 71198.3 978.0 978.4 980.6 999.7 1002.3 14392.5 14547.6 14677.5 14683.6 14723.4 24310.0 31121.5 33090.3 34678.8 35832.6 48359.5 48719.3 49105.0 49404.3 49437.3 7746.4 7817.2 7927.4 7977.3 8045.4 10646.8 10970.5 11057.6 11182.3 11258.1 9094.5 9566.6 9582.6 9816.2 10325.5 9351.9 9419.8 9474.3 9996.1 10222.9 9873.1 9941.2 10001.2 10070.9 10135.9 7619.8 7677.3 7758.1 7832.3 7900.5 8008.6 9577.7 9922.8 10006.1 10187.4 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#3] mootools.html Cycles:180 Average:5045.37 Median:5248.39 stddev:2787.91 (53.1%) stddev-sans-first:2781.33 05:17:23 INFO - PROCESS | 31491 | Values: 1275.5 1384.6 1394.4 1728.5 1753.8 6627.3 6873.8 7251.7 7255.9 7345.7 7455.6 7712.0 7740.8 7795.5 7846.0 1917.5 1921.3 1926.3 1932.0 1943.8 2199.3 2210.5 2213.8 2221.4 2222.3 2186.2 2191.8 2193.0 2195.3 2213.1 2135.8 2136.6 2141.0 2147.1 2149.8 2081.5 2136.4 2147.3 2162.8 2182.1 7718.8 7877.2 7893.9 7895.1 7897.5 4540.2 4689.6 4698.6 4702.2 4730.4 2831.8 2891.5 3015.4 3017.2 3018.2 889.8 923.0 923.4 928.6 953.6 10409.6 10483.1 10544.8 10573.4 10643.8 4279.8 4296.6 4423.2 4445.8 4454.6 8937.0 8946.0 8954.6 9005.1 9024.6 4701.3 4785.5 4797.9 4819.9 4832.4 3697.5 4039.1 4210.3 4343.5 4360.4 3654.7 3705.1 3733.9 3796.5 3883.2 1198.3 1211.7 1213.6 1219.0 1225.4 817.6 883.8 890.5 900.9 904.4 5113.0 5269.8 5271.4 5344.5 5439.0 842.8 881.8 927.2 927.8 935.4 3520.4 3617.5 3706.1 3734.6 3795.8 9079.5 9512.9 9566.1 9566.7 9576.1 9224.9 9293.4 9407.2 9409.4 9439.4 7331.5 7344.9 7400.3 7431.0 7492.8 4924.3 5091.9 5110.3 5181.6 5227.0 8232.0 8896.9 8924.5 8969.1 8993.1 8283.8 8603.7 8682.6 8850.3 9017.0 5649.2 6016.7 6057.5 6743.6 6918.5 7075.8 7140.6 7155.4 7177.5 7205.3 6191.1 6449.9 6471.6 6484.5 6551.1 6258.7 6265.6 6280.2 6330.5 6388.4 5907.3 5964.3 5964.6 5965.4 5996.0 4323.8 4391.6 4430.1 4466.8 4514.9 4284.5 4441.7 4473.8 4484.8 4519.6 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#4] prototype.html Cycles:180 Average:4035.90 Median:4294.35 stddev:1169.95 (27.2%) stddev-sans-first:1139.21 05:17:23 INFO - PROCESS | 31491 | Values: 303.9 310.4 313.9 318.9 319.7 4736.3 4753.3 4789.4 4900.8 4912.6 4813.6 4838.6 4840.5 4879.1 4910.9 3782.0 3824.4 3836.2 3837.8 3838.6 3721.7 3800.1 3813.2 3820.7 3852.5 3756.1 3768.5 3770.3 3789.6 3790.4 3750.3 3758.6 3769.4 3780.1 3790.0 3348.4 3359.3 3365.6 3390.2 3406.5 4372.0 4627.3 4640.3 4659.9 4681.4 4234.1 4244.2 4245.9 4259.3 4266.1 4995.4 5005.6 5020.6 5031.2 5088.9 3620.8 3621.4 3626.0 3628.1 3630.5 5047.6 5250.5 5279.1 5286.9 5327.9 3619.9 3659.5 3662.9 3670.0 3682.3 3706.4 3713.9 3716.1 3732.0 3741.9 4119.0 4198.7 4234.8 4237.0 4255.8 5103.6 5116.0 5118.8 5135.9 5153.6 1202.3 1268.5 1288.3 1293.6 1294.4 802.8 814.8 831.3 837.7 838.7 3876.5 3884.2 3907.7 3922.5 3935.8 4448.4 4489.5 4503.5 4506.0 4527.7 3880.4 3922.9 3934.7 3952.3 3988.2 2354.7 2383.7 2414.4 2424.2 2425.5 4232.9 4276.3 4279.8 4286.4 4314.5 4483.4 4539.8 4607.2 4608.7 4631.5 3962.5 4156.6 4176.4 4269.0 4302.3 4479.6 4534.3 4540.8 4561.6 4586.2 4463.8 4476.8 4488.5 4491.3 4556.5 4223.0 4239.4 4249.0 4313.4 4489.5 3221.4 3643.9 3683.0 3709.4 3726.3 5065.8 5240.6 5247.7 5323.9 5329.6 4881.9 5106.2 5109.5 5110.5 5119.9 5106.9 5107.7 5108.9 5127.2 5128.7 4803.4 4965.4 4965.7 5015.1 5031.7 5019.7 5108.5 5121.3 5142.2 5157.9 5008.1 5019.4 5019.5 5035.6 5041.6 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - PROCESS | 31491 | [#5] yui.html Cycles:180 Average:12264.16 Median:26306.86 stddev:14063.58 (53.5%) stddev-sans-first:14076.37 05:17:23 INFO - PROCESS | 31491 | Values: 732.4 738.7 749.6 781.9 795.2 11860.1 11977.4 12390.5 12450.1 12506.0 12304.0 12320.4 12339.3 12355.8 12364.3 670.6 838.3 848.0 850.6 859.3 855.6 855.7 858.8 859.4 867.5 907.8 910.6 910.6 912.7 915.3 908.9 909.5 910.8 911.9 912.2 667.6 668.8 670.1 670.7 670.8 11587.2 11626.7 11653.0 11679.3 11695.1 12397.9 12565.2 12625.1 12650.9 12976.8 9428.0 9541.5 9640.0 9668.4 9917.4 4012.7 4045.7 4130.9 4147.1 4232.7 41996.6 42421.8 42424.2 42429.2 42713.0 9133.5 9183.8 9279.1 9298.3 9333.2 16254.3 16379.2 16470.0 16481.3 16512.4 6581.7 6650.7 6662.6 6735.5 6751.1 3465.1 3524.6 3576.0 3602.8 3605.2 2715.9 2796.2 2808.7 2823.2 2838.3 523.2 535.8 539.7 541.5 542.2 456.5 509.6 516.2 580.9 594.3 7082.1 7446.4 7514.9 7671.8 7937.1 477.0 575.2 580.3 592.6 597.3 3241.9 3393.5 3414.3 3420.1 3423.2 49611.3 50476.0 51883.7 52090.5 52555.5 44226.8 45582.5 46312.5 46718.0 47403.5 42549.1 43224.0 43326.4 43350.6 44607.2 6980.5 7211.9 7265.1 7289.8 7306.4 27894.4 28094.7 28348.9 29126.7 29291.4 34154.5 34924.5 37453.4 37945.4 38104.0 8703.4 8792.4 8823.6 8865.2 8944.0 10661.5 10741.6 10800.7 10830.6 10872.2 9642.5 10086.6 10312.0 10619.0 10779.4 9819.1 9907.9 9965.6 9980.5 10291.1 9788.9 9811.5 10144.2 10191.1 10365.9 8347.3 8405.9 8468.0 8662.5 8738.4 8477.3 8831.5 8833.8 8838.3 9031.1 05:17:23 INFO - PROCESS | 31491 | -------- Summary: end -------- 05:17:23 INFO - PROCESS | 31491 | 05:17:23 INFO - TEST-INFO | 31491: exit 0 05:17:23 INFO - TEST-OK | dromaeo_css | took 1114025ms 05:17:23 INFO - TEST-START | kraken 05:17:23 INFO - operating with platform_type : linux_ 05:17:23 INFO - Initialising browser for kraken test... 05:17:23 INFO - TEST-INFO | started process 31724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Lgisj/profile http://localhost:50674/getInfo.html) 05:17:27 INFO - PROCESS | 31724 | 05:17:27 INFO - PROCESS | 31724 | (process:31791): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:17:28 INFO - PROCESS | 31724 | __metrics Screen width/height:1600/1200 05:17:28 INFO - PROCESS | 31724 | colorDepth:24 05:17:28 INFO - PROCESS | 31724 | Browser inner width/height: 1024/697 05:17:28 INFO - PROCESS | 31724 | __metrics 05:17:29 ERROR - PROCESS | 31724 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:17:29 INFO - TEST-INFO | 31724: exit 0 05:17:29 INFO - Browser initialized. 05:17:29 INFO - Running cycle 1/1 for kraken test... 05:17:29 INFO - TEST-INFO | started process 31842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Lgisj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:31 INFO - PROCESS | 31842 | 05:17:31 INFO - PROCESS | 31842 | (process:31903): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:17:41 INFO - PROCESS | 31842 | RSS: Main: 164073472 05:17:41 INFO - PROCESS | 31842 | 05:17:44 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50674/tests/kraken/driver.html?testName=audio-beat-detection) 05:17:45 INFO - PROCESS | 31842 | RSS: Main: 168132608 05:17:45 INFO - PROCESS | 31842 | 05:17:47 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50674/tests/kraken/driver.html?testName=audio-dft) 05:17:48 INFO - PROCESS | 31842 | RSS: Main: 167936000 05:17:48 INFO - PROCESS | 31842 | 05:17:51 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50674/tests/kraken/driver.html?testName=audio-fft) 05:17:52 INFO - PROCESS | 31842 | RSS: Main: 163962880 05:17:52 INFO - PROCESS | 31842 | 05:17:54 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50674/tests/kraken/driver.html?testName=audio-oscillator) 05:17:54 INFO - PROCESS | 31842 | RSS: Main: 163098624 05:17:54 INFO - PROCESS | 31842 | 05:17:57 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50674/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:17:57 INFO - PROCESS | 31842 | RSS: Main: 163164160 05:17:57 INFO - PROCESS | 31842 | 05:18:01 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50674/tests/kraken/driver.html?testName=imaging-darkroom) 05:18:01 INFO - PROCESS | 31842 | RSS: Main: 163803136 05:18:01 INFO - PROCESS | 31842 | 05:18:05 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50674/tests/kraken/driver.html?testName=imaging-desaturate) 05:18:05 INFO - PROCESS | 31842 | RSS: Main: 162230272 05:18:05 INFO - PROCESS | 31842 | 05:18:08 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50674/tests/kraken/driver.html?testName=json-parse-financial) 05:18:09 INFO - PROCESS | 31842 | RSS: Main: 162816000 05:18:09 INFO - PROCESS | 31842 | 05:18:11 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50674/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:18:11 INFO - PROCESS | 31842 | RSS: Main: 163991552 05:18:11 INFO - PROCESS | 31842 | 05:18:13 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:18:14 INFO - PROCESS | 31842 | RSS: Main: 163155968 05:18:14 INFO - PROCESS | 31842 | 05:18:16 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:18:17 INFO - PROCESS | 31842 | RSS: Main: 163536896 05:18:17 INFO - PROCESS | 31842 | 05:18:20 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:18:20 INFO - PROCESS | 31842 | RSS: Main: 164642816 05:18:20 INFO - PROCESS | 31842 | 05:18:23 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:18:24 INFO - PROCESS | 31842 | RSS: Main: 163692544 05:18:24 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | Cycle 1(1): loaded http://localhost:50674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50674/tests/kraken/driver.html?testName=ai-astar) 05:18:26 INFO - PROCESS | 31842 | RSS: Main: 165683200 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | __start_tp_report 05:18:26 INFO - PROCESS | 31842 | _x_x_mozilla_page_load 05:18:26 INFO - PROCESS | 31842 | _x_x_mozilla_page_load_details 05:18:26 INFO - PROCESS | 31842 | |i|pagename|runs| 05:18:26 INFO - PROCESS | 31842 | |0;ai-astar;113;118;110;118;119;116;111;116;114;114 05:18:26 INFO - PROCESS | 31842 | |1;audio-beat-detection;168;136;156;217;137;157;158;139;182;143 05:18:26 INFO - PROCESS | 31842 | |2;audio-dft;167;166;163;167;170;172;164;167;166;164 05:18:26 INFO - PROCESS | 31842 | |3;audio-fft;78;80;78;158;78;79;77;80;158;79 05:18:26 INFO - PROCESS | 31842 | |4;audio-oscillator;96;95;169;95;95;94;133;94;95;138 05:18:26 INFO - PROCESS | 31842 | |5;imaging-gaussian-blur;120;121;119;120;120;119;119;119;120;120 05:18:26 INFO - PROCESS | 31842 | |6;imaging-darkroom;147;151;148;149;150;149;148;150;150;148 05:18:26 INFO - PROCESS | 31842 | |7;imaging-desaturate;96;96;96;96;97;97;99;97;97;97 05:18:26 INFO - PROCESS | 31842 | |8;json-parse-financial;69;68;71;70;70;69;68;70;70;69 05:18:26 INFO - PROCESS | 31842 | |9;json-stringify-tinderbox;65;65;65;66;66;65;65;65;65;65 05:18:26 INFO - PROCESS | 31842 | |10;stanford-crypto-aes;86;87;86;87;88;92;87;93;151;87 05:18:26 INFO - PROCESS | 31842 | |11;stanford-crypto-ccm;141;143;142;145;144;154;153;154;166;148 05:18:26 INFO - PROCESS | 31842 | |12;stanford-crypto-pbkdf2;184;181;187;184;184;183;186;181;182;188 05:18:26 INFO - PROCESS | 31842 | |13;stanford-crypto-sha256-iterative;73;73;73;71;70;75;71;70;70;71 05:18:26 INFO - PROCESS | 31842 | __end_tp_report 05:18:26 INFO - PROCESS | 31842 | __start_cc_report 05:18:26 INFO - PROCESS | 31842 | _x_x_mozilla_cycle_collect,816 05:18:26 INFO - PROCESS | 31842 | __end_cc_report 05:18:26 INFO - PROCESS | 31842 | __startTimestamp1462882706486__endTimestamp 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | ------- Summary: start ------- 05:18:26 INFO - PROCESS | 31842 | Number of tests: 14 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#0] ai-astar Cycles:10 Average:114.90 Median:116.00 stddev:3.03 (2.6%) stddev-sans-first:3.14 05:18:26 INFO - PROCESS | 31842 | Values: 113.0 118.0 110.0 118.0 119.0 116.0 111.0 116.0 114.0 114.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#1] audio-beat-detection Cycles:10 Average:159.30 Median:157.50 stddev:25.07 (15.9%) stddev-sans-first:26.39 05:18:26 INFO - PROCESS | 31842 | Values: 168.0 136.0 156.0 217.0 137.0 157.0 158.0 139.0 182.0 143.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#2] audio-dft Cycles:10 Average:166.60 Median:167.00 stddev:2.76 (1.7%) stddev-sans-first:2.92 05:18:26 INFO - PROCESS | 31842 | Values: 167.0 166.0 163.0 167.0 170.0 172.0 164.0 167.0 166.0 164.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#3] audio-fft Cycles:10 Average:94.50 Median:78.50 stddev:33.48 (42.7%) stddev-sans-first:34.97 05:18:26 INFO - PROCESS | 31842 | Values: 78.0 80.0 78.0 158.0 78.0 79.0 77.0 80.0 158.0 79.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:95.00 stddev:26.67 (28.1%) stddev-sans-first:27.77 05:18:26 INFO - PROCESS | 31842 | Values: 96.0 95.0 169.0 95.0 95.0 94.0 133.0 94.0 95.0 138.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#5] imaging-gaussian-blur Cycles:10 Average:119.70 Median:120.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:18:26 INFO - PROCESS | 31842 | Values: 120.0 121.0 119.0 120.0 120.0 119.0 119.0 119.0 120.0 120.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.50 stddev:1.25 (0.8%) stddev-sans-first:1.09 05:18:26 INFO - PROCESS | 31842 | Values: 147.0 151.0 148.0 149.0 150.0 149.0 148.0 150.0 150.0 148.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.92 (0.9%) stddev-sans-first:0.93 05:18:26 INFO - PROCESS | 31842 | Values: 96.0 96.0 96.0 96.0 97.0 97.0 99.0 97.0 97.0 97.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 05:18:26 INFO - PROCESS | 31842 | Values: 69.0 68.0 71.0 70.0 70.0 69.0 68.0 70.0 70.0 69.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 05:18:26 INFO - PROCESS | 31842 | Values: 65.0 65.0 65.0 66.0 66.0 65.0 65.0 65.0 65.0 65.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:87.00 stddev:20.03 (23.0%) stddev-sans-first:21.02 05:18:26 INFO - PROCESS | 31842 | Values: 86.0 87.0 86.0 87.0 88.0 92.0 87.0 93.0 151.0 87.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:150.50 stddev:7.79 (5.2%) stddev-sans-first:7.70 05:18:26 INFO - PROCESS | 31842 | Values: 141.0 143.0 142.0 145.0 144.0 154.0 153.0 154.0 166.0 148.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:184.00 stddev:2.40 (1.3%) stddev-sans-first:2.55 05:18:26 INFO - PROCESS | 31842 | Values: 184.0 181.0 187.0 184.0 184.0 183.0 186.0 181.0 182.0 188.0 05:18:26 INFO - PROCESS | 31842 | 05:18:26 INFO - PROCESS | 31842 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:1.70 (2.4%) stddev-sans-first:1.74 05:18:26 INFO - PROCESS | 31842 | Values: 73.0 73.0 73.0 71.0 70.0 75.0 71.0 70.0 70.0 71.0 05:18:26 INFO - PROCESS | 31842 | -------- Summary: end -------- 05:18:26 INFO - PROCESS | 31842 | 05:18:27 INFO - TEST-INFO | 31842: exit 0 05:18:27 INFO - TEST-OK | kraken | took 63499ms 05:18:27 INFO - SUITE-END | took 1177s 05:18:27 INFO - Completed test suite (00:19:37) 05:18:27 INFO - javascript benchmark 05:18:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2600.4929038837427, 2611.216635009499, 2636.538605743995, 2649.973500264997, 2671.025075847316, 4622.422197225347, 4655.3482512448245, 4682.6019788318035, 4711.222648263032, 4714.222153344698, 4627.467841198263, 4650.209464391053, 4663.207254766689, 4676.205045142326, 4706.058788242357, 4243.257429949762, 4320.351947207912, 4376.146651402978, 4377.562243775613, 4398.582134697199, 4345.087531618364, 4376.146651402978, 4376.277914144162, 4421.88945276369, 4443.066955939256, 4130.586941305875, 4261.083866968585, 4264.97640566265, 4295.4415925929425, 4322.243607368698, 4300.806463709109, 4348.456442944632, 4382.189294980439, 4404.273294906337, 4404.647628189737, 4293.742375457483, 4295.806688699016, 4338.869833904988, 4364.803583838735, 4400.251957167289, 4565.908681826378, 4622.86131416058, 4639.327297541838, 4660.627149828039, 4720.1031803957485, 5006.098902197603, 5050.141475949061, 5067.163917953533, 5077.009983053306, 5130.7691153897795, 5005.174146265862, 5161.3806343238675, 5172.094883395391, 5200.167973124319, 5274.103402421598, 4789.784459699322, 4825.372701548777, 4853.393325834236, 4866.78099485524, 4869.537393947569, 5267.394249661338, 5273.077211488051, 5279.366476022902, 5300.992811365828, 5323.467653234723, 4844.2007068833955, 4970.17992031318, 4985.401751789808, 5011.6241281904, 5092.28707980883, 5018.422881368616, 5031.547160755549, 5077.822276220315, 5095.108356037753, 5110.872228194325, 5054.393472783289, 5081.0854046272025, 5084.542391184811, 5095.8980820383385, 5110.977804439052, 4949.925751113737, 5033.144365457881, 5045.041442125985, 5060.342155519759, 5112.309838171799, 4359.956400436018, 4372.147431250875, 4403.625691816167, 4421.469423669116, 4432.091421258634, 3759.2105657811617, 3763.7741735495956, 3804.1250512382308, 3842.9039274018373, 3856.8650097246464, 4323.870283891489, 4325.264705000094, 4369.934450983238, 4416.447944006999, 4422.248217802981, 5044.218146187347, 5089.796408143633, 5106.387233531926, 5114.207297868837, 5135.948640513622, 4373.343998400265, 4381.868543943687, 4382.890427739332, 4434.600885920283, 4456.598906098467, 4482.843100491467, 4507.887302817456, 4533.274676051882, 4567.657425693086, 4580.702254353457, 4688.601468875184, 4694.34279200919, 4713.057388522309, 4715.363425937455, 4770.642201834807, 5002.924956125734, 5016.54851063406, 5023.070731914608, 5043.722595257296, 5078.0, 4677.836275730333, 4775.737334446639, 4778.116048531033, 4807.6394270429155, 4815.181419158681, 5058.215976523645, 5075.28945947575, 5122.410922743932, 5126.53861152498, 5126.79492820283, 4944.629152813482, 5155.304033955368, 5171.45699701541, 5183.481651834786, 5199.376074871039, 4778.283257511401, 4796.25658023007, 4852.563269305639, 4896.877578060577, 4924.606031517542, 4903.901921961612, 4922.975385123052, 4945.406551213733, 4948.505149485023, 4975.353204083591, 4796.664233503621, 4827.058723548875, 4877.95122048775, 4886.998165376018, 4946.258061290835, 4908.263760435963, 4931.48219436954, 4941.085899108532, 4968.478309777566, 4988.900221995613, 4907.950920490749, 4917.655764096622, 4931.964287499665, 4952.702837829742, 4969.254611808257], "value": 4657.283359138263, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.0716425062897, 577.4946921443736, 587.1369087441461, 589.1986897818969, 596.0373995996467, 36565.451518227244, 40473.0, 40671.59328406715, 40700.389494157615, 40899.0, 40145.0, 40478.797606012005, 40496.39255411171, 40556.59443405565, 40687.18625627486, 7704.576248306327, 7846.843063138734, 7847.254510821463, 7885.921140788605, 7896.131425543185, 7746.845063098763, 7757.263060009318, 7847.0, 7858.057033156029, 7864.5674487903425, 7634.198409167041, 7648.311651951323, 7650.655720492591, 7675.0, 7712.498687585289, 7778.144404115543, 7793.103793063785, 7799.1031031430975, 7821.335186509125, 7834.373250139975, 7712.421568382394, 7741.109772376192, 7766.18455062216, 7821.726239581587, 7828.686852525892, 37605.43591846125, 37967.0, 38004.80997595022, 38419.80790096032, 38574.22851542953, 3012.487877060883, 3340.4600449429054, 3349.112485191426, 3498.7201023918024, 3568.571771387424, 29331.413371732448, 29643.70356296431, 29975.850120749474, 30085.247868803235, 30205.0, 3148.4332820092372, 3179.4753865612147, 3248.902532924016, 3274.279658475132, 3282.376348493802, 244697.0, 245613.0, 246374.0, 247740.0, 248574.0, 27700.861495692392, 27715.307117321823, 28466.715332846405, 42939.57060429418, 43682.563174367846, 35132.824335878155, 35635.643643563235, 35664.46503302453, 35710.28579428397, 35818.46272305917, 13134.146280491737, 13192.538261161006, 13211.33943302831, 13254.801177982337, 13260.867391325963, 11735.647930562096, 11820.940895295469, 11827.467763950643, 11846.703832404259, 11877.881221187678, 8216.055153657286, 8224.671013159406, 8241.0, 8275.634520304082, 8290.129536398592, 3018.4868572342752, 3021.425929073513, 3041.391721655678, 3041.5437684347526, 3080.430120427728, 964.9421034737938, 978.4862946952765, 1061.601899287767, 1066.4134725900724, 1117.1732917640886, 27434.725652743615, 28427.0, 28692.856535717605, 29201.415971680446, 29327.413451731274, 1023.242800327767, 1058.4231593781408, 1090.5419723716059, 1092.7432053467428, 1102.2725001498973, 13622.250776207404, 13714.657133571542, 13912.0, 13935.721285574435, 14134.434622615187, 358313.0, 359297.0, 359545.8090838169, 359772.0, 362449.0, 51093.23360149601, 51619.22571161436, 51686.74156629193, 51806.0, 52233.0, 1260.7415479826614, 1269.6571925580226, 1297.4291311822767, 1307.3136603283353, 1319.0173320875817, 26792.33019174536, 27244.863775680995, 27375.589366159922, 27422.862885685445, 27787.16638500848, 42155.0, 42417.36373954331, 42621.147577048905, 42681.573184269, 42741.35887961745, 311667.0, 313076.0, 313583.0, 314611.0, 315064.0, 25429.491410172064, 25645.230643080737, 25652.486950260518, 25854.353641159123, 25887.741122589287, 35390.46914296236, 35932.10169745777, 36116.09709757277, 36121.0, 36240.0, 37172.628273718, 37219.81390093032, 37236.441453378764, 37541.81229093837, 37781.81109094437, 36176.63823361733, 36450.45324320084, 36499.635003650685, 36557.817210913774, 36889.26221475502, 36745.448818267214, 36787.816060919526, 36866.8156659215, 36992.07519812026, 37198.81235036561, 32801.179970500925, 33330.5000424989, 33411.0, 33593.16017099592, 33674.326513470085, 25216.243512694648, 25221.873890630428, 25262.368440788392, 25321.62017569701, 25549.48901021932], "value": 16439.035060600563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.71334429161686, 378.70839453620715, 384.41184986969927, 387.1849756263066, 393.52580140930183, 14895.10629362239, 16204.432844850433, 17157.0, 17207.827921720796, 17251.396201132964, 10540.736481587965, 11085.168612354086, 11091.944540277307, 11424.71438214043, 11439.370834604093, 4678.181318269306, 4811.35046768687, 4831.782569784351, 4838.758062096881, 4867.975660121694, 4678.345031695549, 4805.20714082176, 4808.447028591721, 4821.915069109457, 4836.9516304837025, 4820.879478013043, 4838.975805120969, 4846.9030619387595, 5278.5249327560705, 5280.102382594968, 5122.948770512303, 5244.685318880879, 5266.7366631668265, 5272.156454967187, 5298.523132918037, 4555.06621142665, 4689.780657029197, 4723.315119307716, 4751.239801631722, 4795.544423279783, 12718.173318734345, 12764.0, 12768.361581920866, 12991.22052676833, 16752.41366552177, 2025.8987050647409, 2056.249468943825, 2208.950748394516, 2403.411164264749, 2455.5579995600783, 8813.162749538784, 9235.676751313738, 9725.124738773544, 11598.652040438801, 11828.763424731544, 1721.5610019445087, 1730.8874923130081, 1735.9045252511107, 1747.580580660645, 1834.4404956488265, 64742.0, 67286.65426691438, 67286.66356668185, 69447.30552694459, 70038.0, 10466.738331541772, 10511.0, 10514.264001519974, 10579.471026448648, 10674.573017079385, 14189.219592922282, 14476.34856431463, 14576.489822856149, 14638.268086595628, 14675.0, 7330.157031941395, 7433.145188303306, 7447.88828167578, 7465.104187497426, 7475.663595138232, 7395.889061664079, 7421.0723659542555, 7447.1063472382575, 7475.401967842559, 7475.775726728207, 4452.510223875371, 4509.210888094571, 4527.501974782771, 4609.1703493371515, 4676.18166820805, 1850.7408962745226, 1885.5003424092627, 1931.8840869547591, 1945.5233467800479, 1994.6110508451004, 812.2293400387213, 926.0924294191595, 978.3885071830106, 1002.7092143278368, 1030.2081359515646, 16267.837321626868, 16633.83366166347, 16682.165891705365, 16743.995360278423, 16756.16219189036, 917.2249449215402, 930.4138392812484, 943.990560094404, 1031.0256807317014, 1042.7028296935337, 7839.149894833392, 9473.905260947302, 9480.099390557885, 9504.809903801885, 9528.809423811623, 381626.0, 384356.0, 388153.0, 390300.0, 393239.0, 42666.0, 43377.783111084245, 43391.349129763075, 57428.0, 71198.2880171202, 977.9902200978041, 978.3982850546862, 980.6126580001003, 999.6501224571342, 1002.2733518199393, 14392.496262630757, 14547.563573092824, 14677.48628797987, 14683.632909177357, 14723.411063557765, 24310.0, 31121.53317700191, 33090.33819323552, 34678.82660586681, 35832.64167358293, 48359.5164048355, 48719.26921096115, 49105.0, 49404.25893611527, 49437.25844112269, 7746.405371689406, 7817.179196184319, 7927.405444591794, 7977.28204461578, 8045.436819422584, 10646.787064258826, 10970.527239371231, 11057.612983545538, 11182.273152245078, 11258.09935205198, 9094.499802510662, 9566.617335306788, 9582.616695332388, 9816.16562592159, 10325.535350909377, 9351.906480935375, 9419.764505887408, 9474.336796424184, 9996.100350968447, 10222.897771022492, 9873.111419972238, 9941.204703623549, 10001.249906257206, 10070.942551032036, 10135.885052644056, 7619.784076376272, 7677.347425468898, 7758.069031716004, 7832.295093441618, 7900.486468379537, 8008.638531449551, 9577.712668619952, 9922.751931201778, 10006.09945104944, 10187.388756674623], "value": 7905.445450200236, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.5471807508338, 1384.639993601664, 1394.3725323604378, 1728.4900954218513, 1753.8070812210647, 6627.271000189975, 6873.79378618641, 7251.70993160273, 7255.927440725598, 7345.66944487498, 7455.627218639073, 7711.961440192791, 7740.845183096335, 7795.53226806392, 7846.0, 1917.5206198450421, 1921.289123024483, 1926.306529649325, 1931.9613607727908, 1943.7861835198116, 2199.274239500961, 2210.53578748463, 2213.7896899794496, 2221.3669104305277, 2222.3221917315213, 2186.180182431588, 2191.824654027682, 2193.0021840062814, 2195.2865318771383, 2213.092632020872, 2135.765065842756, 2136.6154092263387, 2140.9723332800263, 2147.076756994492, 2149.752778430477, 2081.458820706612, 2136.369770917583, 2147.3128598848375, 2162.7729088445803, 2182.0726191368763, 7718.807029824299, 7877.172896845806, 7893.881591776128, 7895.092064412609, 7897.526148431112, 4540.205464043779, 4689.554492323258, 4698.647601429906, 4702.177119004161, 4730.408698912636, 2831.787615928793, 2891.5373540233672, 3015.381846721417, 3017.230606195406, 3018.170003249102, 889.8487257166297, 923.0169869089399, 923.3628796130648, 928.5960607135918, 953.609020301673, 10409.583616655325, 10483.10893574047, 10544.789104217949, 10573.418461984587, 10643.787124257471, 4279.786010699452, 4296.591823776736, 4423.181711383404, 4445.799939002753, 4454.643628509712, 8936.955315223382, 8946.015938246788, 8954.597043133075, 9005.14451127142, 9024.593893274818, 4701.294805779161, 4785.545373189541, 4797.944452220506, 4819.903601927942, 4832.44426890905, 3697.5378077740284, 4039.0912044790275, 4210.263203939298, 4343.5439278875265, 4360.4331436913635, 3654.7258955578436, 3705.1107734144152, 3733.9253214936093, 3796.544414670202, 3883.1845312484693, 1198.269055875915, 1211.6970757310673, 1213.5934461955344, 1219.0491416694992, 1225.3628113380992, 817.5666896544722, 883.7702197428715, 890.5413711938357, 900.8963969143632, 904.421170450912, 5113.0, 5269.789208431696, 5271.393789714155, 5344.465553444625, 5439.020976224318, 842.8314337132598, 881.7971866470748, 927.1840780113457, 927.8422668146296, 935.4293880732747, 3520.419130843382, 3617.529721136288, 3706.129059670975, 3734.6452087051684, 3795.772253664789, 9079.546022698838, 9512.904870951203, 9566.08678264336, 9566.66516671913, 9576.138147566753, 9224.907750922539, 9293.39592926471, 9407.247420206368, 9409.388389754644, 9439.433633981984, 7331.486795924234, 7344.889826652605, 7400.259974002664, 7431.0, 7492.812679682943, 4924.2613607957655, 5091.87270318245, 5110.259012443249, 5181.5595674368105, 5227.0, 8232.0, 8896.911030889609, 8924.464532128093, 8969.058248884034, 8993.055729148346, 8283.83432331362, 8603.741887743377, 8682.565871706516, 8850.33622478304, 9016.954915225644, 5649.237352957298, 6016.7292471838, 6057.4851137653795, 6743.629100399525, 6918.481113916577, 7075.752348667772, 7140.571565706075, 7155.42756579462, 7177.533460325062, 7205.315495027964, 6191.133241346125, 6449.935500644933, 6471.611703297817, 6484.5460817742305, 6551.050097735706, 6258.655773932477, 6265.592736472115, 6280.214973128359, 6330.461910737639, 6388.361163883575, 5907.261592300963, 5964.343922168645, 5964.612300200473, 5965.373635768182, 5995.970020149872, 4323.762193079411, 4391.626711729411, 4430.091831174537, 4466.821327146878, 4514.909701806011, 4284.485861696617, 4441.733495990251, 4473.843415480443, 4484.775761211991, 4519.593236608721], "value": 4096.484774679691, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.8936372269706, 310.4210647143079, 313.9089663997441, 318.9362127574484, 319.74260720120293, 4736.3369128322065, 4753.33453316536, 4789.425268967724, 4900.754962251886, 4912.582430493412, 4813.638977076725, 4838.564529192372, 4840.515948405157, 4879.146149423855, 4910.9263361049525, 3781.9977705907954, 3824.445455408964, 3836.175222327199, 3837.827297771607, 3838.5777564467885, 3721.6650501454865, 3800.0689830991446, 3813.1801662642524, 3820.6561409473143, 3852.5184351956004, 3756.079760458678, 3768.5477742670782, 3770.26479836432, 3789.5831458539537, 3790.4314352847155, 3750.3372605450163, 3758.624137586247, 3769.3968964965748, 3780.1494663700587, 3789.962100379002, 3348.363810875926, 3359.2945481448924, 3365.562476878015, 3390.186355274741, 3406.5230867678547, 4371.9725864421835, 4627.259638457831, 4640.303954406832, 4659.906801863944, 4681.414823147106, 4234.110836724291, 4244.236037513247, 4245.872623821259, 4259.254630439661, 4266.104118135165, 4995.426440671177, 5005.57452616528, 5020.590116743947, 5031.195008798574, 5088.938979713222, 3620.7827530348263, 3621.384364657988, 3625.981870090659, 3628.11111277736, 3630.455431685242, 5047.596192304607, 5250.501202385767, 5279.128943724281, 5286.920696189561, 5327.920081198785, 3619.8778378702686, 3659.5059666945153, 3662.8901132966053, 3670.0274427276795, 3682.263547290499, 3706.3513885070006, 3713.925721485555, 3716.1267102230954, 3731.962680373216, 3741.8587330864348, 4119.0, 4198.706090573692, 4234.767087810138, 4236.978815105905, 4255.808373655382, 5103.642745007888, 5115.974420127875, 5118.820841270538, 5135.87160320995, 5153.639245252796, 1202.2666173634077, 1268.5052829396543, 1288.2592509307185, 1293.5925183567147, 1294.3916359311108, 802.7917983434893, 814.8411059843393, 831.301706566487, 837.677494164751, 838.7316058861228, 3876.457295978565, 3884.1649045455365, 3907.7459965102175, 3922.490076290064, 3935.8032098394965, 4448.399466072039, 4489.483709373399, 4503.4555476155865, 4506.0, 4527.728336299832, 3880.4373365861793, 3922.882313530599, 3934.6852251819787, 3952.3281042222893, 3988.202359528106, 2354.705661792276, 2383.690120284352, 2414.4205390706106, 2424.1878970544713, 2425.50277193175, 4232.873013809591, 4276.273033584298, 4279.828806847691, 4286.357046443059, 4314.50383205929, 4483.417155769729, 4539.818407263739, 4607.193741095362, 4608.700434471749, 4631.51369106239, 3962.465067215889, 4156.56356082613, 4176.43618111551, 4268.978655106766, 4302.333138363559, 4479.619232365286, 4534.274516077412, 4540.818367265272, 4561.608709343682, 4586.243269860569, 4463.754493502888, 4476.798544065459, 4488.5287044859815, 4491.3487544306545, 4556.498785133566, 4223.0, 4239.385289132997, 4248.957510424856, 4313.352997050468, 4489.461264648263, 3221.355728854285, 3643.9089022773583, 3683.0, 3709.3508635989133, 3726.2733766916276, 5065.848024559269, 5240.606954478353, 5247.711375874211, 5323.908598737172, 5329.57363410934, 4881.925976285212, 5106.157484015096, 5109.467159852104, 5110.514501122388, 5119.897602048012, 5106.872328191825, 5107.744612769272, 5108.948910510847, 5127.230915362726, 5128.666636668605, 4803.447603525499, 4965.4041515018425, 4965.726885021359, 5015.122353588181, 5031.7169121873685, 5019.7490125494305, 5108.540231379194, 5121.334226550524, 5142.202958541432, 5157.871053223699, 5008.123578373843, 5019.422766381913, 5019.472955339783, 5035.571976382049, 5041.6470847040355], "value": 3672.453478307637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.4067505320687, 738.6602163005017, 749.6064566102796, 781.9335356494697, 795.2326005404783, 11860.051195904329, 11977.4011299435, 12390.504379824797, 12450.066245031614, 12506.0, 12304.0, 12320.4455799489, 12339.259644421341, 12355.814662780065, 12364.319962402064, 670.6244503078267, 838.3000194837302, 847.9576021198916, 850.6172222499869, 859.3468963587687, 855.6021450025737, 855.7304449098546, 858.8153546987396, 859.4413631139747, 867.5011868175793, 907.8411278026352, 910.5538286239761, 910.6357457017212, 912.739869137296, 915.3363811236867, 908.9454632722058, 909.5225006871412, 910.7632015675906, 911.8768966189579, 912.1881525442361, 667.5627464011031, 668.8428219368444, 670.0786418674322, 670.6914819183132, 670.7518218259227, 11587.188896777228, 11626.709332266677, 11653.009494193004, 11679.299242045505, 11695.064394848478, 12397.938010309981, 12565.18326308787, 12625.116241863074, 12650.936745316307, 12976.805347919788, 9428.0, 9541.475218862959, 9639.951800241024, 9668.371555848847, 9917.355371900803, 4012.7391719538427, 4045.6763458923215, 4130.855420060284, 4147.129102888397, 4232.682548808852, 41996.58003419957, 42421.78789106035, 42424.151516969796, 42429.1514169718, 42712.99999999969, 9133.49765762876, 9183.77040573991, 9279.118483744034, 9298.256139508823, 9333.206677432494, 16254.349826007065, 16379.181040947906, 16470.0, 16481.25834337458, 16512.431319024672, 6581.670916454158, 6650.733970641217, 6662.600243985376, 6735.461163106939, 6751.0886030386255, 3465.1163953191776, 3524.6122926478065, 3575.9987203583037, 3602.819859007039, 3605.1708107135505, 2715.877785499618, 2796.2170592234206, 2808.7331703488135, 2823.223613506302, 2838.318803487148, 523.2386877093819, 535.8499620106377, 539.7436217796516, 541.5423966748092, 542.2110828744168, 456.45681638849663, 509.571959553969, 516.1921592707475, 580.9099589563624, 594.2779522879636, 7082.114735658043, 7446.404287657083, 7514.887276690854, 7671.808204794925, 7937.087234968052, 476.9642276829252, 575.1746244139673, 580.2717589425245, 592.6236839606795, 597.2683462758173, 3241.9351612967607, 3393.50794134854, 3414.3342048300838, 3420.1449637590604, 3423.2297733009873, 49611.25583116256, 50476.0, 51883.74058129685, 52090.47909520932, 52555.47444525582, 44226.778866105466, 45582.54417455849, 46312.53687463082, 46718.0, 47403.5259647406, 42549.14901701949, 43224.0, 43326.35010474845, 43350.56649433528, 44607.21571137118, 6980.546264492792, 7211.891821622785, 7265.12818461788, 7289.781306560812, 7306.378957788647, 27894.44211115807, 28094.71905280921, 28348.85825570859, 29126.70873291325, 29291.41417171687, 34154.48768268428, 34924.47613285854, 37453.4381984265, 37945.43081853719, 38104.0, 8703.43427677199, 8792.384533082879, 8823.602937867683, 8865.246454051477, 8944.0, 10661.466926653482, 10741.570337186426, 10800.729981750519, 10830.566777329133, 10872.238943274211, 9642.469664168257, 10086.646967356108, 10312.0, 10619.0, 10779.353238805697, 9819.116279534876, 9907.900920990698, 9965.551550180404, 9980.451075190935, 10291.125254353465, 9788.9021109788, 9811.460369679737, 10144.239182061227, 10191.133753631024, 10365.9481702591, 8347.300207389584, 8405.873911891204, 8468.0, 8662.523561203941, 8738.388312818295, 8477.321814254721, 8831.470111793313, 8833.779155521164, 8838.337124715543, 9031.142041506046], "value": 5775.726491911401, "unit": "score"}], "name": "dromaeo_css", "value": 6121.055605590012, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 110.0, 118.0, 119.0, 116.0, 111.0, 116.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 136.0, 156.0, 217.0, 137.0, 157.0, 158.0, 139.0, 182.0, 143.0], "value": 159.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 163.0, 167.0, 170.0, 172.0, 164.0, 167.0, 166.0, 164.0], "value": 166.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 78.0, 158.0, 78.0, 79.0, 77.0, 80.0, 158.0, 79.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 169.0, 95.0, 95.0, 94.0, 133.0, 94.0, 95.0, 138.0], "value": 110.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 119.0, 120.0, 120.0, 119.0, 119.0, 119.0, 120.0, 120.0], "value": 119.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 151.0, 148.0, 149.0, 150.0, 149.0, 148.0, 150.0, 150.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 99.0, 97.0, 97.0, 97.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 70.0, 70.0, 69.0, 68.0, 70.0, 70.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 87.0, 88.0, 92.0, 87.0, 93.0, 151.0, 87.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 142.0, 145.0, 144.0, 154.0, 153.0, 154.0, 166.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 187.0, 184.0, 184.0, 183.0, 186.0, 181.0, 182.0, 188.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 71.0, 70.0, 75.0, 71.0, 70.0, 70.0, 71.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1644.9000000000003, "alertThreshold": 2.0}]} 05:18:27 INFO - Return code: 0 05:18:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:18:27 ERROR - # TBPL SUCCESS # 05:18:27 INFO - Running post-action listener: _resource_record_post_action 05:18:27 INFO - ##### 05:18:27 INFO - ##### Finished run-tests step (success) 05:18:27 INFO - ##### 05:18:27 INFO - Running post-run listener: _resource_record_post_run 05:18:28 INFO - Total resource usage - Wall time: 1195s; CPU: 26.0%; Read bytes: 0; Write bytes: 534536192; Read time: 0; Write time: 717268 05:18:28 INFO - install - Wall time: 16s; CPU: 27.0%; Read bytes: 0; Write bytes: 246145024; Read time: 0; Write time: 364784 05:18:28 INFO - run-tests - Wall time: 1179s; CPU: 26.0%; Read bytes: 0; Write bytes: 288391168; Read time: 0; Write time: 352484 05:18:28 INFO - Running post-run listener: _upload_blobber_files 05:18:28 INFO - Blob upload gear active. 05:18:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:18:28 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 05:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:18:29 INFO - (blobuploader) - INFO - Open directory for files ... 05:18:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:18:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:30 INFO - (blobuploader) - INFO - Done attempting. 05:18:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:18:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:18:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:31 INFO - (blobuploader) - INFO - Done attempting. 05:18:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:18:31 INFO - Return code: 0 05:18:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:18:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:18:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/994ff68e79bd3c842ed27044a8da730d6ee0e8aa025ce0c62b9c83c78b8e386280850d4cc95ed028a720685f556e3750d643b129dd6292a1e5a33f84f7cdd041", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/adabe5a9908df296fbe2f398b8da6056c36d5de20ff914f7fbb45a36bbf162df4abaae7e43259234821d1ed57d5778b363e455d5850159a308028591ff2b8bbe"} 05:18:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:18:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:18:31 INFO - Contents: 05:18:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/994ff68e79bd3c842ed27044a8da730d6ee0e8aa025ce0c62b9c83c78b8e386280850d4cc95ed028a720685f556e3750d643b129dd6292a1e5a33f84f7cdd041", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/adabe5a9908df296fbe2f398b8da6056c36d5de20ff914f7fbb45a36bbf162df4abaae7e43259234821d1ed57d5778b363e455d5850159a308028591ff2b8bbe"} 05:18:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:18:31 INFO - Copying logs to upload dir... 05:18:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:18:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.366595 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-10 05:18:30.699343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:18:30.702954) ========= 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=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 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/Fx-Team-Non-PGO/sha512/994ff68e79bd3c842ed27044a8da730d6ee0e8aa025ce0c62b9c83c78b8e386280850d4cc95ed028a720685f556e3750d643b129dd6292a1e5a33f84f7cdd041", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/adabe5a9908df296fbe2f398b8da6056c36d5de20ff914f7fbb45a36bbf162df4abaae7e43259234821d1ed57d5778b363e455d5850159a308028591ff2b8bbe"} build_url:https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005325 build_url: 'https://queue.taskcluster.net/v1/task/K2CToNDwTTutH_93JVw51g/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/Fx-Team-Non-PGO/sha512/994ff68e79bd3c842ed27044a8da730d6ee0e8aa025ce0c62b9c83c78b8e386280850d4cc95ed028a720685f556e3750d643b129dd6292a1e5a33f84f7cdd041", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/adabe5a9908df296fbe2f398b8da6056c36d5de20ff914f7fbb45a36bbf162df4abaae7e43259234821d1ed57d5778b363e455d5850159a308028591ff2b8bbe"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:18:30.717990) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:18:30.718394) ========= 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=c6927cf182745ad25b1d38b9000001fe-1462881475.231366-1837026762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:18:30.761949) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:18:30.762263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:18:30.762575) ========= ========= Total master_lag: 0.13 =========