builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1462611512.7 results: success (0) buildid: 20160507012950 builduid: c92d4849b1c843228803373bdd43ee44 revision: 44a989cf6c1662b60e3113cb6503817b7fc31131 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.698971) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.700991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.701284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.721233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.721610) ========= 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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-07 01:58:33-- 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% 42.7M=0s 2016-05-07 01:58:33 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.849677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.850037) ========= 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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017712 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.909615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:32.909992) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 44a989cf6c1662b60e3113cb6503817b7fc31131 --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 44a989cf6c1662b60e3113cb6503817b7fc31131 --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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-07 01:58:33,482 truncating revision to first 12 chars 2016-05-07 01:58:33,482 Setting DEBUG logging. 2016-05-07 01:58:33,482 attempt 1/10 2016-05-07 01:58:33,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/44a989cf6c16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-07 01:58:33,752 unpacking tar archive at: fx-team-44a989cf6c16/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531399 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:33.464048) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:33.464397) ========= script_repo_revision: 44a989cf6c1662b60e3113cb6503817b7fc31131 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:33.464835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:33.465109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 01:58:33.474039) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-07 01:58:33.474314) ========= /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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:58:34 INFO - MultiFileLogger online at 20160507 01:58:34 in /builds/slave/test 01:58:34 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 01:58:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:58:34 INFO - {'append_to_log': False, 01:58:34 INFO - 'base_work_dir': '/builds/slave/test', 01:58:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:58:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:58:34 INFO - 'branch': 'Fx-Team-Non-PGO', 01:58:34 INFO - 'buildbot_json_path': 'buildprops.json', 01:58:34 INFO - 'config_files': ('talos/linux_config.py',), 01:58:34 INFO - 'default_actions': ('clobber', 01:58:34 INFO - 'read-buildbot-config', 01:58:34 INFO - 'download-and-extract', 01:58:34 INFO - 'populate-webroot', 01:58:34 INFO - 'create-virtualenv', 01:58:34 INFO - 'install', 01:58:34 INFO - 'run-tests'), 01:58:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:58:34 INFO - 'download_minidump_stackwalk': True, 01:58:34 INFO - 'download_symbols': 'ondemand', 01:58:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:58:34 INFO - 'tooltool.py': '/tools/tooltool.py', 01:58:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:58:34 INFO - '/tools/misc-python/virtualenv.py')}, 01:58:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:58:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:58:34 INFO - 'installer_path': 'installer.exe', 01:58:34 INFO - 'log_level': 'info', 01:58:34 INFO - 'log_name': 'talos', 01:58:34 INFO - 'log_to_console': True, 01:58:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:58:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:58:34 INFO - 'opt_config_files': (), 01:58:34 INFO - 'pip_index': False, 01:58:34 INFO - 'sps_profile': False, 01:58:34 INFO - 'sps_profile_interval': 0, 01:58:34 INFO - 'suite': 'dromaeojs-e10s', 01:58:34 INFO - 'system_bits': '32', 01:58:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:58:34 INFO - 'title': 'talos-linux64-ix-039', 01:58:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:58:34 INFO - 'use_talos_json': True, 01:58:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:58:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:58:34 INFO - 'work_dir': 'build'} 01:58:34 INFO - ##### 01:58:34 INFO - ##### Running clobber step. 01:58:34 INFO - ##### 01:58:34 INFO - Running pre-action listener: _resource_record_pre_action 01:58:34 INFO - Running main action method: clobber 01:58:34 INFO - rmtree: /builds/slave/test/build 01:58:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:58:35 INFO - Running post-action listener: _resource_record_post_action 01:58:35 INFO - ##### 01:58:35 INFO - ##### Finished clobber step (success) 01:58:35 INFO - ##### 01:58:35 INFO - ##### 01:58:35 INFO - ##### Running read-buildbot-config step. 01:58:35 INFO - ##### 01:58:35 INFO - Running pre-action listener: _resource_record_pre_action 01:58:35 INFO - Running main action method: read_buildbot_config 01:58:35 INFO - Using buildbot properties: 01:58:35 INFO - { 01:58:35 INFO - "project": "", 01:58:35 INFO - "product": "firefox", 01:58:35 INFO - "script_repo_revision": "production", 01:58:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 01:58:35 INFO - "repository": "", 01:58:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 01:58:35 INFO - "buildid": "20160507012950", 01:58:35 INFO - "pgo_build": "False", 01:58:35 INFO - "basedir": "/builds/slave/test", 01:58:35 INFO - "buildnumber": 1260, 01:58:35 INFO - "slavename": "talos-linux64-ix-039", 01:58:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:58:35 INFO - "platform": "ubuntu64_hw", 01:58:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 01:58:35 INFO - "branch": "fx-team", 01:58:35 INFO - "repo_path": "integration/fx-team", 01:58:35 INFO - "revision": "44a989cf6c1662b60e3113cb6503817b7fc31131", 01:58:35 INFO - "stage_platform": "linux64", 01:58:35 INFO - "builduid": "c92d4849b1c843228803373bdd43ee44", 01:58:35 INFO - "slavebuilddir": "test" 01:58:35 INFO - } 01:58:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 01:58:35 INFO - Running post-action listener: _resource_record_post_action 01:58:35 INFO - ##### 01:58:35 INFO - ##### Finished read-buildbot-config step (success) 01:58:35 INFO - ##### 01:58:35 INFO - ##### 01:58:35 INFO - ##### Running download-and-extract step. 01:58:35 INFO - ##### 01:58:35 INFO - Running pre-action listener: _resource_record_pre_action 01:58:35 INFO - Running main action method: download_and_extract 01:58:35 INFO - mkdir: /builds/slave/test/build/tests 01:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:35 INFO - https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:38 INFO - Downloaded 1361 bytes. 01:58:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:58:38 INFO - Using the following test package requirements: 01:58:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:58:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:58:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:58:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'jsshell-linux-x86_64.zip'], 01:58:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:58:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:58:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:58:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:58:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:58:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:58:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:38 INFO - https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:58:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:58:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:58:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:40 INFO - Downloaded 22321369 bytes. 01:58:40 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'] 01:58:40 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 01:58:41 INFO - Return code: 0 01:58:41 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 01:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:41 INFO - https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:58:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:58:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:58:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:46 INFO - Downloaded 22321369 bytes. 01:58:46 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'] 01:58:46 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 01:58:47 INFO - Return code: 0 01:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:47 INFO - https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:58:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:58:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:58:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:48 INFO - Downloaded 10840152 bytes. 01:58:48 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'] 01:58:48 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 01:58:48 INFO - Return code: 0 01: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')]} 01:58:48 INFO - https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:58:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:58:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:58:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MHk5wQMuScCETDEV_MNd0A/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 01:58:50 INFO - Downloaded 54843563 bytes. 01:58:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:58:50 INFO - mkdir: /builds/slave/test/properties 01:58:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:58:50 INFO - Writing to file /builds/slave/test/properties/build_url 01:58:50 INFO - Contents: 01:58:50 INFO - build_url:https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:58:51 INFO - Running post-action listener: _resource_record_post_action 01:58:51 INFO - Running post-action listener: set_extra_try_arguments 01:58:51 INFO - ##### 01:58:51 INFO - ##### Finished download-and-extract step (success) 01:58:51 INFO - ##### 01:58:51 INFO - ##### 01:58:51 INFO - ##### Running populate-webroot step. 01:58:51 INFO - ##### 01:58:51 INFO - Running pre-action listener: _resource_record_pre_action 01:58:51 INFO - Running main action method: populate_webroot 01:58:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:58:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:58:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:58:51 INFO - '--tppagecycles', 01:58:51 INFO - '7'], 01:58:51 INFO - 'tests': ['tsvgm']}}, 01:58:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:58:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:58:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:58:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:58:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['damp', 'tps']}, 01:58:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'tests': ['damp', 'tps']}, 01:58:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['dromaeo_dom']}, 01:58:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:58:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'other-e10s': {'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['a11yr', 01:58:51 INFO - 'ts_paint', 01:58:51 INFO - 'tpaint', 01:58:51 INFO - 'sessionrestore', 01:58:51 INFO - 'sessionrestore_no_auto_restore', 01:58:51 INFO - 'tabpaint']}, 01:58:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['tsvgx', 01:58:51 INFO - 'tsvgr_opacity', 01:58:51 INFO - 'tart', 01:58:51 INFO - 'tscrollx', 01:58:51 INFO - 'cart']}, 01:58:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:58:51 INFO - 'tsvgr_opacity', 01:58:51 INFO - 'tart', 01:58:51 INFO - 'tscrollx', 01:58:51 INFO - 'cart']}, 01:58:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'talos_options': ['--disable-e10s'], 01:58:51 INFO - 'tests': ['tp5o']}, 01:58:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'tests': ['tp5o']}, 01:58:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'talos_options': ['--disable-e10s', 01:58:51 INFO - '--xperf_path', 01:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:51 INFO - 'tests': ['tp5n']}, 01:58:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:51 INFO - 'talos_options': ['--xperf_path', 01:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:51 INFO - 'tests': ['tp5n']}}, 01:58:51 INFO - 'talos.zip': {'path': '', 01:58:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:58:51 INFO - Running post-action listener: _resource_record_post_action 01:58:51 INFO - ##### 01:58:51 INFO - ##### Finished populate-webroot step (success) 01:58:51 INFO - ##### 01:58:51 INFO - ##### 01:58:51 INFO - ##### Running create-virtualenv step. 01:58:51 INFO - ##### 01:58:51 INFO - Running pre-action listener: _resource_record_pre_action 01:58:51 INFO - Running main action method: create_virtualenv 01:58:51 INFO - Creating virtualenv /builds/slave/test/build/venv 01:58:51 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 01:58:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:58:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:58:51 INFO - Using real prefix '/usr' 01:58:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:58:51 INFO - Installing distribute.............................................................................................................................................................................................done. 01:58:53 INFO - Installing pip.................done. 01:58:53 INFO - Return code: 0 01:58:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:58:53 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 01:58:53 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 01:58:53 INFO - Using env: {'DISPLAY': ':0', 01:58:53 INFO - 'HOME': '/home/cltbld', 01:58:53 INFO - 'LANG': 'en_US.UTF-8', 01:58:53 INFO - 'LANGUAGE': 'en_US:en', 01:58:53 INFO - 'LOGNAME': 'cltbld', 01:58:53 INFO - 'MAIL': '/var/mail/cltbld', 01:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:53 INFO - 'MOZ_NO_REMOTE': '1', 01:58:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:58:53 INFO - 'NO_EM_RESTART': '1', 01:58:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:53 INFO - 'PWD': '/builds/slave/test', 01:58:53 INFO - 'SHELL': '/bin/bash', 01:58:53 INFO - 'SHLVL': '1', 01:58:53 INFO - 'TERM': 'linux', 01:58:53 INFO - 'TMOUT': '86400', 01:58:53 INFO - 'USER': 'cltbld', 01:58:53 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:53 INFO - '_': '/tools/buildbot/bin/python'} 01:58:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:53 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 01:58:53 INFO - Cleaning up... 01:58:53 INFO - Return code: 0 01:58:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:58:53 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 01:58:53 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 01:58:53 INFO - Using env: {'DISPLAY': ':0', 01:58:53 INFO - 'HOME': '/home/cltbld', 01:58:53 INFO - 'LANG': 'en_US.UTF-8', 01:58:53 INFO - 'LANGUAGE': 'en_US:en', 01:58:53 INFO - 'LOGNAME': 'cltbld', 01:58:53 INFO - 'MAIL': '/var/mail/cltbld', 01:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:53 INFO - 'MOZ_NO_REMOTE': '1', 01:58:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:58:53 INFO - 'NO_EM_RESTART': '1', 01:58:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:53 INFO - 'PWD': '/builds/slave/test', 01:58:53 INFO - 'SHELL': '/bin/bash', 01:58:53 INFO - 'SHLVL': '1', 01:58:53 INFO - 'TERM': 'linux', 01:58:53 INFO - 'TMOUT': '86400', 01:58:53 INFO - 'USER': 'cltbld', 01:58:53 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:53 INFO - '_': '/tools/buildbot/bin/python'} 01:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:54 INFO - Downloading/unpacking psutil>=0.7.1 01:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:56 INFO - Installing collected packages: psutil 01:58:56 INFO - Running setup.py install for psutil 01:58:56 INFO - building 'psutil._psutil_linux' extension 01:58:56 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 01:58:56 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 01:58:56 INFO - building 'psutil._psutil_posix' extension 01:58:56 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 01:58:56 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 01:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:56 INFO - Successfully installed psutil 01:58:56 INFO - Cleaning up... 01:58:56 INFO - Return code: 0 01:58:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:58:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:58:56 INFO - Using env: {'DISPLAY': ':0', 01:58:56 INFO - 'HOME': '/home/cltbld', 01:58:56 INFO - 'LANG': 'en_US.UTF-8', 01:58:56 INFO - 'LANGUAGE': 'en_US:en', 01:58:56 INFO - 'LOGNAME': 'cltbld', 01:58:56 INFO - 'MAIL': '/var/mail/cltbld', 01:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:56 INFO - 'MOZ_NO_REMOTE': '1', 01:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:58:56 INFO - 'NO_EM_RESTART': '1', 01:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:56 INFO - 'PWD': '/builds/slave/test', 01:58:56 INFO - 'SHELL': '/bin/bash', 01:58:56 INFO - 'SHLVL': '1', 01:58:56 INFO - 'TERM': 'linux', 01:58:56 INFO - 'TMOUT': '86400', 01:58:56 INFO - 'USER': 'cltbld', 01:58:56 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:56 INFO - '_': '/tools/buildbot/bin/python'} 01:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:58:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:58:58 INFO - Installing collected packages: mozsystemmonitor 01:58:58 INFO - Running setup.py install for mozsystemmonitor 01:58:58 INFO - Successfully installed mozsystemmonitor 01:58:58 INFO - Cleaning up... 01:58:58 INFO - Return code: 0 01:58:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:58: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')]} 01:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58: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')]} 01:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:58 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:58:58 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 01:58:58 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 01:58:58 INFO - Using env: {'DISPLAY': ':0', 01:58:58 INFO - 'HOME': '/home/cltbld', 01:58:58 INFO - 'LANG': 'en_US.UTF-8', 01:58:58 INFO - 'LANGUAGE': 'en_US:en', 01:58:58 INFO - 'LOGNAME': 'cltbld', 01:58:58 INFO - 'MAIL': '/var/mail/cltbld', 01:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:58 INFO - 'MOZ_NO_REMOTE': '1', 01:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:58:58 INFO - 'NO_EM_RESTART': '1', 01:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:58 INFO - 'PWD': '/builds/slave/test', 01:58:58 INFO - 'SHELL': '/bin/bash', 01:58:58 INFO - 'SHLVL': '1', 01:58:58 INFO - 'TERM': 'linux', 01:58:58 INFO - 'TMOUT': '86400', 01:58:58 INFO - 'USER': 'cltbld', 01:58:58 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:58 INFO - '_': '/tools/buildbot/bin/python'} 01:58:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:59 INFO - Downloading/unpacking blobuploader==1.2.4 01:58:59 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 01:58:59 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 01:58:59 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 01:58:59 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 01:58:59 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 01:58:59 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 01:59:01 INFO - Downloading blobuploader-1.2.4.tar.gz 01:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:59:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:59:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 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 01:59:01 INFO - Downloading docopt-0.6.1.tar.gz 01:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:59:01 INFO - Installing collected packages: blobuploader, requests, docopt 01:59:01 INFO - Running setup.py install for blobuploader 01:59:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:59:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:59:01 INFO - Running setup.py install for requests 01:59:02 INFO - Running setup.py install for docopt 01:59:02 INFO - Successfully installed blobuploader requests docopt 01:59:02 INFO - Cleaning up... 01:59:02 INFO - Return code: 0 01:59:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59: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')]} 01:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59: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')]} 01:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:02 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:59:02 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 01:59:02 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 01:59:02 INFO - Using env: {'DISPLAY': ':0', 01:59:02 INFO - 'HOME': '/home/cltbld', 01:59:02 INFO - 'LANG': 'en_US.UTF-8', 01:59:02 INFO - 'LANGUAGE': 'en_US:en', 01:59:02 INFO - 'LOGNAME': 'cltbld', 01:59:02 INFO - 'MAIL': '/var/mail/cltbld', 01:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:02 INFO - 'MOZ_NO_REMOTE': '1', 01:59:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:02 INFO - 'NO_EM_RESTART': '1', 01:59:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:02 INFO - 'PWD': '/builds/slave/test', 01:59:02 INFO - 'SHELL': '/bin/bash', 01:59:02 INFO - 'SHLVL': '1', 01:59:02 INFO - 'TERM': 'linux', 01:59:02 INFO - 'TMOUT': '86400', 01:59:02 INFO - 'USER': 'cltbld', 01:59:02 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:02 INFO - '_': '/tools/buildbot/bin/python'} 01:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:59:02 INFO - Running setup.py (path:/tmp/pip-nlOvEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:59:02 INFO - Running setup.py (path:/tmp/pip-MwA0ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:59:02 INFO - Running setup.py (path:/tmp/pip-TR3Epi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:59:02 INFO - Running setup.py (path:/tmp/pip-tqxwle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:59:02 INFO - Running setup.py (path:/tmp/pip-G_IBU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:59:02 INFO - Running setup.py (path:/tmp/pip-B7JyAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:59:03 INFO - Running setup.py (path:/tmp/pip-pFYzUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:59:03 INFO - Running setup.py (path:/tmp/pip-0zo4Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:59:03 INFO - Running setup.py (path:/tmp/pip-X8W0kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:59:03 INFO - Running setup.py (path:/tmp/pip-1ed4D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:59:03 INFO - Running setup.py (path:/tmp/pip-mtWy2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:59:03 INFO - Running setup.py (path:/tmp/pip-CGORIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:59:03 INFO - Running setup.py (path:/tmp/pip-9cOiOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:59:03 INFO - Running setup.py (path:/tmp/pip-Tu_Hav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:03 INFO - Running setup.py (path:/tmp/pip-9dQtF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:59:03 INFO - Running setup.py (path:/tmp/pip-MiVyrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:59:03 INFO - Running setup.py (path:/tmp/pip-05iJK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:59:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:59:03 INFO - Running setup.py install for manifestparser 01:59:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:59:03 INFO - Running setup.py install for mozcrash 01:59:04 INFO - Running setup.py install for mozdebug 01:59:04 INFO - Running setup.py install for mozdevice 01:59:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for mozfile 01:59:04 INFO - Running setup.py install for mozhttpd 01:59:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for mozinfo 01:59:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for mozInstall 01:59:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for mozleak 01:59:04 INFO - Running setup.py install for mozlog 01:59:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for moznetwork 01:59:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:59:04 INFO - Running setup.py install for mozprocess 01:59:05 INFO - Running setup.py install for mozprofile 01:59:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:59:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:59:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:59:05 INFO - Running setup.py install for mozrunner 01:59:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:59:05 INFO - Running setup.py install for mozscreenshot 01:59:05 INFO - Running setup.py install for moztest 01:59:05 INFO - Running setup.py install for mozversion 01:59:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:59:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:59:05 INFO - Cleaning up... 01:59:05 INFO - Return code: 0 01:59:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59: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')]} 01:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59: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')]} 01:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:05 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:59:05 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 01:59:05 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 01:59:05 INFO - Using env: {'DISPLAY': ':0', 01:59:05 INFO - 'HOME': '/home/cltbld', 01:59:05 INFO - 'LANG': 'en_US.UTF-8', 01:59:05 INFO - 'LANGUAGE': 'en_US:en', 01:59:05 INFO - 'LOGNAME': 'cltbld', 01:59:05 INFO - 'MAIL': '/var/mail/cltbld', 01:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:05 INFO - 'MOZ_NO_REMOTE': '1', 01:59:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:05 INFO - 'NO_EM_RESTART': '1', 01:59:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:05 INFO - 'PWD': '/builds/slave/test', 01:59:05 INFO - 'SHELL': '/bin/bash', 01:59:05 INFO - 'SHLVL': '1', 01:59:05 INFO - 'TERM': 'linux', 01:59:05 INFO - 'TMOUT': '86400', 01:59:05 INFO - 'USER': 'cltbld', 01:59:05 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:05 INFO - '_': '/tools/buildbot/bin/python'} 01:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:59:05 INFO - Running setup.py (path:/tmp/pip-aQowLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:59:05 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)) 01:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:59:05 INFO - Running setup.py (path:/tmp/pip-x_A18N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:59:05 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)) 01:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:59:05 INFO - Running setup.py (path:/tmp/pip-2Mfayg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:59:06 INFO - Running setup.py (path:/tmp/pip-kHTHWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:59:06 INFO - Running setup.py (path:/tmp/pip-d8wTGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:59:06 INFO - Running setup.py (path:/tmp/pip-A239h2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:59:06 INFO - Running setup.py (path:/tmp/pip-8ddjR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:59:06 INFO - Running setup.py (path:/tmp/pip-_fGUP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:59:06 INFO - Running setup.py (path:/tmp/pip-HxPqgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:59:06 INFO - Running setup.py (path:/tmp/pip-f2BGLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:59:06 INFO - Running setup.py (path:/tmp/pip-DPc1Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:59:06 INFO - Running setup.py (path:/tmp/pip-g1cwuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:59:06 INFO - Running setup.py (path:/tmp/pip-RbY3rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:59:06 INFO - Running setup.py (path:/tmp/pip-XtHM_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:06 INFO - Running setup.py (path:/tmp/pip-rE99In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:06 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)) 01:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:59:06 INFO - Running setup.py (path:/tmp/pip-m4nAFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:59:07 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)) 01:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:59:07 INFO - Running setup.py (path:/tmp/pip-NBPXme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:08 INFO - Downloading blessings-1.6.tar.gz 01:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:59:09 INFO - Installing collected packages: blessings 01:59:09 INFO - Running setup.py install for blessings 01:59:09 INFO - Successfully installed blessings 01:59:09 INFO - Cleaning up... 01:59:09 INFO - Return code: 0 01:59:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:59:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:09 INFO - Reading from file tmpfile_stdout 01:59:09 INFO - Current package versions: 01:59:09 INFO - argparse == 1.2.1 01:59:09 INFO - blessings == 1.6 01:59:09 INFO - blobuploader == 1.2.4 01:59:09 INFO - docopt == 0.6.1 01:59:09 INFO - manifestparser == 1.1 01:59:09 INFO - mozInstall == 1.12 01:59:09 INFO - mozcrash == 0.17 01:59:09 INFO - mozdebug == 0.1 01:59:09 INFO - mozdevice == 0.48 01:59:09 INFO - mozfile == 1.2 01:59:09 INFO - mozhttpd == 0.7 01:59:09 INFO - mozinfo == 0.9 01:59:09 INFO - mozleak == 0.1 01:59:09 INFO - mozlog == 3.1 01:59:09 INFO - moznetwork == 0.27 01:59:09 INFO - mozprocess == 0.22 01:59:09 INFO - mozprofile == 0.28 01:59:09 INFO - mozrunner == 6.11 01:59:09 INFO - mozscreenshot == 0.1 01:59:09 INFO - mozsystemmonitor == 0.0 01:59:09 INFO - moztest == 0.7 01:59:09 INFO - mozversion == 1.4 01:59:09 INFO - psutil == 3.1.1 01:59:09 INFO - requests == 1.2.3 01:59:09 INFO - wsgiref == 0.1.2 01:59:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:09 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:59:09 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 01:59:09 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 01:59:09 INFO - Using env: {'DISPLAY': ':0', 01:59:09 INFO - 'HOME': '/home/cltbld', 01:59:09 INFO - 'LANG': 'en_US.UTF-8', 01:59:09 INFO - 'LANGUAGE': 'en_US:en', 01:59:09 INFO - 'LOGNAME': 'cltbld', 01:59:09 INFO - 'MAIL': '/var/mail/cltbld', 01:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:09 INFO - 'MOZ_NO_REMOTE': '1', 01:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:09 INFO - 'NO_EM_RESTART': '1', 01:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:09 INFO - 'PWD': '/builds/slave/test', 01:59:09 INFO - 'SHELL': '/bin/bash', 01:59:09 INFO - 'SHLVL': '1', 01:59:09 INFO - 'TERM': 'linux', 01:59:09 INFO - 'TMOUT': '86400', 01:59:09 INFO - 'USER': 'cltbld', 01:59:09 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:09 INFO - '_': '/tools/buildbot/bin/python'} 01:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 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)) 01:59:09 INFO - Cleaning up... 01:59:09 INFO - Return code: 0 01:59:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:59:09 INFO - Using env: {'DISPLAY': ':0', 01:59:09 INFO - 'HOME': '/home/cltbld', 01:59:09 INFO - 'LANG': 'en_US.UTF-8', 01:59:09 INFO - 'LANGUAGE': 'en_US:en', 01:59:09 INFO - 'LOGNAME': 'cltbld', 01:59:09 INFO - 'MAIL': '/var/mail/cltbld', 01:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:09 INFO - 'MOZ_NO_REMOTE': '1', 01:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:09 INFO - 'NO_EM_RESTART': '1', 01:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:09 INFO - 'PWD': '/builds/slave/test', 01:59:09 INFO - 'SHELL': '/bin/bash', 01:59:09 INFO - 'SHLVL': '1', 01:59:09 INFO - 'TERM': 'linux', 01:59:09 INFO - 'TMOUT': '86400', 01:59:09 INFO - 'USER': 'cltbld', 01:59:09 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:09 INFO - '_': '/tools/buildbot/bin/python'} 01:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:09 INFO - Downloading/unpacking jsonschema 01:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:59:11 INFO - Downloading/unpacking functools32 (from jsonschema) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:11 INFO - Downloading functools32-3.2.3-2.tar.gz 01:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:59:12 INFO - warning: no files found matching '*.txt' 01:59:12 INFO - no previously-included directories found matching 'build' 01:59:12 INFO - no previously-included directories found matching 'dist' 01:59:12 INFO - no previously-included directories found matching '.git*' 01:59:12 INFO - Installing collected packages: jsonschema, functools32 01:59:12 INFO - Running setup.py install for functools32 01:59:12 INFO - warning: no files found matching '*.txt' 01:59:12 INFO - no previously-included directories found matching 'build' 01:59:12 INFO - no previously-included directories found matching 'dist' 01:59:12 INFO - no previously-included directories found matching '.git*' 01:59:12 INFO - Successfully installed jsonschema functools32 01:59:12 INFO - Cleaning up... 01:59:12 INFO - Return code: 0 01:59:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:12 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 0x7fd7b6105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12eb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, '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': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', '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 01:59:12 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 01:59:12 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 01:59:12 INFO - Using env: {'DISPLAY': ':0', 01:59:12 INFO - 'HOME': '/home/cltbld', 01:59:12 INFO - 'LANG': 'en_US.UTF-8', 01:59:12 INFO - 'LANGUAGE': 'en_US:en', 01:59:12 INFO - 'LOGNAME': 'cltbld', 01:59:12 INFO - 'MAIL': '/var/mail/cltbld', 01:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:12 INFO - 'MOZ_NO_REMOTE': '1', 01:59:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:12 INFO - 'NO_EM_RESTART': '1', 01:59:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:12 INFO - 'PWD': '/builds/slave/test', 01:59:12 INFO - 'SHELL': '/bin/bash', 01:59:12 INFO - 'SHLVL': '1', 01:59:12 INFO - 'TERM': 'linux', 01:59:12 INFO - 'TMOUT': '86400', 01:59:12 INFO - 'USER': 'cltbld', 01:59:12 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:12 INFO - '_': '/tools/buildbot/bin/python'} 01:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:12 INFO - Downloading/unpacking flake8 01:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:59:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:59:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:59:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:15 INFO - no previously-included directories found matching 'docs/_build' 01:59:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:59:15 INFO - Running setup.py install for pep8 01:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:15 INFO - no previously-included directories found matching 'docs/_build' 01:59:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:59:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:59:15 INFO - Cleaning up... 01:59:15 INFO - Return code: 0 01:59:15 INFO - Running post-action listener: _resource_record_post_action 01:59:15 INFO - Running post-action listener: _start_resource_monitoring 01:59:15 INFO - Starting resource monitoring. 01:59:15 INFO - ##### 01:59:15 INFO - ##### Finished create-virtualenv step (success) 01:59:15 INFO - ##### 01:59:15 INFO - ##### 01:59:15 INFO - ##### Running install step. 01:59:15 INFO - ##### 01:59:15 INFO - Running pre-action listener: _resource_record_pre_action 01:59:15 INFO - Running main action method: install 01:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:15 INFO - Reading from file tmpfile_stdout 01:59:15 INFO - Detecting whether we're running mozinstall >=1.0... 01:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:59:15 INFO - Reading from file tmpfile_stdout 01:59:15 INFO - Output received: 01:59:15 INFO - Usage: mozinstall [options] installer 01:59:15 INFO - Options: 01:59:15 INFO - -h, --help show this help message and exit 01:59:15 INFO - -d DEST, --destination=DEST 01:59:15 INFO - Directory to install application into. [default: 01:59:15 INFO - "/builds/slave/test"] 01:59:15 INFO - --app=APP Application being installed. [default: firefox] 01:59:15 INFO - mkdir: /builds/slave/test/build/application 01:59:15 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'] 01:59:15 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 01:59:31 INFO - Reading from file tmpfile_stdout 01:59:31 INFO - Output received: 01:59:31 INFO - /builds/slave/test/build/application/firefox/firefox 01:59:31 INFO - Running post-action listener: _resource_record_post_action 01:59:31 INFO - ##### 01:59:31 INFO - ##### Finished install step (success) 01:59:31 INFO - ##### 01:59:31 INFO - ##### 01:59:31 INFO - ##### Running run-tests step. 01:59:31 INFO - ##### 01:59:31 INFO - Running pre-action listener: _resource_record_pre_action 01:59:31 INFO - Running main action method: run_tests 01:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:59:31 INFO - Python 2.7.3 01:59:31 INFO - Return code: 0 01:59:31 INFO - grabbing minidump binary from tooltool 01:59:31 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')]} 01:59:31 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 0x137a9c0>, '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 0x137b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:59:31 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 01:59:31 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 01:59:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:59:31 INFO - Return code: 0 01:59:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:59:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:59:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:59:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:59:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:59:32 INFO - Return code: 0 01:59:32 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/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 01:59:32 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/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 01:59:32 INFO - Using env: {'DISPLAY': ':0', 01:59:32 INFO - 'HOME': '/home/cltbld', 01:59:32 INFO - 'LANG': 'en_US.UTF-8', 01:59:32 INFO - 'LANGUAGE': 'en_US:en', 01:59:32 INFO - 'LOGNAME': 'cltbld', 01:59:32 INFO - 'MAIL': '/var/mail/cltbld', 01:59:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:59:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:32 INFO - 'MOZ_NO_REMOTE': '1', 01:59:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:59:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:32 INFO - 'NO_EM_RESTART': '1', 01:59:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:32 INFO - 'PWD': '/builds/slave/test', 01:59:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:59:32 INFO - 'SHELL': '/bin/bash', 01:59:32 INFO - 'SHLVL': '1', 01:59:32 INFO - 'TERM': 'linux', 01:59:32 INFO - 'TMOUT': '86400', 01:59:32 INFO - 'USER': 'cltbld', 01:59:32 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868', 01:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:32 INFO - '_': '/tools/buildbot/bin/python'} 01:59:32 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/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 01:59:32 INFO - mozversion application_buildid: 20160507012950 01:59:32 INFO - mozversion application_changeset: 44a989cf6c1662b60e3113cb6503817b7fc31131 01:59:32 INFO - mozversion application_display_name: Nightly 01:59:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:59:32 INFO - mozversion application_name: Firefox 01:59:32 INFO - mozversion application_remotingname: firefox 01:59:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:59:32 INFO - mozversion application_vendor: Mozilla 01:59:32 INFO - mozversion application_version: 49.0a1 01:59:32 INFO - mozversion platform_buildid: 20160507012950 01:59:32 INFO - mozversion platform_changeset: 44a989cf6c1662b60e3113cb6503817b7fc31131 01:59:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:59:32 INFO - mozversion platform_version: 49.0a1 01:59:32 INFO - using testdate: 1462611572 01:59:32 INFO - actual date: 1462611572 01:59:32 INFO - starting webserver on 'localhost:60961' 01:59:32 INFO - SUITE-START | Running 2 tests 01:59:32 INFO - TEST-START | dromaeo_css 01:59:32 INFO - operating with platform_type : linux_ 01:59:32 INFO - Initialising browser for dromaeo_css test... 01:59:32 INFO - TEST-INFO | started process 8842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpImPyJp/profile http://localhost:60961/getInfo.html) 01:59:36 INFO - PROCESS | 8842 | 01:59:36 INFO - PROCESS | 8842 | (process:8910): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:59:37 INFO - PROCESS | 8842 | __metrics Screen width/height:1600/1200 01:59:37 INFO - PROCESS | 8842 | colorDepth:24 01:59:37 INFO - PROCESS | 8842 | Browser inner width/height: 1024/697 01:59:37 INFO - PROCESS | 8842 | __metrics 01:59:38 ERROR - PROCESS | 8842 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 01:59:39 INFO - TEST-INFO | 8842: exit 0 01:59:39 INFO - Browser initialized. 01:59:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:59:39 INFO - TEST-INFO | started process 8961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpImPyJp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:41 INFO - PROCESS | 8961 | 01:59:41 INFO - PROCESS | 8961 | (process:9021): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:59:51 INFO - PROCESS | 8961 | RSS: Main: 163942400 01:59:51 INFO - PROCESS | 8961 | 02:00:40 INFO - PROCESS | 8961 | 1462611640859 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] 02:02:51 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60961/tests/dromaeo/cssquery-ext.html) 02:02:52 INFO - PROCESS | 8961 | RSS: Main: 165556224 02:02:52 INFO - PROCESS | 8961 | 02:05:54 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-ext.html (next: http://localhost:60961/tests/dromaeo/cssquery-jquery.html) 02:05:55 INFO - PROCESS | 8961 | RSS: Main: 166658048 02:05:55 INFO - PROCESS | 8961 | 02:08:57 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60961/tests/dromaeo/cssquery-mootools.html) 02:08:57 INFO - PROCESS | 8961 | RSS: Main: 167505920 02:08:57 INFO - PROCESS | 8961 | 02:11:59 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60961/tests/dromaeo/cssquery-prototype.html) 02:12:00 INFO - PROCESS | 8961 | RSS: Main: 165109760 02:12:00 INFO - PROCESS | 8961 | 02:15:02 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60961/tests/dromaeo/cssquery-yui.html) 02:15:03 INFO - PROCESS | 8961 | RSS: Main: 166170624 02:15:03 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | Cycle 1(1): loaded http://localhost:60961/tests/dromaeo/cssquery-yui.html (next: http://localhost:60961/tests/dromaeo/cssquery-dojo.html) 02:18:06 INFO - PROCESS | 8961 | RSS: Main: 168509440 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | __start_tp_report 02:18:06 INFO - PROCESS | 8961 | _x_x_mozilla_page_load 02:18:06 INFO - PROCESS | 8961 | _x_x_mozilla_page_load_details 02:18:06 INFO - PROCESS | 8961 | |i|pagename|runs| 02:18:06 INFO - PROCESS | 8961 | |0;dojo.html;2582.7675509204173;2631.7631413172817;2646.3516438472575;2650.2446802661234;2659.135780871216;4621.353010578522;4630.398048253726;4661.277501987197;4696.272077827942;4698.107359601678;4534.705244159128;4567.36056952027;4576.97711511442;4597.931031034537;4610.815567377301;4166.500019997597;4274.252005898955;4322.956770432303;4331.675124365669;4377.6935614507;19.403411044308545;4067.5098265676206;4155.794819502341;4301.569843015689;4323.265044942367;4208.032152604903;4221.113566151112;4223.408722778798;4289.356596510517;4335.631471324941;4249.723767955073;4270.765107919063;4271.380649805761;4300.78496075195;4342.804573794187;4114.650254728352;4192.098698779747;4255.680823938217;4256.212600668886;4268.039691069484;4502.032063106448;4540.931886021713;4550.908981820345;4551.044280701057;4610.976945115286;4925.802967881281;4964.478729733398;4977.850664480072;4982.626303027288;5012.172991456406;4847;4959.181735013718;4979.327790748239;5002.774875130628;5035.269885866565;4682.648801339913;4695.131400690849;4699.271612900006;4706.458757242961;4744.406949131393;4915.705057696549;5127.282180494733;5133.281340612317;5137.948620513747;5176.974115129476;4778.928316075262;4810.927836082462;4822.903541929142;4843.249296358999;4845.491223421561;4908.067467181224;4937.259411088366;4938.753062346868;4938.851834444972;4987.825426110068;4807.591354734886;4845.3216549683075;4880.341153944243;4890.168671325883;4954.455009948902;4850.587700045536;4854.441739200037;4876.5854902332985;4889.63327750413;4938.209886418156;4243.448351714257;4249.5113061997645;4253.957460425418;4266.978665106654;4308.310670292801;3654.9948764090086;3698.4637227601847;3712.090537818252;3723.7579557328686;3772.302124107049;4225;4258.957410425856;4267.317229243306;4308.353746937984;4364.388985542027;5025.195968644999;5039.370078740158;5039.748012599355;5045.495450454999;5080.085584594808;4253.914921701548;4301.505326887385;4322.935155972663;4333.8699839004885;4380.7152535085115;4534.659900507475;4563.703359281636;4580.198465268565;4620.607248383858;4624.398828152319;4722.173619616553;4743.952560474351;4745.928811067905;4752.14461396945;4806.8798280043275;4830.637702172281;4903.77932993009;4911.754412279301;5051.040302342543;5052;4639.837605683785;4650.93023604653;4706.294055891643;4707.270373092176;4707.81168753246;4880.365552478155;4911.7298548580175;4916.0905232532095;4928.162212423825;4948.777305021355;4803.5916947059895;4921.50784921505;4934.235193545006;4959.181735013683;4971.105201063843;4817.927731083966;4839.1531481991215;4852.8301509447;4858.975705121452;4888.340074090094;4816.759162041954;4847.903041939212;4852.733099679552;4854.68444551103;4900.705957642553;4570;4774.737389443614;4790.329353890388;4842.176829938849;4848.393950756155;4869.9026019480125;4881.926771098508;4908.214685650279;4908.656394052382;4943.826966056171;4634.32802243666;4800.35195248651;4822.252550854623;4834.105690447279;4875.585575226146 02:18:06 INFO - PROCESS | 8961 | |1;ext.html;558.1516095534787;600.5255847880175;614.259816920611;615.4368752591379;619.8326451857996;36692.633073669254;40180.39729404061;40585.18829623406;40648.18703625933;40654;40246.7987660062;40618.7969060155;41241.793791031;41344.17311653765;41399;7769.300762931349;7879.527228366288;7898.2101789820845;7898.960505197466;7941.08677502086;7882.172371900954;7905.090914544815;7940.166282540336;7956.880646790303;7992.720254791084;7976.322012628933;8006.079300880415;8014.67941282348;8015.759527214163;8018.158093400197;7900.130985591581;7926.960365198166;7944;7962.323202527792;7986.840263194733;7867.567283799389;7879;7891.881621775678;7921.960390198012;7935.2064793520185;30717;38180.61819381799;38541.61458385408;38595.614043859765;38710.419343709866;3145.7126260444775;3208.96791032089;3332.6667333266723;3446.9310613787834;3611.9999999999736;28501.714982850113;28865.278368040756;28962.565561516592;29022.854885725646;29030.709692903223;3280.065181423283;3288.8817801947453;3289.901302960915;3314.552535407713;3356.3119560490286;216584;216588;217157;217408;217697;33876.83061584676;33880.49179262313;33949.83025084859;33954.66045339515;33978.49032264518;33410.49884251738;35809.64190358115;36252.09369765777;36348.27303453916;37991.24017519634;15079.924600377148;15322.387104515918;15328.693426131415;15348.309326080353;15355.846441535665;12025.87974120265;12046;12080.637580872588;12121;12166.209196402382;19266.068722204447;19641.017949102486;19661.311854085037;19784.90107549453;19817.504562386057;7301;7338.4496162788;7448.366888814403;7481.9251807482315;7504.812379690442;901.6753968571309;966.8598053282235;991.5934466868694;1003.3779056984716;1005.4470041477158;27824.860875695493;28666.426671466455;28810.135695929155;29175.416491670047;29293.56059659107;1038.9220808439397;1056.8309070548828;1059.4067322299536;1088.3905013192636;1101.9228653994303;15348;15483.845161548466;15675.529734107995;15689.843101569066;16059.759103613454;368248;706654;723251;723280;723392;52571;53107.73446132822;53124.46875531272;53386;53412.46587534153;1303.89568834493;1317.0714646174615;1331.6803967047838;1333.0335506757717;1334.759743246225;28098.438031239668;28825.855870720512;29505.704942951153;29645.851770741872;29694.703052969195;44072.33891491566;44289.33565996577;44359;44545.55454445544;44832.775836120614;336561;338026;338580;338886;338966;26113.869430653485;26556.734432656198;26750.331241719112;43274;44621.33068003917;42832.57167428286;45258.32112518249;45940.54059459363;46082.539174607824;46143.538564615264;38956.80521597374;39037.21925561416;39165.608343916196;39173.2165356697;39248;38080;38099;38379.42430863595;38753.806230968665;38763.41854872123;34172.31655366928;38141.80929095448;38666.42000369941;39173.41239881461;39927.2014559713;33524.32951340911;35284.294314114086;35816.104597385274;36209;36312.63687363092;26381.736182637927;26415.20754377372;26479.07323243677;26481.60277595876;26798 02:18:06 INFO - PROCESS | 8961 | |2;jquery.html;379.5729803970533;380.6669164481079;382.37481717392063;387.7615266611032;393.836557828501;16047.67904641908;17242.310307587682;17414.477565673034;17950.910245448755;17956;16229.350825966947;16499.422520211763;16726.58183545409;16768.32926682931;16798.58003549912;4630.629549636021;4843.200871856139;4854.684445511049;4857.684250523705;4874.488178741234;3501.0897166736704;5173.3016042834115;5477.20580515827;5478.342598888119;5549.306336707911;5361.463853614647;5539.086050801613;5545.889082218353;5558.777648894039;5559.444055594428;5471.124620060771;5475.425080366544;5486.6159368844;5496.31296087989;5517.779288828442;5175.45657705943;5281.498257665554;5323.627346085776;5326.866828329323;5358.571314294847;18158.727619085723;19408;20524.89737551303;20850.165993360246;20891.58216835655;1873.7938826729048;1967.1147983407525;2005.9097340619708;2052.1688716070016;2105.210546045233;9279.443233406017;9428.292878034175;9561.713148605553;11449.370284634342;12479.875201247962;1707.487753673893;1781.536800431884;1808.1140241281678;1821;1869.07480797005;67770.66114669395;68159.65920170417;68806.65596671985;69681;69698.65150674214;9389.577469013775;9615;9746.95126524377;9766.267529935141;9782.951085244527;13125.934370328278;13256.801147982787;13261.73476530464;13289;13289.3355332233;7001.614911179832;7016.649167541603;7155.534890232118;7203.675834587456;7308.04995350601;6885.690143943534;6902.102726645604;6932.826679332956;7068.611226382598;7071.398931090915;4075.694322925792;4105.67154627629;4129.876103716893;4171.416001759756;4233.491980962304;1629.7881275434117;1650.5460998225344;1774.104077440884;1780.1900135438345;1791.229771198371;897.6544030548156;913.6345461815195;915.7390143808982;1002.5238011944414;1021.5658345203259;16424;16604.83395166057;16921.407750728664;16923.576910577332;17053.48839534816;894.4812009034745;904.9864252036225;930.0095398400682;935.5462600638684;965.358036905464;9371.297152713574;9683.90316096844;9751;9865.506724663737;9922.702318930444;305817;306201;308391;393223;394545;80886.5955670218;84837.15162848416;85341;86773.1322686765;87125.56437217773;955.7897262602216;960.4189465373488;984.7685793838442;991.3407583956588;994.8955359687274;15331.61670958235;15480;15520.37918483248;15556.688866222386;16019.038857668576;34644;34924.825375872955;35519.934401967985;35823;36171.457428139125;47681;49962.75018625029;50048.249276260154;50879;51401.7429912848;7192.496525243183;7588.658510366937;7752.8837067445165;7764.22357764219;7768.53388796674;9552;11128.833067503832;11319.717007074889;11438.199326047097;11534.423278836193;9505.524723763645;9507.334486585873;9694.739683841055;9811.656592019244;9850.408975461496;9538.23694104456;9960.501974901372;10023.448710321003;10229;10443.112335451571;9751.317407781387;10036.996300370196;10136.493175341351;10200.795984080129;10275.537600807833;7706.422018348759;7762.922370776446;7813.413993950363;7844.607769611611;7871.370290376871;9632.518374081408;10097.040781125781;10356.326838755456;10554.313969591953;10573.2598718089 02:18:06 INFO - PROCESS | 8961 | |3;mootools.html;1526.0538466150986;1540.7919930809342;1734.1935999760117;1843.6036252205772;1875.6401608833598;6701.095352127461;7048.682809273582;7402.778541540652;7577.7726668199975;7629.8092547686265;7659.961700191505;7667.693292268303;7726.459147859652;7728.343090837271;7743.380529557622;2223.1774243529835;2261.2876943762744;2262.9773702262933;2264.116994372196;2265.150568536799;2186.5517568898335;2220.9000594973263;2251.3808702606752;2255.4248666589997;2259.4464356232666;2227.042371780134;2228.6434170532716;2233.70961774969;2237.966430503544;2250.2574150530368;2161.6973623692697;2169.2624507667388;2182.0726191368685;2186.0709198590534;2186.5408264264443;2165.036558731365;2165.177232651598;2167.5881582499273;2175.6845257437585;2175.8803265820375;7554.735584254525;7681.539107653559;7684.731034413769;7687.654055567513;7692.307692307663;4546.590806827368;4671.439427268716;4672.813087476497;4675.041616468649;4706.152892479352;2800.467911096885;2937;2956.0688383159236;2983.1647138801172;2992.8204307741607;942.1050002497655;1009.328796350426;1014.0518615094836;1015.9695209143738;1023.0894503891542;10527.420991845445;10539.262251642465;10575.78848423035;10579.57681692739;10600.469976501145;4272.401863739079;4335.3063509838275;4407.713498622579;4415.801288942005;4439.334099885043;8887.511186884654;9032.909670903207;9034.954825225963;9063.274938005075;9114.316426268057;4613.884652883705;4670.906581868411;4700.741459219776;4720.881977950578;4724.196886529298;3737.7383583149463;4125.773082480492;4250.043740158501;4267.253230684618;4282.678799090081;3673.430618254175;3692.538432695913;3773.8867833965023;3789.1663833956486;3900.3759398496672;1196.096946805174;1251.6620512461584;1252.4551819958165;1256.7486502699496;1259.175240217659;816.3795515408333;839.9790005249918;848.3806821020689;938.7840796616816;968.0561452583675;5297.443768404339;5314.12316967696;5373.086575282211;5447.53695935842;5465.617406781487;930.5254320296563;942.2085448223388;951.619352259088;952.1620973543238;952.8237276103943;3674.2284120334493;3742.5883152853166;3747.718921080875;3747.737658363943;3747.9437808432895;8915.55422228886;8945.284377249935;9074.909250907538;9198.356115072014;9241.168294853496;9004.054574269738;9059.637614495477;9097.681581144629;9137.680181193626;9161.496117713456;7058.364747172779;7099.89350159748;7135.785926422215;7137.357637812622;7246.637668116573;5326.520613144836;5351.759170837244;5393.325834270716;5422.349318081855;5424.2134890439875;8287.12985136552;8436.915630843612;8581.699640512552;8700.086490918362;8733.65065397377;8555.401121921655;8848.646054157762;8856.512891791013;8937.106289371271;9058.637654493747;5909.85225369352;6221.782237621662;6472.773452929125;6697.196336439659;6857.417119544695;7278.854422911618;7330.5968171751065;7331.120265568166;7350.705971761284;7360.33756961876;6475.287718351072;6668.933310666831;6681.632510211791;6740;6813.2846051163915;6600.471962242913;6622.238442579165;6661.400473957173;6708.329167083252;6741.1236539249585;6104.11490333908;6110.297315808739;6200.875982480416;6209.875802484015;6216.2540495139065;4522.502524722344;4560.954390456053;4577.496475387771;4589.977050114728;4662.114198302312;4415.448068991376;4463.196624607472;4600.976995115003;4687.648426367968;4696.2486002239475 02:18:06 INFO - PROCESS | 8961 | |4;prototype.html;315.09567541156883;318.93940151371237;319.70906475107654;321.1809884793775;321.3492677328411;4949.826756063534;4978.726170060645;4979.925301120486;5006.699598024121;5022.3219865318215;4837.685550439219;4856;4871.415430148379;4884.853454396365;4910.656254062217;3667.468217108518;3763.2097259575385;3862.8454861805494;3863.613638636128;3880.7477513961644;3854.8843534694006;3857.24783667185;3881.514810648669;3883.9029024274478;3885.1064255221177;3827.3684842001035;3849.538055433352;3853.7109716771356;3863.517060367454;3871.399933010374;3758.4550240215017;3772.717735570044;3862;3865.5168103987003;3871.1483473635753;3386.9661303386897;3387.1532116970757;3388.5933687957604;3403.6256011838677;3418.2479854431986;4622.768861556909;4628.421447319085;4641.559051890065;4664.416947881515;4676.953230467686;4419.4033805436175;4423.889402764925;4429.424174857248;4431.822727090913;4447.577480139382;4885.65488565487;4973.4280557735965;5001.44984051754;5015.824446144404;5059.203982586166;3705.110393699206;3720.962790372089;3729.533808273966;3731.048582611417;3747.7563958342894;5302.2046692996355;5305.204219367087;5306.548943339821;5309.761060752276;5347.652402593819;3706.5552133744122;3756.6055564165918;3767.210574976919;3799.126200973736;3820.815547180383;3827.6363745444137;3852.8266228019806;3859.3824988001784;3863.5750067492554;3873.205992771475;4334.21984042869;4361.629261512743;4363.432753742057;4371;4377.693561450668;4960.404751429779;4985.551300382983;5032.899342013139;5103.489651034941;5106.540411362995;1287.4978758284276;1294.372229468707;1303.4590644882267;1303.648015035935;1312.586535241396;846.280661437773;855.8074433252593;883.3330835219368;892.8883889513811;903.4714691905177;4357.956420435755;4507.166174257773;4515.706479078849;4533.32000199966;4534.274516077412;5276.762545685453;5280.551153152025;5286.60350473716;5287.576993840484;5298.8940221196135;4438.718339824569;4518.751468669189;4531.161735079021;4536.500984891659;4543.795529201194;2644.722304158074;2756.5803611140277;2761.6962134165224;2764.9723502764714;2765;4776.785044672998;4820.3492528508905;4833.516648335139;4876.414830220397;4911.287863259808;5043.546080852668;5219.8434046978655;5238.449962753933;5244.973775131176;5260.842174734764;5008.2738002988635;5013.949860501421;5018.548330650259;5018.949810501848;5022.488755622189;5313.3889602694635;5328.333958255218;5328.3605967284175;5337.866553336197;5342.706151161723;4954.430240522386;5202.73986300691;5248.107821670249;5255.290535777621;5277.788888444419;4948.812285051634;4967.82612608557;4970.776315065758;4975.676581022222;4981.452040275639;3690.9815450922574;3714.275716235308;3715.662361549786;3717.275131349361;3731.6268373163552;4994.350734404649;5291.391489978701;5328.333958255218;5330.706811125426;5365.517103460707;5118.360204974379;5129.563987060992;5149.665271757323;5150.175971844487;5179.92230116541;5099.974500127476;5134.666246693953;5180.766865490987;5187.58499320061;5195.480451954774;4954.934689041874;5023.572996295356;5041.395032596112;5050.696958182521;5063.8480845574695;4901.411830580353;4983;5113.693178409308;5140.7429628517675;5160.870978225575;5027.12025395547;5047.268146118866;5072.264521644414;5083.593312534915;5090.541851233407 02:18:06 INFO - PROCESS | 8961 | |5;yui.html;718.7879575525219;741.2180149941812;779.7972527142942;790.4506368074187;822.983540329193;11477.827832582507;12042.157049006562;12151.939240303785;12247.510099596017;12266.938665306661;12473.251604903711;12493.250404975684;13044;13398.397072131753;13403.262820544865;878.4202426398579;885.9734207973771;885.9778505537381;887.1527691055027;888.2671795768484;684.2985939412092;841.5161282262693;867.657275376226;871.4117970370006;876.951767652782;925.9305552083587;927.4945154890601;928.7770934975591;932.5849996751448;935.8596210568401;905.5110240470171;910.8633704944245;912.3066469483174;915.276931224332;916.9633214671405;673.737242475435;674.7773234832493;676.3777324861139;678.4809620640215;681.4241965539161;12742.490300387974;12794.680132996657;12795.680107997281;12798.872011279862;12800.61598152057;12151.331676757774;12479.875201247962;12511.311877846714;12531.122821402505;12901.67745806353;10171.745706357326;10209.795804083951;10352.275340726152;10470.319429237075;10580.947095264552;3330.467125259971;3343.5987681478136;3359.3281343731105;3372.3423932333203;3445.4831775233665;43846.780766096286;44309.77845110786;44763.552364476265;45133.54866451326;45225.773871130434;9689.370190937567;9756.658516951873;9762.511874406251;9892.80214395708;10014.39913605186;17872.28510859551;17967.281308747504;18131.36540221086;18188.454346369632;18225.45323640293;6956.200036995709;7033.683484243222;7066.576005439587;7105.609191494517;7144.3212894774915;3760.5487341519192;3835.2137811748526;3888.922221555553;3927.312720273998;4002.3796311571755;2892.942141157165;2952.660444048919;2987.0292155049697;2992.012635830171;2999.4151140527388;514.7194778845538;518.7847043476914;520.1002266079703;526.431454029647;534.6791924845062;552.5962065017143;599.9500873471425;623.9220097487814;633.6863252689948;661.6856992928417;7675.117361503387;7913.089994650573;7945.404094692921;8004.599770011476;8005.159458256916;580.6632153351046;605.8091701114136;638.2755572425237;641.2689533931322;668.7893313606198;3604.279144171124;3755.887323380303;3765.8305376258136;3769.359208934488;3772.641599048086;50843.74578127086;50864.49135508598;51046.234306485436;51157;51916.48083519192;45256.094878102915;45596.77201614037;46031.76984115058;46194.76902615465;46276;39075.2184956305;39750.60249397527;39979.60020399817;42850.57149428528;43012.78493607512;8323.916760832315;8363.414560980673;8409.117042710428;8512.829743405222;8629.26651234627;28954.710452896044;29516;29789;29926.25184370408;29951;36232.81883590565;36950.81524592467;36956.63043369532;37121.443178352885;37301.81349093237;10471.738206544896;10886.891131088905;10927.45362731876;11132.109431245643;11266.493007814504;11675.514018691589;11810.586629467927;12049.277043377426;12546.247225166519;12652.367381631066;11196.608118715805;11418.257813242113;11682.182247242952;11794.941025294818;12410.5656302029;11735.706607334885;12014.699632509257;12168.452419641315;12186.329751863732;12577.119601627799;10240;11395.943020285176;11486;11745;11844.230125041844;9078.546072696206;9273.76815579589;9293.628254869729;9308.953455232951;9439.292053096182;9233.815323693354;9968.352057116264;10004.949975250078;10061;10112.898871011195 02:18:06 INFO - PROCESS | 8961 | __end_tp_report 02:18:06 INFO - PROCESS | 8961 | __start_cc_report 02:18:06 INFO - PROCESS | 8961 | _x_x_mozilla_cycle_collect,387 02:18:06 INFO - PROCESS | 8961 | __end_cc_report 02:18:06 INFO - PROCESS | 8961 | __startTimestamp1462612686462__endTimestamp 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | ------- Summary: start ------- 02:18:06 INFO - PROCESS | 8961 | Number of tests: 6 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#0] dojo.html Cycles:165 Average:4567.05 Median:4743.95 stddev:591.13 (12.5%) stddev-sans-first:572.08 02:18:06 INFO - PROCESS | 8961 | Values: 2582.8 2631.8 2646.4 2650.2 2659.1 4621.4 4630.4 4661.3 4696.3 4698.1 4534.7 4567.4 4577.0 4597.9 4610.8 4166.5 4274.3 4323.0 4331.7 4377.7 19.4 4067.5 4155.8 4301.6 4323.3 4208.0 4221.1 4223.4 4289.4 4335.6 4249.7 4270.8 4271.4 4300.8 4342.8 4114.7 4192.1 4255.7 4256.2 4268.0 4502.0 4540.9 4550.9 4551.0 4611.0 4925.8 4964.5 4977.9 4982.6 5012.2 4847.0 4959.2 4979.3 5002.8 5035.3 4682.6 4695.1 4699.3 4706.5 4744.4 4915.7 5127.3 5133.3 5137.9 5177.0 4778.9 4810.9 4822.9 4843.2 4845.5 4908.1 4937.3 4938.8 4938.9 4987.8 4807.6 4845.3 4880.3 4890.2 4954.5 4850.6 4854.4 4876.6 4889.6 4938.2 4243.4 4249.5 4254.0 4267.0 4308.3 3655.0 3698.5 3712.1 3723.8 3772.3 4225.0 4259.0 4267.3 4308.4 4364.4 5025.2 5039.4 5039.7 5045.5 5080.1 4253.9 4301.5 4322.9 4333.9 4380.7 4534.7 4563.7 4580.2 4620.6 4624.4 4722.2 4744.0 4745.9 4752.1 4806.9 4830.6 4903.8 4911.8 5051.0 5052.0 4639.8 4650.9 4706.3 4707.3 4707.8 4880.4 4911.7 4916.1 4928.2 4948.8 4803.6 4921.5 4934.2 4959.2 4971.1 4817.9 4839.2 4852.8 4859.0 4888.3 4816.8 4847.9 4852.7 4854.7 4900.7 4570.0 4774.7 4790.3 4842.2 4848.4 4869.9 4881.9 4908.2 4908.7 4943.8 4634.3 4800.4 4822.3 4834.1 4875.6 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#1] ext.html Cycles:180 Average:54241.84 Median:36520.45 stddev:120745.04 (330.6%) stddev-sans-first:121016.49 02:18:06 INFO - PROCESS | 8961 | Values: 558.2 600.5 614.3 615.4 619.8 36692.6 40180.4 40585.2 40648.2 40654.0 40246.8 40618.8 41241.8 41344.2 41399.0 7769.3 7879.5 7898.2 7899.0 7941.1 7882.2 7905.1 7940.2 7956.9 7992.7 7976.3 8006.1 8014.7 8015.8 8018.2 7900.1 7927.0 7944.0 7962.3 7986.8 7867.6 7879.0 7891.9 7922.0 7935.2 30717.0 38180.6 38541.6 38595.6 38710.4 3145.7 3209.0 3332.7 3446.9 3612.0 28501.7 28865.3 28962.6 29022.9 29030.7 3280.1 3288.9 3289.9 3314.6 3356.3 216584.0 216588.0 217157.0 217408.0 217697.0 33876.8 33880.5 33949.8 33954.7 33978.5 33410.5 35809.6 36252.1 36348.3 37991.2 15079.9 15322.4 15328.7 15348.3 15355.8 12025.9 12046.0 12080.6 12121.0 12166.2 19266.1 19641.0 19661.3 19784.9 19817.5 7301.0 7338.4 7448.4 7481.9 7504.8 901.7 966.9 991.6 1003.4 1005.4 27824.9 28666.4 28810.1 29175.4 29293.6 1038.9 1056.8 1059.4 1088.4 1101.9 15348.0 15483.8 15675.5 15689.8 16059.8 368248.0 706654.0 723251.0 723280.0 723392.0 52571.0 53107.7 53124.5 53386.0 53412.5 1303.9 1317.1 1331.7 1333.0 1334.8 28098.4 28825.9 29505.7 29645.9 29694.7 44072.3 44289.3 44359.0 44545.6 44832.8 336561.0 338026.0 338580.0 338886.0 338966.0 26113.9 26556.7 26750.3 43274.0 44621.3 42832.6 45258.3 45940.5 46082.5 46143.5 38956.8 39037.2 39165.6 39173.2 39248.0 38080.0 38099.0 38379.4 38753.8 38763.4 34172.3 38141.8 38666.4 39173.4 39927.2 33524.3 35284.3 35816.1 36209.0 36312.6 26381.7 26415.2 26479.1 26481.6 26798.0 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#2] jquery.html Cycles:180 Average:23049.66 Median:27418.62 stddev:57415.12 (209.4%) stddev-sans-first:57550.96 02:18:06 INFO - PROCESS | 8961 | Values: 379.6 380.7 382.4 387.8 393.8 16047.7 17242.3 17414.5 17950.9 17956.0 16229.4 16499.4 16726.6 16768.3 16798.6 4630.6 4843.2 4854.7 4857.7 4874.5 3501.1 5173.3 5477.2 5478.3 5549.3 5361.5 5539.1 5545.9 5558.8 5559.4 5471.1 5475.4 5486.6 5496.3 5517.8 5175.5 5281.5 5323.6 5326.9 5358.6 18158.7 19408.0 20524.9 20850.2 20891.6 1873.8 1967.1 2005.9 2052.2 2105.2 9279.4 9428.3 9561.7 11449.4 12479.9 1707.5 1781.5 1808.1 1821.0 1869.1 67770.7 68159.7 68806.7 69681.0 69698.7 9389.6 9615.0 9747.0 9766.3 9783.0 13125.9 13256.8 13261.7 13289.0 13289.3 7001.6 7016.6 7155.5 7203.7 7308.0 6885.7 6902.1 6932.8 7068.6 7071.4 4075.7 4105.7 4129.9 4171.4 4233.5 1629.8 1650.5 1774.1 1780.2 1791.2 897.7 913.6 915.7 1002.5 1021.6 16424.0 16604.8 16921.4 16923.6 17053.5 894.5 905.0 930.0 935.5 965.4 9371.3 9683.9 9751.0 9865.5 9922.7 305817.0 306201.0 308391.0 393223.0 394545.0 80886.6 84837.2 85341.0 86773.1 87125.6 955.8 960.4 984.8 991.3 994.9 15331.6 15480.0 15520.4 15556.7 16019.0 34644.0 34924.8 35519.9 35823.0 36171.5 47681.0 49962.8 50048.2 50879.0 51401.7 7192.5 7588.7 7752.9 7764.2 7768.5 9552.0 11128.8 11319.7 11438.2 11534.4 9505.5 9507.3 9694.7 9811.7 9850.4 9538.2 9960.5 10023.4 10229.0 10443.1 9751.3 10037.0 10136.5 10200.8 10275.5 7706.4 7762.9 7813.4 7844.6 7871.4 9632.5 10097.0 10356.3 10554.3 10573.3 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#3] mootools.html Cycles:180 Average:5090.28 Median:5320.32 stddev:2742.34 (51.5%) stddev-sans-first:2736.96 02:18:06 INFO - PROCESS | 8961 | Values: 1526.1 1540.8 1734.2 1843.6 1875.6 6701.1 7048.7 7402.8 7577.8 7629.8 7660.0 7667.7 7726.5 7728.3 7743.4 2223.2 2261.3 2263.0 2264.1 2265.2 2186.6 2220.9 2251.4 2255.4 2259.4 2227.0 2228.6 2233.7 2238.0 2250.3 2161.7 2169.3 2182.1 2186.1 2186.5 2165.0 2165.2 2167.6 2175.7 2175.9 7554.7 7681.5 7684.7 7687.7 7692.3 4546.6 4671.4 4672.8 4675.0 4706.2 2800.5 2937.0 2956.1 2983.2 2992.8 942.1 1009.3 1014.1 1016.0 1023.1 10527.4 10539.3 10575.8 10579.6 10600.5 4272.4 4335.3 4407.7 4415.8 4439.3 8887.5 9032.9 9035.0 9063.3 9114.3 4613.9 4670.9 4700.7 4720.9 4724.2 3737.7 4125.8 4250.0 4267.3 4282.7 3673.4 3692.5 3773.9 3789.2 3900.4 1196.1 1251.7 1252.5 1256.7 1259.2 816.4 840.0 848.4 938.8 968.1 5297.4 5314.1 5373.1 5447.5 5465.6 930.5 942.2 951.6 952.2 952.8 3674.2 3742.6 3747.7 3747.7 3747.9 8915.6 8945.3 9074.9 9198.4 9241.2 9004.1 9059.6 9097.7 9137.7 9161.5 7058.4 7099.9 7135.8 7137.4 7246.6 5326.5 5351.8 5393.3 5422.3 5424.2 8287.1 8436.9 8581.7 8700.1 8733.7 8555.4 8848.6 8856.5 8937.1 9058.6 5909.9 6221.8 6472.8 6697.2 6857.4 7278.9 7330.6 7331.1 7350.7 7360.3 6475.3 6668.9 6681.6 6740.0 6813.3 6600.5 6622.2 6661.4 6708.3 6741.1 6104.1 6110.3 6200.9 6209.9 6216.3 4522.5 4561.0 4577.5 4590.0 4662.1 4415.4 4463.2 4601.0 4687.6 4696.2 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#4] prototype.html Cycles:180 Average:4256.96 Median:4873.92 stddev:1226.63 (25.2%) stddev-sans-first:1193.86 02:18:06 INFO - PROCESS | 8961 | Values: 315.1 318.9 319.7 321.2 321.3 4949.8 4978.7 4979.9 5006.7 5022.3 4837.7 4856.0 4871.4 4884.9 4910.7 3667.5 3763.2 3862.8 3863.6 3880.7 3854.9 3857.2 3881.5 3883.9 3885.1 3827.4 3849.5 3853.7 3863.5 3871.4 3758.5 3772.7 3862.0 3865.5 3871.1 3387.0 3387.2 3388.6 3403.6 3418.2 4622.8 4628.4 4641.6 4664.4 4677.0 4419.4 4423.9 4429.4 4431.8 4447.6 4885.7 4973.4 5001.4 5015.8 5059.2 3705.1 3721.0 3729.5 3731.0 3747.8 5302.2 5305.2 5306.5 5309.8 5347.7 3706.6 3756.6 3767.2 3799.1 3820.8 3827.6 3852.8 3859.4 3863.6 3873.2 4334.2 4361.6 4363.4 4371.0 4377.7 4960.4 4985.6 5032.9 5103.5 5106.5 1287.5 1294.4 1303.5 1303.6 1312.6 846.3 855.8 883.3 892.9 903.5 4358.0 4507.2 4515.7 4533.3 4534.3 5276.8 5280.6 5286.6 5287.6 5298.9 4438.7 4518.8 4531.2 4536.5 4543.8 2644.7 2756.6 2761.7 2765.0 2765.0 4776.8 4820.3 4833.5 4876.4 4911.3 5043.5 5219.8 5238.4 5245.0 5260.8 5008.3 5013.9 5018.5 5018.9 5022.5 5313.4 5328.3 5328.4 5337.9 5342.7 4954.4 5202.7 5248.1 5255.3 5277.8 4948.8 4967.8 4970.8 4975.7 4981.5 3691.0 3714.3 3715.7 3717.3 3731.6 4994.4 5291.4 5328.3 5330.7 5365.5 5118.4 5129.6 5149.7 5150.2 5179.9 5100.0 5134.7 5180.8 5187.6 5195.5 4954.9 5023.6 5041.4 5050.7 5063.8 4901.4 4983.0 5113.7 5140.7 5160.9 5027.1 5047.3 5072.3 5083.6 5090.5 02:18:06 INFO - PROCESS | 8961 | 02:18:06 INFO - PROCESS | 8961 | [#5] yui.html Cycles:180 Average:12757.77 Median:21990.99 stddev:14026.21 (63.8%) stddev-sans-first:14036.41 02:18:06 INFO - PROCESS | 8961 | Values: 718.8 741.2 779.8 790.5 823.0 11477.8 12042.2 12151.9 12247.5 12266.9 12473.3 12493.3 13044.0 13398.4 13403.3 878.4 886.0 886.0 887.2 888.3 684.3 841.5 867.7 871.4 877.0 925.9 927.5 928.8 932.6 935.9 905.5 910.9 912.3 915.3 917.0 673.7 674.8 676.4 678.5 681.4 12742.5 12794.7 12795.7 12798.9 12800.6 12151.3 12479.9 12511.3 12531.1 12901.7 10171.7 10209.8 10352.3 10470.3 10580.9 3330.5 3343.6 3359.3 3372.3 3445.5 43846.8 44309.8 44763.6 45133.5 45225.8 9689.4 9756.7 9762.5 9892.8 10014.4 17872.3 17967.3 18131.4 18188.5 18225.5 6956.2 7033.7 7066.6 7105.6 7144.3 3760.5 3835.2 3888.9 3927.3 4002.4 2892.9 2952.7 2987.0 2992.0 2999.4 514.7 518.8 520.1 526.4 534.7 552.6 600.0 623.9 633.7 661.7 7675.1 7913.1 7945.4 8004.6 8005.2 580.7 605.8 638.3 641.3 668.8 3604.3 3755.9 3765.8 3769.4 3772.6 50843.7 50864.5 51046.2 51157.0 51916.5 45256.1 45596.8 46031.8 46194.8 46276.0 39075.2 39750.6 39979.6 42850.6 43012.8 8323.9 8363.4 8409.1 8512.8 8629.3 28954.7 29516.0 29789.0 29926.3 29951.0 36232.8 36950.8 36956.6 37121.4 37301.8 10471.7 10886.9 10927.5 11132.1 11266.5 11675.5 11810.6 12049.3 12546.2 12652.4 11196.6 11418.3 11682.2 11794.9 12410.6 11735.7 12014.7 12168.5 12186.3 12577.1 10240.0 11395.9 11486.0 11745.0 11844.2 9078.5 9273.8 9293.6 9309.0 9439.3 9233.8 9968.4 10004.9 10061.0 10112.9 02:18:06 INFO - PROCESS | 8961 | -------- Summary: end -------- 02:18:06 INFO - PROCESS | 8961 | 02:18:07 INFO - TEST-INFO | 8961: exit 0 02:18:07 INFO - TEST-OK | dromaeo_css | took 1114683ms 02:18:07 INFO - TEST-START | kraken 02:18:07 INFO - operating with platform_type : linux_ 02:18:07 INFO - Initialising browser for kraken test... 02:18:07 INFO - TEST-INFO | started process 9199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUBNY3T/profile http://localhost:60961/getInfo.html) 02:18:11 INFO - PROCESS | 9199 | 02:18:11 INFO - PROCESS | 9199 | (process:9266): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:18:12 INFO - PROCESS | 9199 | __metrics Screen width/height:1600/1200 02:18:12 INFO - PROCESS | 9199 | colorDepth:24 02:18:12 INFO - PROCESS | 9199 | Browser inner width/height: 1024/697 02:18:12 INFO - PROCESS | 9199 | __metrics 02:18:12 ERROR - PROCESS | 9199 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 02:18:13 INFO - TEST-INFO | 9199: exit 0 02:18:13 INFO - Browser initialized. 02:18:13 INFO - Running cycle 1/1 for kraken test... 02:18:13 INFO - TEST-INFO | started process 9317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUBNY3T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:18:15 INFO - PROCESS | 9317 | 02:18:15 INFO - PROCESS | 9317 | (process:9376): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:18:25 INFO - PROCESS | 9317 | RSS: Main: 164311040 02:18:25 INFO - PROCESS | 9317 | 02:18:28 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60961/tests/kraken/driver.html?testName=audio-beat-detection) 02:18:29 INFO - PROCESS | 9317 | RSS: Main: 167854080 02:18:29 INFO - PROCESS | 9317 | 02:18:31 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60961/tests/kraken/driver.html?testName=audio-dft) 02:18:32 INFO - PROCESS | 9317 | RSS: Main: 168947712 02:18:32 INFO - PROCESS | 9317 | 02:18:35 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60961/tests/kraken/driver.html?testName=audio-fft) 02:18:36 INFO - PROCESS | 9317 | RSS: Main: 163254272 02:18:36 INFO - PROCESS | 9317 | 02:18:38 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60961/tests/kraken/driver.html?testName=audio-oscillator) 02:18:38 INFO - PROCESS | 9317 | RSS: Main: 163512320 02:18:38 INFO - PROCESS | 9317 | 02:18:41 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60961/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:18:41 INFO - PROCESS | 9317 | RSS: Main: 163692544 02:18:41 INFO - PROCESS | 9317 | 02:18:45 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60961/tests/kraken/driver.html?testName=imaging-darkroom) 02:18:45 INFO - PROCESS | 9317 | RSS: Main: 164319232 02:18:45 INFO - PROCESS | 9317 | 02:18:49 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60961/tests/kraken/driver.html?testName=imaging-desaturate) 02:18:49 INFO - PROCESS | 9317 | RSS: Main: 163364864 02:18:49 INFO - PROCESS | 9317 | 02:18:52 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60961/tests/kraken/driver.html?testName=json-parse-financial) 02:18:53 INFO - PROCESS | 9317 | RSS: Main: 163262464 02:18:53 INFO - PROCESS | 9317 | 02:18:55 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60961/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:18:55 INFO - PROCESS | 9317 | RSS: Main: 163467264 02:18:55 INFO - PROCESS | 9317 | 02:18:57 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:18:58 INFO - PROCESS | 9317 | RSS: Main: 164016128 02:18:58 INFO - PROCESS | 9317 | 02:19:00 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:19:01 INFO - PROCESS | 9317 | RSS: Main: 164147200 02:19:01 INFO - PROCESS | 9317 | 02:19:03 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:19:04 INFO - PROCESS | 9317 | RSS: Main: 164225024 02:19:04 INFO - PROCESS | 9317 | 02:19:07 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:19:08 INFO - PROCESS | 9317 | RSS: Main: 164671488 02:19:08 INFO - PROCESS | 9317 | 02:19:09 INFO - PROCESS | 9317 | Cycle 1(1): loaded http://localhost:60961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60961/tests/kraken/driver.html?testName=ai-astar) 02:19:10 INFO - PROCESS | 9317 | RSS: Main: 165941248 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | __start_tp_report 02:19:10 INFO - PROCESS | 9317 | _x_x_mozilla_page_load 02:19:10 INFO - PROCESS | 9317 | _x_x_mozilla_page_load_details 02:19:10 INFO - PROCESS | 9317 | |i|pagename|runs| 02:19:10 INFO - PROCESS | 9317 | |0;ai-astar;118;115;107;113;114;113;109;114;115;112 02:19:10 INFO - PROCESS | 9317 | |1;audio-beat-detection;160;132;163;185;132;156;162;138;200;133 02:19:10 INFO - PROCESS | 9317 | |2;audio-dft;161;161;165;166;169;162;162;163;164;162 02:19:10 INFO - PROCESS | 9317 | |3;audio-fft;77;78;76;152;77;77;76;77;179;78 02:19:10 INFO - PROCESS | 9317 | |4;audio-oscillator;94;92;144;94;93;93;144;93;94;152 02:19:10 INFO - PROCESS | 9317 | |5;imaging-gaussian-blur;118;118;118;117;118;118;118;118;118;118 02:19:10 INFO - PROCESS | 9317 | |6;imaging-darkroom;146;147;148;147;147;148;148;147;147;153 02:19:10 INFO - PROCESS | 9317 | |7;imaging-desaturate;95;95;97;95;95;96;96;96;96;96 02:19:10 INFO - PROCESS | 9317 | |8;json-parse-financial;70;68;70;69;69;71;68;87;67;67 02:19:10 INFO - PROCESS | 9317 | |9;json-stringify-tinderbox;64;64;64;64;64;64;63;64;64;65 02:19:10 INFO - PROCESS | 9317 | |10;stanford-crypto-aes;83;83;83;83;87;84;92;82;86;89 02:19:10 INFO - PROCESS | 9317 | |11;stanford-crypto-ccm;136;134;135;135;143;133;133;155;211;139 02:19:10 INFO - PROCESS | 9317 | |12;stanford-crypto-pbkdf2;174;174;173;172;174;174;179;179;176;173 02:19:10 INFO - PROCESS | 9317 | |13;stanford-crypto-sha256-iterative;73;70;70;67;69;69;68;73;69;67 02:19:10 INFO - PROCESS | 9317 | __end_tp_report 02:19:10 INFO - PROCESS | 9317 | __start_cc_report 02:19:10 INFO - PROCESS | 9317 | _x_x_mozilla_cycle_collect,957 02:19:10 INFO - PROCESS | 9317 | __end_cc_report 02:19:10 INFO - PROCESS | 9317 | __startTimestamp1462612750140__endTimestamp 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | ------- Summary: start ------- 02:19:10 INFO - PROCESS | 9317 | Number of tests: 14 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.13 (2.7%) stddev-sans-first:2.74 02:19:10 INFO - PROCESS | 9317 | Values: 118.0 115.0 107.0 113.0 114.0 113.0 109.0 114.0 115.0 112.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#1] audio-beat-detection Cycles:10 Average:156.10 Median:161.00 stddev:23.29 (14.5%) stddev-sans-first:24.66 02:19:10 INFO - PROCESS | 9317 | Values: 160.0 132.0 163.0 185.0 132.0 156.0 162.0 138.0 200.0 133.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.55 (1.6%) stddev-sans-first:2.54 02:19:10 INFO - PROCESS | 9317 | Values: 161.0 161.0 165.0 166.0 169.0 162.0 162.0 163.0 164.0 162.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#3] audio-fft Cycles:10 Average:94.70 Median:77.00 stddev:37.86 (49.2%) stddev-sans-first:39.61 02:19:10 INFO - PROCESS | 9317 | Values: 77.0 78.0 76.0 152.0 77.0 77.0 76.0 77.0 179.0 78.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#4] audio-oscillator Cycles:10 Average:109.30 Median:93.00 stddev:25.88 (27.8%) stddev-sans-first:26.86 02:19:10 INFO - PROCESS | 9317 | Values: 94.0 92.0 144.0 94.0 93.0 93.0 144.0 93.0 94.0 152.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 02:19:10 INFO - PROCESS | 9317 | Values: 118.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 118.0 118.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:147.50 stddev:1.93 (1.3%) stddev-sans-first:1.94 02:19:10 INFO - PROCESS | 9317 | Values: 146.0 147.0 148.0 147.0 147.0 148.0 148.0 147.0 147.0 153.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 02:19:10 INFO - PROCESS | 9317 | Values: 95.0 95.0 97.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#8] json-parse-financial Cycles:10 Average:70.60 Median:69.50 stddev:5.91 (8.5%) stddev-sans-first:6.26 02:19:10 INFO - PROCESS | 9317 | Values: 70.0 68.0 70.0 69.0 69.0 71.0 68.0 87.0 67.0 67.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 02:19:10 INFO - PROCESS | 9317 | Values: 64.0 64.0 64.0 64.0 64.0 64.0 63.0 64.0 64.0 65.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:3.26 (3.8%) stddev-sans-first:3.36 02:19:10 INFO - PROCESS | 9317 | Values: 83.0 83.0 83.0 83.0 87.0 84.0 92.0 82.0 86.0 89.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:137.50 stddev:24.00 (17.5%) stddev-sans-first:25.21 02:19:10 INFO - PROCESS | 9317 | Values: 136.0 134.0 135.0 135.0 143.0 133.0 133.0 155.0 211.0 139.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:174.00 stddev:2.44 (1.4%) stddev-sans-first:2.57 02:19:10 INFO - PROCESS | 9317 | Values: 174.0 174.0 173.0 172.0 174.0 174.0 179.0 179.0 176.0 173.0 02:19:10 INFO - PROCESS | 9317 | 02:19:10 INFO - PROCESS | 9317 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:2.12 (3.1%) stddev-sans-first:1.83 02:19:10 INFO - PROCESS | 9317 | Values: 73.0 70.0 70.0 67.0 69.0 69.0 68.0 73.0 69.0 67.0 02:19:10 INFO - PROCESS | 9317 | -------- Summary: end -------- 02:19:10 INFO - PROCESS | 9317 | 02:19:11 INFO - TEST-INFO | 9317: exit 0 02:19:11 INFO - TEST-OK | kraken | took 63916ms 02:19:11 INFO - SUITE-END | took 1178s 02:19:11 INFO - Completed test suite (00:19:38) 02:19:11 INFO - javascript benchmark 02:19:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2582.7675509204173, 2631.7631413172817, 2646.3516438472575, 2650.2446802661234, 2659.135780871216, 4621.353010578522, 4630.398048253726, 4661.277501987197, 4696.272077827942, 4698.107359601678, 4534.705244159128, 4567.36056952027, 4576.97711511442, 4597.931031034537, 4610.815567377301, 4166.500019997597, 4274.252005898955, 4322.956770432303, 4331.675124365669, 4377.6935614507, 19.403411044308545, 4067.5098265676206, 4155.794819502341, 4301.569843015689, 4323.265044942367, 4208.032152604903, 4221.113566151112, 4223.408722778798, 4289.356596510517, 4335.631471324941, 4249.723767955073, 4270.765107919063, 4271.380649805761, 4300.78496075195, 4342.804573794187, 4114.650254728352, 4192.098698779747, 4255.680823938217, 4256.212600668886, 4268.039691069484, 4502.032063106448, 4540.931886021713, 4550.908981820345, 4551.044280701057, 4610.976945115286, 4925.802967881281, 4964.478729733398, 4977.850664480072, 4982.626303027288, 5012.172991456406, 4847.0, 4959.181735013718, 4979.327790748239, 5002.774875130628, 5035.269885866565, 4682.648801339913, 4695.131400690849, 4699.271612900006, 4706.458757242961, 4744.406949131393, 4915.705057696549, 5127.282180494733, 5133.281340612317, 5137.948620513747, 5176.974115129476, 4778.928316075262, 4810.927836082462, 4822.903541929142, 4843.249296358999, 4845.491223421561, 4908.067467181224, 4937.259411088366, 4938.753062346868, 4938.851834444972, 4987.825426110068, 4807.591354734886, 4845.3216549683075, 4880.341153944243, 4890.168671325883, 4954.455009948902, 4850.587700045536, 4854.441739200037, 4876.5854902332985, 4889.63327750413, 4938.209886418156, 4243.448351714257, 4249.5113061997645, 4253.957460425418, 4266.978665106654, 4308.310670292801, 3654.9948764090086, 3698.4637227601847, 3712.090537818252, 3723.7579557328686, 3772.302124107049, 4225.0, 4258.957410425856, 4267.317229243306, 4308.353746937984, 4364.388985542027, 5025.195968644999, 5039.370078740158, 5039.748012599355, 5045.495450454999, 5080.085584594808, 4253.914921701548, 4301.505326887385, 4322.935155972663, 4333.8699839004885, 4380.7152535085115, 4534.659900507475, 4563.703359281636, 4580.198465268565, 4620.607248383858, 4624.398828152319, 4722.173619616553, 4743.952560474351, 4745.928811067905, 4752.14461396945, 4806.8798280043275, 4830.637702172281, 4903.77932993009, 4911.754412279301, 5051.040302342543, 5052.0, 4639.837605683785, 4650.93023604653, 4706.294055891643, 4707.270373092176, 4707.81168753246, 4880.365552478155, 4911.7298548580175, 4916.0905232532095, 4928.162212423825, 4948.777305021355, 4803.5916947059895, 4921.50784921505, 4934.235193545006, 4959.181735013683, 4971.105201063843, 4817.927731083966, 4839.1531481991215, 4852.8301509447, 4858.975705121452, 4888.340074090094, 4816.759162041954, 4847.903041939212, 4852.733099679552, 4854.68444551103, 4900.705957642553, 4570.0, 4774.737389443614, 4790.329353890388, 4842.176829938849, 4848.393950756155, 4869.9026019480125, 4881.926771098508, 4908.214685650279, 4908.656394052382, 4943.826966056171, 4634.32802243666, 4800.35195248651, 4822.252550854623, 4834.105690447279, 4875.585575226146], "value": 4532.460521930439, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.1516095534787, 600.5255847880175, 614.259816920611, 615.4368752591379, 619.8326451857996, 36692.633073669254, 40180.39729404061, 40585.18829623406, 40648.18703625933, 40654.0, 40246.7987660062, 40618.7969060155, 41241.793791031, 41344.17311653765, 41399.0, 7769.300762931349, 7879.527228366288, 7898.2101789820845, 7898.960505197466, 7941.08677502086, 7882.172371900954, 7905.090914544815, 7940.166282540336, 7956.880646790303, 7992.720254791084, 7976.322012628933, 8006.079300880415, 8014.67941282348, 8015.759527214163, 8018.158093400197, 7900.130985591581, 7926.960365198166, 7944.0, 7962.323202527792, 7986.840263194733, 7867.567283799389, 7879.0, 7891.881621775678, 7921.960390198012, 7935.2064793520185, 30717.0, 38180.61819381799, 38541.61458385408, 38595.614043859765, 38710.419343709866, 3145.7126260444775, 3208.96791032089, 3332.6667333266723, 3446.9310613787834, 3611.9999999999736, 28501.714982850113, 28865.278368040756, 28962.565561516592, 29022.854885725646, 29030.709692903223, 3280.065181423283, 3288.8817801947453, 3289.901302960915, 3314.552535407713, 3356.3119560490286, 216584.0, 216588.0, 217157.0, 217408.0, 217697.0, 33876.83061584676, 33880.49179262313, 33949.83025084859, 33954.66045339515, 33978.49032264518, 33410.49884251738, 35809.64190358115, 36252.09369765777, 36348.27303453916, 37991.24017519634, 15079.924600377148, 15322.387104515918, 15328.693426131415, 15348.309326080353, 15355.846441535665, 12025.87974120265, 12046.0, 12080.637580872588, 12121.0, 12166.209196402382, 19266.068722204447, 19641.017949102486, 19661.311854085037, 19784.90107549453, 19817.504562386057, 7301.0, 7338.4496162788, 7448.366888814403, 7481.9251807482315, 7504.812379690442, 901.6753968571309, 966.8598053282235, 991.5934466868694, 1003.3779056984716, 1005.4470041477158, 27824.860875695493, 28666.426671466455, 28810.135695929155, 29175.416491670047, 29293.56059659107, 1038.9220808439397, 1056.8309070548828, 1059.4067322299536, 1088.3905013192636, 1101.9228653994303, 15348.0, 15483.845161548466, 15675.529734107995, 15689.843101569066, 16059.759103613454, 368248.0, 706654.0, 723251.0, 723280.0, 723392.0, 52571.0, 53107.73446132822, 53124.46875531272, 53386.0, 53412.46587534153, 1303.89568834493, 1317.0714646174615, 1331.6803967047838, 1333.0335506757717, 1334.759743246225, 28098.438031239668, 28825.855870720512, 29505.704942951153, 29645.851770741872, 29694.703052969195, 44072.33891491566, 44289.33565996577, 44359.0, 44545.55454445544, 44832.775836120614, 336561.0, 338026.0, 338580.0, 338886.0, 338966.0, 26113.869430653485, 26556.734432656198, 26750.331241719112, 43274.0, 44621.33068003917, 42832.57167428286, 45258.32112518249, 45940.54059459363, 46082.539174607824, 46143.538564615264, 38956.80521597374, 39037.21925561416, 39165.608343916196, 39173.2165356697, 39248.0, 38080.0, 38099.0, 38379.42430863595, 38753.806230968665, 38763.41854872123, 34172.31655366928, 38141.80929095448, 38666.42000369941, 39173.41239881461, 39927.2014559713, 33524.32951340911, 35284.294314114086, 35816.104597385274, 36209.0, 36312.63687363092, 26381.736182637927, 26415.20754377372, 26479.07323243677, 26481.60277595876, 26798.0], "value": 18123.548141382278, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.5729803970533, 380.6669164481079, 382.37481717392063, 387.7615266611032, 393.836557828501, 16047.67904641908, 17242.310307587682, 17414.477565673034, 17950.910245448755, 17956.0, 16229.350825966947, 16499.422520211763, 16726.58183545409, 16768.32926682931, 16798.58003549912, 4630.629549636021, 4843.200871856139, 4854.684445511049, 4857.684250523705, 4874.488178741234, 3501.0897166736704, 5173.3016042834115, 5477.20580515827, 5478.342598888119, 5549.306336707911, 5361.463853614647, 5539.086050801613, 5545.889082218353, 5558.777648894039, 5559.444055594428, 5471.124620060771, 5475.425080366544, 5486.6159368844, 5496.31296087989, 5517.779288828442, 5175.45657705943, 5281.498257665554, 5323.627346085776, 5326.866828329323, 5358.571314294847, 18158.727619085723, 19408.0, 20524.89737551303, 20850.165993360246, 20891.58216835655, 1873.7938826729048, 1967.1147983407525, 2005.9097340619708, 2052.1688716070016, 2105.210546045233, 9279.443233406017, 9428.292878034175, 9561.713148605553, 11449.370284634342, 12479.875201247962, 1707.487753673893, 1781.536800431884, 1808.1140241281678, 1821.0, 1869.07480797005, 67770.66114669395, 68159.65920170417, 68806.65596671985, 69681.0, 69698.65150674214, 9389.577469013775, 9615.0, 9746.95126524377, 9766.267529935141, 9782.951085244527, 13125.934370328278, 13256.801147982787, 13261.73476530464, 13289.0, 13289.3355332233, 7001.614911179832, 7016.649167541603, 7155.534890232118, 7203.675834587456, 7308.04995350601, 6885.690143943534, 6902.102726645604, 6932.826679332956, 7068.611226382598, 7071.398931090915, 4075.694322925792, 4105.67154627629, 4129.876103716893, 4171.416001759756, 4233.491980962304, 1629.7881275434117, 1650.5460998225344, 1774.104077440884, 1780.1900135438345, 1791.229771198371, 897.6544030548156, 913.6345461815195, 915.7390143808982, 1002.5238011944414, 1021.5658345203259, 16424.0, 16604.83395166057, 16921.407750728664, 16923.576910577332, 17053.48839534816, 894.4812009034745, 904.9864252036225, 930.0095398400682, 935.5462600638684, 965.358036905464, 9371.297152713574, 9683.90316096844, 9751.0, 9865.506724663737, 9922.702318930444, 305817.0, 306201.0, 308391.0, 393223.0, 394545.0, 80886.5955670218, 84837.15162848416, 85341.0, 86773.1322686765, 87125.56437217773, 955.7897262602216, 960.4189465373488, 984.7685793838442, 991.3407583956588, 994.8955359687274, 15331.61670958235, 15480.0, 15520.37918483248, 15556.688866222386, 16019.038857668576, 34644.0, 34924.825375872955, 35519.934401967985, 35823.0, 36171.457428139125, 47681.0, 49962.75018625029, 50048.249276260154, 50879.0, 51401.7429912848, 7192.496525243183, 7588.658510366937, 7752.8837067445165, 7764.22357764219, 7768.53388796674, 9552.0, 11128.833067503832, 11319.717007074889, 11438.199326047097, 11534.423278836193, 9505.524723763645, 9507.334486585873, 9694.739683841055, 9811.656592019244, 9850.408975461496, 9538.23694104456, 9960.501974901372, 10023.448710321003, 10229.0, 10443.112335451571, 9751.317407781387, 10036.996300370196, 10136.493175341351, 10200.795984080129, 10275.537600807833, 7706.422018348759, 7762.922370776446, 7813.413993950363, 7844.607769611611, 7871.370290376871, 9632.518374081408, 10097.040781125781, 10356.326838755456, 10554.313969591953, 10573.2598718089], "value": 8187.38725821557, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1526.0538466150986, 1540.7919930809342, 1734.1935999760117, 1843.6036252205772, 1875.6401608833598, 6701.095352127461, 7048.682809273582, 7402.778541540652, 7577.7726668199975, 7629.8092547686265, 7659.961700191505, 7667.693292268303, 7726.459147859652, 7728.343090837271, 7743.380529557622, 2223.1774243529835, 2261.2876943762744, 2262.9773702262933, 2264.116994372196, 2265.150568536799, 2186.5517568898335, 2220.9000594973263, 2251.3808702606752, 2255.4248666589997, 2259.4464356232666, 2227.042371780134, 2228.6434170532716, 2233.70961774969, 2237.966430503544, 2250.2574150530368, 2161.6973623692697, 2169.2624507667388, 2182.0726191368685, 2186.0709198590534, 2186.5408264264443, 2165.036558731365, 2165.177232651598, 2167.5881582499273, 2175.6845257437585, 2175.8803265820375, 7554.735584254525, 7681.539107653559, 7684.731034413769, 7687.654055567513, 7692.307692307663, 4546.590806827368, 4671.439427268716, 4672.813087476497, 4675.041616468649, 4706.152892479352, 2800.467911096885, 2937.0, 2956.0688383159236, 2983.1647138801172, 2992.8204307741607, 942.1050002497655, 1009.328796350426, 1014.0518615094836, 1015.9695209143738, 1023.0894503891542, 10527.420991845445, 10539.262251642465, 10575.78848423035, 10579.57681692739, 10600.469976501145, 4272.401863739079, 4335.3063509838275, 4407.713498622579, 4415.801288942005, 4439.334099885043, 8887.511186884654, 9032.909670903207, 9034.954825225963, 9063.274938005075, 9114.316426268057, 4613.884652883705, 4670.906581868411, 4700.741459219776, 4720.881977950578, 4724.196886529298, 3737.7383583149463, 4125.773082480492, 4250.043740158501, 4267.253230684618, 4282.678799090081, 3673.430618254175, 3692.538432695913, 3773.8867833965023, 3789.1663833956486, 3900.3759398496672, 1196.096946805174, 1251.6620512461584, 1252.4551819958165, 1256.7486502699496, 1259.175240217659, 816.3795515408333, 839.9790005249918, 848.3806821020689, 938.7840796616816, 968.0561452583675, 5297.443768404339, 5314.12316967696, 5373.086575282211, 5447.53695935842, 5465.617406781487, 930.5254320296563, 942.2085448223388, 951.619352259088, 952.1620973543238, 952.8237276103943, 3674.2284120334493, 3742.5883152853166, 3747.718921080875, 3747.737658363943, 3747.9437808432895, 8915.55422228886, 8945.284377249935, 9074.909250907538, 9198.356115072014, 9241.168294853496, 9004.054574269738, 9059.637614495477, 9097.681581144629, 9137.680181193626, 9161.496117713456, 7058.364747172779, 7099.89350159748, 7135.785926422215, 7137.357637812622, 7246.637668116573, 5326.520613144836, 5351.759170837244, 5393.325834270716, 5422.349318081855, 5424.2134890439875, 8287.12985136552, 8436.915630843612, 8581.699640512552, 8700.086490918362, 8733.65065397377, 8555.401121921655, 8848.646054157762, 8856.512891791013, 8937.106289371271, 9058.637654493747, 5909.85225369352, 6221.782237621662, 6472.773452929125, 6697.196336439659, 6857.417119544695, 7278.854422911618, 7330.5968171751065, 7331.120265568166, 7350.705971761284, 7360.33756961876, 6475.287718351072, 6668.933310666831, 6681.632510211791, 6740.0, 6813.2846051163915, 6600.471962242913, 6622.238442579165, 6661.400473957173, 6708.329167083252, 6741.1236539249585, 6104.11490333908, 6110.297315808739, 6200.875982480416, 6209.875802484015, 6216.2540495139065, 4522.502524722344, 4560.954390456053, 4577.496475387771, 4589.977050114728, 4662.114198302312, 4415.448068991376, 4463.196624607472, 4600.976995115003, 4687.648426367968, 4696.2486002239475], "value": 4174.638081556421, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.09567541156883, 318.93940151371237, 319.70906475107654, 321.1809884793775, 321.3492677328411, 4949.826756063534, 4978.726170060645, 4979.925301120486, 5006.699598024121, 5022.3219865318215, 4837.685550439219, 4856.0, 4871.415430148379, 4884.853454396365, 4910.656254062217, 3667.468217108518, 3763.2097259575385, 3862.8454861805494, 3863.613638636128, 3880.7477513961644, 3854.8843534694006, 3857.24783667185, 3881.514810648669, 3883.9029024274478, 3885.1064255221177, 3827.3684842001035, 3849.538055433352, 3853.7109716771356, 3863.517060367454, 3871.399933010374, 3758.4550240215017, 3772.717735570044, 3862.0, 3865.5168103987003, 3871.1483473635753, 3386.9661303386897, 3387.1532116970757, 3388.5933687957604, 3403.6256011838677, 3418.2479854431986, 4622.768861556909, 4628.421447319085, 4641.559051890065, 4664.416947881515, 4676.953230467686, 4419.4033805436175, 4423.889402764925, 4429.424174857248, 4431.822727090913, 4447.577480139382, 4885.65488565487, 4973.4280557735965, 5001.44984051754, 5015.824446144404, 5059.203982586166, 3705.110393699206, 3720.962790372089, 3729.533808273966, 3731.048582611417, 3747.7563958342894, 5302.2046692996355, 5305.204219367087, 5306.548943339821, 5309.761060752276, 5347.652402593819, 3706.5552133744122, 3756.6055564165918, 3767.210574976919, 3799.126200973736, 3820.815547180383, 3827.6363745444137, 3852.8266228019806, 3859.3824988001784, 3863.5750067492554, 3873.205992771475, 4334.21984042869, 4361.629261512743, 4363.432753742057, 4371.0, 4377.693561450668, 4960.404751429779, 4985.551300382983, 5032.899342013139, 5103.489651034941, 5106.540411362995, 1287.4978758284276, 1294.372229468707, 1303.4590644882267, 1303.648015035935, 1312.586535241396, 846.280661437773, 855.8074433252593, 883.3330835219368, 892.8883889513811, 903.4714691905177, 4357.956420435755, 4507.166174257773, 4515.706479078849, 4533.32000199966, 4534.274516077412, 5276.762545685453, 5280.551153152025, 5286.60350473716, 5287.576993840484, 5298.8940221196135, 4438.718339824569, 4518.751468669189, 4531.161735079021, 4536.500984891659, 4543.795529201194, 2644.722304158074, 2756.5803611140277, 2761.6962134165224, 2764.9723502764714, 2765.0, 4776.785044672998, 4820.3492528508905, 4833.516648335139, 4876.414830220397, 4911.287863259808, 5043.546080852668, 5219.8434046978655, 5238.449962753933, 5244.973775131176, 5260.842174734764, 5008.2738002988635, 5013.949860501421, 5018.548330650259, 5018.949810501848, 5022.488755622189, 5313.3889602694635, 5328.333958255218, 5328.3605967284175, 5337.866553336197, 5342.706151161723, 4954.430240522386, 5202.73986300691, 5248.107821670249, 5255.290535777621, 5277.788888444419, 4948.812285051634, 4967.82612608557, 4970.776315065758, 4975.676581022222, 4981.452040275639, 3690.9815450922574, 3714.275716235308, 3715.662361549786, 3717.275131349361, 3731.6268373163552, 4994.350734404649, 5291.391489978701, 5328.333958255218, 5330.706811125426, 5365.517103460707, 5118.360204974379, 5129.563987060992, 5149.665271757323, 5150.175971844487, 5179.92230116541, 5099.974500127476, 5134.666246693953, 5180.766865490987, 5187.58499320061, 5195.480451954774, 4954.934689041874, 5023.572996295356, 5041.395032596112, 5050.696958182521, 5063.8480845574695, 4901.411830580353, 4983.0, 5113.693178409308, 5140.7429628517675, 5160.870978225575, 5027.12025395547, 5047.268146118866, 5072.264521644414, 5083.593312534915, 5090.541851233407], "value": 3870.7788774736678, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.7879575525219, 741.2180149941812, 779.7972527142942, 790.4506368074187, 822.983540329193, 11477.827832582507, 12042.157049006562, 12151.939240303785, 12247.510099596017, 12266.938665306661, 12473.251604903711, 12493.250404975684, 13044.0, 13398.397072131753, 13403.262820544865, 878.4202426398579, 885.9734207973771, 885.9778505537381, 887.1527691055027, 888.2671795768484, 684.2985939412092, 841.5161282262693, 867.657275376226, 871.4117970370006, 876.951767652782, 925.9305552083587, 927.4945154890601, 928.7770934975591, 932.5849996751448, 935.8596210568401, 905.5110240470171, 910.8633704944245, 912.3066469483174, 915.276931224332, 916.9633214671405, 673.737242475435, 674.7773234832493, 676.3777324861139, 678.4809620640215, 681.4241965539161, 12742.490300387974, 12794.680132996657, 12795.680107997281, 12798.872011279862, 12800.61598152057, 12151.331676757774, 12479.875201247962, 12511.311877846714, 12531.122821402505, 12901.67745806353, 10171.745706357326, 10209.795804083951, 10352.275340726152, 10470.319429237075, 10580.947095264552, 3330.467125259971, 3343.5987681478136, 3359.3281343731105, 3372.3423932333203, 3445.4831775233665, 43846.780766096286, 44309.77845110786, 44763.552364476265, 45133.54866451326, 45225.773871130434, 9689.370190937567, 9756.658516951873, 9762.511874406251, 9892.80214395708, 10014.39913605186, 17872.28510859551, 17967.281308747504, 18131.36540221086, 18188.454346369632, 18225.45323640293, 6956.200036995709, 7033.683484243222, 7066.576005439587, 7105.609191494517, 7144.3212894774915, 3760.5487341519192, 3835.2137811748526, 3888.922221555553, 3927.312720273998, 4002.3796311571755, 2892.942141157165, 2952.660444048919, 2987.0292155049697, 2992.012635830171, 2999.4151140527388, 514.7194778845538, 518.7847043476914, 520.1002266079703, 526.431454029647, 534.6791924845062, 552.5962065017143, 599.9500873471425, 623.9220097487814, 633.6863252689948, 661.6856992928417, 7675.117361503387, 7913.089994650573, 7945.404094692921, 8004.599770011476, 8005.159458256916, 580.6632153351046, 605.8091701114136, 638.2755572425237, 641.2689533931322, 668.7893313606198, 3604.279144171124, 3755.887323380303, 3765.8305376258136, 3769.359208934488, 3772.641599048086, 50843.74578127086, 50864.49135508598, 51046.234306485436, 51157.0, 51916.48083519192, 45256.094878102915, 45596.77201614037, 46031.76984115058, 46194.76902615465, 46276.0, 39075.2184956305, 39750.60249397527, 39979.60020399817, 42850.57149428528, 43012.78493607512, 8323.916760832315, 8363.414560980673, 8409.117042710428, 8512.829743405222, 8629.26651234627, 28954.710452896044, 29516.0, 29789.0, 29926.25184370408, 29951.0, 36232.81883590565, 36950.81524592467, 36956.63043369532, 37121.443178352885, 37301.81349093237, 10471.738206544896, 10886.891131088905, 10927.45362731876, 11132.109431245643, 11266.493007814504, 11675.514018691589, 11810.586629467927, 12049.277043377426, 12546.247225166519, 12652.367381631066, 11196.608118715805, 11418.257813242113, 11682.182247242952, 11794.941025294818, 12410.5656302029, 11735.706607334885, 12014.699632509257, 12168.452419641315, 12186.329751863732, 12577.119601627799, 10240.0, 11395.943020285176, 11486.0, 11745.0, 11844.230125041844, 9078.546072696206, 9273.76815579589, 9293.628254869729, 9308.953455232951, 9439.292053096182, 9233.815323693354, 9968.352057116264, 10004.949975250078, 10061.0, 10112.898871011195], "value": 6103.155438145708, "unit": "score"}], "name": "dromaeo_css", "value": 6362.44649267193, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 107.0, 113.0, 114.0, 113.0, 109.0, 114.0, 115.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 132.0, 163.0, 185.0, 132.0, 156.0, 162.0, 138.0, 200.0, 133.0], "value": 156.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 165.0, 166.0, 169.0, 162.0, 162.0, 163.0, 164.0, 162.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 152.0, 77.0, 77.0, 76.0, 77.0, 179.0, 78.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 144.0, 94.0, 93.0, 93.0, 144.0, 93.0, 94.0, 152.0], "value": 109.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 147.0, 147.0, 148.0, 148.0, 147.0, 147.0, 153.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 69.0, 69.0, 71.0, 68.0, 87.0, 67.0, 67.0], "value": 70.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0, 65.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 87.0, 84.0, 92.0, 82.0, 86.0, 89.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 135.0, 135.0, 143.0, 133.0, 133.0, 155.0, 211.0, 139.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 173.0, 172.0, 174.0, 174.0, 179.0, 179.0, 176.0, 173.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 70.0, 67.0, 69.0, 69.0, 68.0, 73.0, 69.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1607.5, "alertThreshold": 2.0}]} 02:19:11 INFO - Return code: 0 02:19:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:19:11 ERROR - # TBPL SUCCESS # 02:19:11 INFO - Running post-action listener: _resource_record_post_action 02:19:11 INFO - ##### 02:19:11 INFO - ##### Finished run-tests step (success) 02:19:11 INFO - ##### 02:19:11 INFO - Running post-run listener: _resource_record_post_run 02:19:12 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 519090176; Read time: 12; Write time: 498944 02:19:12 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234848256; Read time: 0; Write time: 305840 02:19:12 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284241920; Read time: 12; Write time: 193104 02:19:12 INFO - Running post-run listener: _upload_blobber_files 02:19:12 INFO - Blob upload gear active. 02:19:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:19:12 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 02:19:12 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'] 02:19:12 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 02:19:12 INFO - (blobuploader) - INFO - Open directory for files ... 02:19:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:19:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:19:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:13 INFO - (blobuploader) - INFO - Done attempting. 02:19:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:19:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:19:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:14 INFO - (blobuploader) - INFO - Done attempting. 02:19:14 INFO - (blobuploader) - INFO - Iteration through files over. 02:19:14 INFO - Return code: 0 02:19:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:19:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:19:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/be1b44843c73c8bbf80b428590964dfb95a4ba05546e9acf7ae197f97586464ab6e9f07f5d4dec5450de4f3f6c44725ccedbd95e63fa80bd85ced6161375d643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fadc6e99ff4d71eafcbbf3cf369c36e13a3b4adfe5db48fa0f918463e755e8ca6058bbf996436208bdc7eb6d9fdab3bf1fa95fd156d6d006836e70aa8893b426"} 02:19:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:19:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:19:14 INFO - Contents: 02:19:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/be1b44843c73c8bbf80b428590964dfb95a4ba05546e9acf7ae197f97586464ab6e9f07f5d4dec5450de4f3f6c44725ccedbd95e63fa80bd85ced6161375d643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fadc6e99ff4d71eafcbbf3cf369c36e13a3b4adfe5db48fa0f918463e755e8ca6058bbf996436208bdc7eb6d9fdab3bf1fa95fd156d6d006836e70aa8893b426"} 02:19:14 INFO - Running post-run listener: copy_logs_to_upload_dir 02:19:14 INFO - Copying logs to upload dir... 02:19:14 INFO - mkdir: /builds/slave/test/build/upload/logs 02:19:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.676345 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-07 02:19:14.161893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 02:19:14.165875) ========= 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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 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/be1b44843c73c8bbf80b428590964dfb95a4ba05546e9acf7ae197f97586464ab6e9f07f5d4dec5450de4f3f6c44725ccedbd95e63fa80bd85ced6161375d643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fadc6e99ff4d71eafcbbf3cf369c36e13a3b4adfe5db48fa0f918463e755e8ca6058bbf996436208bdc7eb6d9fdab3bf1fa95fd156d6d006836e70aa8893b426"} build_url:https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/MHk5wQMuScCETDEV_MNd0A/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/be1b44843c73c8bbf80b428590964dfb95a4ba05546e9acf7ae197f97586464ab6e9f07f5d4dec5450de4f3f6c44725ccedbd95e63fa80bd85ced6161375d643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fadc6e99ff4d71eafcbbf3cf369c36e13a3b4adfe5db48fa0f918463e755e8ca6058bbf996436208bdc7eb6d9fdab3bf1fa95fd156d6d006836e70aa8893b426"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 02:19:14.191485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 02:19:14.192237) ========= 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=5bd038846534b2fdfa68eeae000001e2-1462610660.283804-1817979868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004508 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 02:19:14.274776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 02:19:14.275311) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 02:19:14.275831) ========= ========= Total master_lag: 0.19 =========