builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1463114132.88 results: success (0) buildid: 20160512205746 builduid: cb022b4a2d684398a99bc04e8700c9d9 revision: e03a91e03791b7767a02b918ce207609f552bab9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:32.884836) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:32.885221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:32.885644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:32.929174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:32.929433) ========= 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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 21:35: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% 40.5M=0s 2016-05-12 21:35:33 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115586 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.057583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.057861) ========= 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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019001 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.106859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.107164) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e03a91e03791b7767a02b918ce207609f552bab9 --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 e03a91e03791b7767a02b918ce207609f552bab9 --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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 21:35:33,617 truncating revision to first 12 chars 2016-05-12 21:35:33,617 Setting DEBUG logging. 2016-05-12 21:35:33,617 attempt 1/10 2016-05-12 21:35:33,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e03a91e03791?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 21:35:33,864 unpacking tar archive at: fx-team-e03a91e03791/testing/mozharness/ program finished with exit code 0 elapsedTime=0.500881 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.620206) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.620484) ========= script_repo_revision: e03a91e03791b7767a02b918ce207609f552bab9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.620963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.621227) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 21:35:33.629541) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-12 21:35:33.629826) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:35:34 INFO - MultiFileLogger online at 20160512 21:35:34 in /builds/slave/test 21:35:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 21:35:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:35:34 INFO - {'append_to_log': False, 21:35:34 INFO - 'base_work_dir': '/builds/slave/test', 21:35:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:35:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:35:34 INFO - 'branch': 'Fx-Team-Non-PGO', 21:35:34 INFO - 'buildbot_json_path': 'buildprops.json', 21:35:34 INFO - 'config_files': ('talos/linux_config.py',), 21:35:34 INFO - 'default_actions': ('clobber', 21:35:34 INFO - 'read-buildbot-config', 21:35:34 INFO - 'download-and-extract', 21:35:34 INFO - 'populate-webroot', 21:35:34 INFO - 'create-virtualenv', 21:35:34 INFO - 'install', 21:35:34 INFO - 'run-tests'), 21:35:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:35:34 INFO - 'download_minidump_stackwalk': True, 21:35:34 INFO - 'download_symbols': 'ondemand', 21:35:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:35:34 INFO - 'tooltool.py': '/tools/tooltool.py', 21:35:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:35:34 INFO - '/tools/misc-python/virtualenv.py')}, 21:35:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:35:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:35:34 INFO - 'installer_path': 'installer.exe', 21:35:34 INFO - 'log_level': 'info', 21:35:34 INFO - 'log_name': 'talos', 21:35:34 INFO - 'log_to_console': True, 21:35:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:35:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:35:34 INFO - 'opt_config_files': (), 21:35:34 INFO - 'pip_index': False, 21:35:34 INFO - 'sps_profile': False, 21:35:34 INFO - 'sps_profile_interval': 0, 21:35:34 INFO - 'suite': 'dromaeojs', 21:35:34 INFO - 'system_bits': '32', 21:35:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:35:34 INFO - 'title': 'talos-linux64-ix-059', 21:35:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:35:34 INFO - 'use_talos_json': True, 21:35:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:35:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:35:34 INFO - 'work_dir': 'build'} 21:35:34 INFO - ##### 21:35:34 INFO - ##### Running clobber step. 21:35:34 INFO - ##### 21:35:34 INFO - Running pre-action listener: _resource_record_pre_action 21:35:34 INFO - Running main action method: clobber 21:35:34 INFO - rmtree: /builds/slave/test/build 21:35:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:35:34 INFO - Running post-action listener: _resource_record_post_action 21:35:34 INFO - ##### 21:35:34 INFO - ##### Finished clobber step (success) 21:35:34 INFO - ##### 21:35:34 INFO - ##### 21:35:34 INFO - ##### Running read-buildbot-config step. 21:35:34 INFO - ##### 21:35:34 INFO - Running pre-action listener: _resource_record_pre_action 21:35:34 INFO - Running main action method: read_buildbot_config 21:35:34 INFO - Using buildbot properties: 21:35:34 INFO - { 21:35:34 INFO - "project": "", 21:35:34 INFO - "product": "firefox", 21:35:34 INFO - "script_repo_revision": "production", 21:35:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:35:34 INFO - "repository": "", 21:35:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:35:34 INFO - "buildid": "20160512205746", 21:35:34 INFO - "pgo_build": "False", 21:35:34 INFO - "basedir": "/builds/slave/test", 21:35:34 INFO - "buildnumber": 1679, 21:35:34 INFO - "slavename": "talos-linux64-ix-059", 21:35:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:35:34 INFO - "platform": "ubuntu64_hw", 21:35:34 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:35:34 INFO - "branch": "fx-team", 21:35:34 INFO - "repo_path": "integration/fx-team", 21:35:34 INFO - "revision": "e03a91e03791b7767a02b918ce207609f552bab9", 21:35:34 INFO - "stage_platform": "linux64", 21:35:34 INFO - "builduid": "cb022b4a2d684398a99bc04e8700c9d9", 21:35:34 INFO - "slavebuilddir": "test" 21:35:34 INFO - } 21:35:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:35:34 INFO - Running post-action listener: _resource_record_post_action 21:35:34 INFO - ##### 21:35:34 INFO - ##### Finished read-buildbot-config step (success) 21:35:34 INFO - ##### 21:35:34 INFO - ##### 21:35:34 INFO - ##### Running download-and-extract step. 21:35:34 INFO - ##### 21:35:34 INFO - Running pre-action listener: _resource_record_pre_action 21:35:34 INFO - Running main action method: download_and_extract 21:35:34 INFO - mkdir: /builds/slave/test/build/tests 21:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:34 INFO - https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:35:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:35:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:35:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:39 INFO - Downloaded 1361 bytes. 21:35:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:35:39 INFO - Using the following test package requirements: 21:35:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:35:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:35:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:35:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'jsshell-linux-x86_64.zip'], 21:35:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:35:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:35:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:35:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:35:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:35:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:35:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:35:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:35:39 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')]} 21:35:39 INFO - https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:35:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:35:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:35:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:56 INFO - Downloaded 22329650 bytes. 21:35:56 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'] 21:35:56 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 21:35:57 INFO - Return code: 0 21:35:57 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 21:35:57 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')]} 21:35:57 INFO - https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:35:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:35:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:35:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:35:59 INFO - Downloaded 22329650 bytes. 21:35:59 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'] 21:35:59 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 21:36:01 INFO - Return code: 0 21:36:01 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')]} 21:36:01 INFO - https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:36:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:36:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:36:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:36:02 INFO - Downloaded 10840239 bytes. 21:36:02 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'] 21:36:02 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 21:36:02 INFO - Return code: 0 21:36: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')]} 21:36:02 INFO - https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:36:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:36:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c8kr85fHQLmzhhHtQTf1gQ/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 21:36:04 INFO - Downloaded 54681272 bytes. 21:36:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:04 INFO - mkdir: /builds/slave/test/properties 21:36:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:36:04 INFO - Writing to file /builds/slave/test/properties/build_url 21:36:04 INFO - Contents: 21:36:04 INFO - build_url:https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:36:05 INFO - Running post-action listener: _resource_record_post_action 21:36:05 INFO - Running post-action listener: set_extra_try_arguments 21:36:05 INFO - ##### 21:36:05 INFO - ##### Finished download-and-extract step (success) 21:36:05 INFO - ##### 21:36:05 INFO - ##### 21:36:05 INFO - ##### Running populate-webroot step. 21:36:05 INFO - ##### 21:36:05 INFO - Running pre-action listener: _resource_record_pre_action 21:36:05 INFO - Running main action method: populate_webroot 21:36:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:36:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:36:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:36:05 INFO - '--tppagecycles', 21:36:05 INFO - '7'], 21:36:05 INFO - 'tests': ['tsvgm']}}, 21:36:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:36:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:36:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:36:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:36:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:36:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:36:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['damp', 'tps']}, 21:36:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'tests': ['damp', 'tps']}, 21:36:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['dromaeo_dom']}, 21:36:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:36:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'other-e10s': {'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['a11yr', 21:36:05 INFO - 'ts_paint', 21:36:05 INFO - 'tpaint', 21:36:05 INFO - 'sessionrestore', 21:36:05 INFO - 'sessionrestore_no_auto_restore', 21:36:05 INFO - 'tabpaint']}, 21:36:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['tsvgx', 21:36:05 INFO - 'tsvgr_opacity', 21:36:05 INFO - 'tart', 21:36:05 INFO - 'tscrollx', 21:36:05 INFO - 'cart']}, 21:36:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:36:05 INFO - 'tsvgr_opacity', 21:36:05 INFO - 'tart', 21:36:05 INFO - 'tscrollx', 21:36:05 INFO - 'cart']}, 21:36:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'talos_options': ['--disable-e10s'], 21:36:05 INFO - 'tests': ['tp5o']}, 21:36:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'tests': ['tp5o']}, 21:36:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'talos_options': ['--disable-e10s', 21:36:05 INFO - '--xperf_path', 21:36:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:36:05 INFO - 'tests': ['tp5n']}, 21:36:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:36:05 INFO - 'talos_options': ['--xperf_path', 21:36:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:36:05 INFO - 'tests': ['tp5n']}}, 21:36:05 INFO - 'talos.zip': {'path': '', 21:36:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:36:05 INFO - Running post-action listener: _resource_record_post_action 21:36:05 INFO - ##### 21:36:05 INFO - ##### Finished populate-webroot step (success) 21:36:05 INFO - ##### 21:36:05 INFO - ##### 21:36:05 INFO - ##### Running create-virtualenv step. 21:36:05 INFO - ##### 21:36:05 INFO - Running pre-action listener: _resource_record_pre_action 21:36:05 INFO - Running main action method: create_virtualenv 21:36:05 INFO - Creating virtualenv /builds/slave/test/build/venv 21:36:05 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 21:36:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:36:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:36:05 INFO - Using real prefix '/usr' 21:36:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:36:05 INFO - Installing distribute.............................................................................................................................................................................................done. 21:36:07 INFO - Installing pip.................done. 21:36:07 INFO - Return code: 0 21:36:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:36:07 INFO - Using env: {'DISPLAY': ':0', 21:36:07 INFO - 'HOME': '/home/cltbld', 21:36:07 INFO - 'LANG': 'en_US.UTF-8', 21:36:07 INFO - 'LANGUAGE': 'en_US:en', 21:36:07 INFO - 'LOGNAME': 'cltbld', 21:36:07 INFO - 'MAIL': '/var/mail/cltbld', 21:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:07 INFO - 'MOZ_NO_REMOTE': '1', 21:36:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:07 INFO - 'NO_EM_RESTART': '1', 21:36:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:07 INFO - 'PWD': '/builds/slave/test', 21:36:07 INFO - 'SHELL': '/bin/bash', 21:36:07 INFO - 'SHLVL': '1', 21:36:07 INFO - 'TERM': 'linux', 21:36:07 INFO - 'TMOUT': '86400', 21:36:07 INFO - 'USER': 'cltbld', 21:36:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:07 INFO - '_': '/tools/buildbot/bin/python'} 21:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:08 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 21:36:08 INFO - Cleaning up... 21:36:08 INFO - Return code: 0 21:36:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:36:08 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')]} 21:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:08 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')]} 21:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:08 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:08 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 21:36:08 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 21:36:08 INFO - Using env: {'DISPLAY': ':0', 21:36:08 INFO - 'HOME': '/home/cltbld', 21:36:08 INFO - 'LANG': 'en_US.UTF-8', 21:36:08 INFO - 'LANGUAGE': 'en_US:en', 21:36:08 INFO - 'LOGNAME': 'cltbld', 21:36:08 INFO - 'MAIL': '/var/mail/cltbld', 21:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:08 INFO - 'MOZ_NO_REMOTE': '1', 21:36:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:08 INFO - 'NO_EM_RESTART': '1', 21:36:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:08 INFO - 'PWD': '/builds/slave/test', 21:36:08 INFO - 'SHELL': '/bin/bash', 21:36:08 INFO - 'SHLVL': '1', 21:36:08 INFO - 'TERM': 'linux', 21:36:08 INFO - 'TMOUT': '86400', 21:36:08 INFO - 'USER': 'cltbld', 21:36:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:08 INFO - '_': '/tools/buildbot/bin/python'} 21:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:08 INFO - Downloading/unpacking psutil>=0.7.1 21:36:08 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 21:36:08 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 21:36:08 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 21:36:08 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 21:36:08 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 21:36:08 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 21:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:36:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:10 INFO - Installing collected packages: psutil 21:36:10 INFO - Running setup.py install for psutil 21:36:10 INFO - building 'psutil._psutil_linux' extension 21:36:10 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 21:36:10 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 21:36:10 INFO - building 'psutil._psutil_posix' extension 21:36:10 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 21:36:10 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 21:36:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:10 INFO - Successfully installed psutil 21:36:10 INFO - Cleaning up... 21:36:10 INFO - Return code: 0 21:36:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:36:10 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')]} 21:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:10 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')]} 21:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:10 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:10 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 21:36:10 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 21:36:10 INFO - Using env: {'DISPLAY': ':0', 21:36:10 INFO - 'HOME': '/home/cltbld', 21:36:10 INFO - 'LANG': 'en_US.UTF-8', 21:36:10 INFO - 'LANGUAGE': 'en_US:en', 21:36:10 INFO - 'LOGNAME': 'cltbld', 21:36:10 INFO - 'MAIL': '/var/mail/cltbld', 21:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:10 INFO - 'MOZ_NO_REMOTE': '1', 21:36:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:10 INFO - 'NO_EM_RESTART': '1', 21:36:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:10 INFO - 'PWD': '/builds/slave/test', 21:36:10 INFO - 'SHELL': '/bin/bash', 21:36:10 INFO - 'SHLVL': '1', 21:36:10 INFO - 'TERM': 'linux', 21:36:10 INFO - 'TMOUT': '86400', 21:36:10 INFO - 'USER': 'cltbld', 21:36:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:10 INFO - '_': '/tools/buildbot/bin/python'} 21:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36: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 21:36:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:36:12 INFO - Installing collected packages: mozsystemmonitor 21:36:12 INFO - Running setup.py install for mozsystemmonitor 21:36:13 INFO - Successfully installed mozsystemmonitor 21:36:13 INFO - Cleaning up... 21:36:13 INFO - Return code: 0 21:36:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:36:13 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')]} 21:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:13 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')]} 21:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:13 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:13 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 21:36:13 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 21:36:13 INFO - Using env: {'DISPLAY': ':0', 21:36:13 INFO - 'HOME': '/home/cltbld', 21:36:13 INFO - 'LANG': 'en_US.UTF-8', 21:36:13 INFO - 'LANGUAGE': 'en_US:en', 21:36:13 INFO - 'LOGNAME': 'cltbld', 21:36:13 INFO - 'MAIL': '/var/mail/cltbld', 21:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:13 INFO - 'MOZ_NO_REMOTE': '1', 21:36:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:13 INFO - 'NO_EM_RESTART': '1', 21:36:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:13 INFO - 'PWD': '/builds/slave/test', 21:36:13 INFO - 'SHELL': '/bin/bash', 21:36:13 INFO - 'SHLVL': '1', 21:36:13 INFO - 'TERM': 'linux', 21:36:13 INFO - 'TMOUT': '86400', 21:36:13 INFO - 'USER': 'cltbld', 21:36:13 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:13 INFO - '_': '/tools/buildbot/bin/python'} 21:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:13 INFO - Downloading/unpacking blobuploader==1.2.4 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:15 INFO - Downloading blobuploader-1.2.4.tar.gz 21:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:36:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:36:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 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 21:36:15 INFO - Downloading docopt-0.6.1.tar.gz 21:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:36:15 INFO - Installing collected packages: blobuploader, requests, docopt 21:36:15 INFO - Running setup.py install for blobuploader 21:36:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:36:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:36:16 INFO - Running setup.py install for requests 21:36:16 INFO - Running setup.py install for docopt 21:36:16 INFO - Successfully installed blobuploader requests docopt 21:36:16 INFO - Cleaning up... 21:36:16 INFO - Return code: 0 21:36:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:16 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:16 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 21:36:16 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 21:36:16 INFO - Using env: {'DISPLAY': ':0', 21:36:16 INFO - 'HOME': '/home/cltbld', 21:36:16 INFO - 'LANG': 'en_US.UTF-8', 21:36:16 INFO - 'LANGUAGE': 'en_US:en', 21:36:16 INFO - 'LOGNAME': 'cltbld', 21:36:16 INFO - 'MAIL': '/var/mail/cltbld', 21:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:16 INFO - 'MOZ_NO_REMOTE': '1', 21:36:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:16 INFO - 'NO_EM_RESTART': '1', 21:36:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:16 INFO - 'PWD': '/builds/slave/test', 21:36:16 INFO - 'SHELL': '/bin/bash', 21:36:16 INFO - 'SHLVL': '1', 21:36:16 INFO - 'TERM': 'linux', 21:36:16 INFO - 'TMOUT': '86400', 21:36:16 INFO - 'USER': 'cltbld', 21:36:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:16 INFO - '_': '/tools/buildbot/bin/python'} 21:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:16 INFO - Running setup.py (path:/tmp/pip-j6iofb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:16 INFO - Running setup.py (path:/tmp/pip-AVQepN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:16 INFO - Running setup.py (path:/tmp/pip-XDmnWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:17 INFO - Running setup.py (path:/tmp/pip-H1P7cA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:17 INFO - Running setup.py (path:/tmp/pip-tuZvfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:17 INFO - Running setup.py (path:/tmp/pip-jqvYp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:17 INFO - Running setup.py (path:/tmp/pip-NuKF5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:17 INFO - Running setup.py (path:/tmp/pip-5Dhg7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:17 INFO - Running setup.py (path:/tmp/pip-vMxX_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:17 INFO - Running setup.py (path:/tmp/pip-nb31uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:17 INFO - Running setup.py (path:/tmp/pip-uzYXmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:17 INFO - Running setup.py (path:/tmp/pip-EteWSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:17 INFO - Running setup.py (path:/tmp/pip-pf2AeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:17 INFO - Running setup.py (path:/tmp/pip-VbJ6oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:17 INFO - Running setup.py (path:/tmp/pip-R6bU92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:17 INFO - Running setup.py (path:/tmp/pip-NQVQn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:17 INFO - Running setup.py (path:/tmp/pip-71RTYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:36:18 INFO - Running setup.py install for manifestparser 21:36:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Running setup.py install for mozcrash 21:36:18 INFO - Running setup.py install for mozdebug 21:36:18 INFO - Running setup.py install for mozdevice 21:36:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Running setup.py install for mozfile 21:36:18 INFO - Running setup.py install for mozhttpd 21:36:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Running setup.py install for mozinfo 21:36:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Running setup.py install for mozInstall 21:36:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:36:18 INFO - Running setup.py install for mozleak 21:36:18 INFO - Running setup.py install for mozlog 21:36:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Running setup.py install for moznetwork 21:36:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Running setup.py install for mozprocess 21:36:19 INFO - Running setup.py install for mozprofile 21:36:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Running setup.py install for mozrunner 21:36:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Running setup.py install for mozscreenshot 21:36:19 INFO - Running setup.py install for moztest 21:36:19 INFO - Running setup.py install for mozversion 21:36:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:36:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:36:19 INFO - Cleaning up... 21:36:19 INFO - Return code: 0 21:36:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:19 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:19 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 21:36:19 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 21:36:19 INFO - Using env: {'DISPLAY': ':0', 21:36:19 INFO - 'HOME': '/home/cltbld', 21:36:19 INFO - 'LANG': 'en_US.UTF-8', 21:36:19 INFO - 'LANGUAGE': 'en_US:en', 21:36:19 INFO - 'LOGNAME': 'cltbld', 21:36:19 INFO - 'MAIL': '/var/mail/cltbld', 21:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:19 INFO - 'MOZ_NO_REMOTE': '1', 21:36:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:19 INFO - 'NO_EM_RESTART': '1', 21:36:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:19 INFO - 'PWD': '/builds/slave/test', 21:36:19 INFO - 'SHELL': '/bin/bash', 21:36:19 INFO - 'SHLVL': '1', 21:36:19 INFO - 'TERM': 'linux', 21:36:19 INFO - 'TMOUT': '86400', 21:36:19 INFO - 'USER': 'cltbld', 21:36:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:19 INFO - '_': '/tools/buildbot/bin/python'} 21:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:20 INFO - Running setup.py (path:/tmp/pip-VKmYJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:20 INFO - Running setup.py (path:/tmp/pip-1K15SJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:20 INFO - Running setup.py (path:/tmp/pip-lna5vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:20 INFO - Running setup.py (path:/tmp/pip-YGhkgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:20 INFO - Running setup.py (path:/tmp/pip-c80NGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:20 INFO - Running setup.py (path:/tmp/pip-LZsCgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:20 INFO - Running setup.py (path:/tmp/pip-tEKI4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:20 INFO - Running setup.py (path:/tmp/pip-W_OHpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:20 INFO - Running setup.py (path:/tmp/pip-sfGxq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:20 INFO - Running setup.py (path:/tmp/pip-Xv0OlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:20 INFO - Running setup.py (path:/tmp/pip-eZtNR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:20 INFO - Running setup.py (path:/tmp/pip-vbg5Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:20 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)) 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:20 INFO - Running setup.py (path:/tmp/pip-VFI7ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:21 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)) 21:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:21 INFO - Running setup.py (path:/tmp/pip-FP92h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:21 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)) 21:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:21 INFO - Running setup.py (path:/tmp/pip-yNNbol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:21 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)) 21:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:21 INFO - Running setup.py (path:/tmp/pip-ZooaZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:21 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)) 21:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:21 INFO - Running setup.py (path:/tmp/pip-wIZA8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:21 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)) 21:36:21 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)) 21:36:21 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)) 21:36:21 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)) 21:36:21 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)) 21:36:21 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)) 21:36:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:21 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 21:36:21 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 21:36:21 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 21:36:21 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 21:36:21 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 21:36:21 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 21:36:23 INFO - Downloading blessings-1.6.tar.gz 21:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:36:23 INFO - Installing collected packages: blessings 21:36:23 INFO - Running setup.py install for blessings 21:36:23 INFO - Successfully installed blessings 21:36:23 INFO - Cleaning up... 21:36:23 INFO - Return code: 0 21:36:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:23 INFO - Reading from file tmpfile_stdout 21:36:23 INFO - Current package versions: 21:36:23 INFO - argparse == 1.2.1 21:36:23 INFO - blessings == 1.6 21:36:23 INFO - blobuploader == 1.2.4 21:36:23 INFO - docopt == 0.6.1 21:36:23 INFO - manifestparser == 1.1 21:36:23 INFO - mozInstall == 1.12 21:36:23 INFO - mozcrash == 0.17 21:36:23 INFO - mozdebug == 0.1 21:36:23 INFO - mozdevice == 0.48 21:36:23 INFO - mozfile == 1.2 21:36:23 INFO - mozhttpd == 0.7 21:36:23 INFO - mozinfo == 0.9 21:36:23 INFO - mozleak == 0.1 21:36:23 INFO - mozlog == 3.2 21:36:23 INFO - moznetwork == 0.27 21:36:23 INFO - mozprocess == 0.22 21:36:23 INFO - mozprofile == 0.28 21:36:23 INFO - mozrunner == 6.11 21:36:23 INFO - mozscreenshot == 0.1 21:36:23 INFO - mozsystemmonitor == 0.0 21:36:23 INFO - moztest == 0.7 21:36:23 INFO - mozversion == 1.4 21:36:23 INFO - psutil == 3.1.1 21:36:23 INFO - requests == 1.2.3 21:36:23 INFO - wsgiref == 0.1.2 21:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:23 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')]} 21:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 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')]} 21:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:23 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 21:36:23 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 21:36:23 INFO - Using env: {'DISPLAY': ':0', 21:36:23 INFO - 'HOME': '/home/cltbld', 21:36:23 INFO - 'LANG': 'en_US.UTF-8', 21:36:23 INFO - 'LANGUAGE': 'en_US:en', 21:36:23 INFO - 'LOGNAME': 'cltbld', 21:36:23 INFO - 'MAIL': '/var/mail/cltbld', 21:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:23 INFO - 'MOZ_NO_REMOTE': '1', 21:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:23 INFO - 'NO_EM_RESTART': '1', 21:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:23 INFO - 'PWD': '/builds/slave/test', 21:36:23 INFO - 'SHELL': '/bin/bash', 21:36:23 INFO - 'SHLVL': '1', 21:36:23 INFO - 'TERM': 'linux', 21:36:23 INFO - 'TMOUT': '86400', 21:36:23 INFO - 'USER': 'cltbld', 21:36:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:23 INFO - '_': '/tools/buildbot/bin/python'} 21:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 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)) 21:36:23 INFO - Cleaning up... 21:36:23 INFO - Return code: 0 21:36:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:36:23 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')]} 21:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 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')]} 21:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 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 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:23 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 21:36:23 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 21:36:23 INFO - Using env: {'DISPLAY': ':0', 21:36:23 INFO - 'HOME': '/home/cltbld', 21:36:23 INFO - 'LANG': 'en_US.UTF-8', 21:36:23 INFO - 'LANGUAGE': 'en_US:en', 21:36:23 INFO - 'LOGNAME': 'cltbld', 21:36:23 INFO - 'MAIL': '/var/mail/cltbld', 21:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:23 INFO - 'MOZ_NO_REMOTE': '1', 21:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:23 INFO - 'NO_EM_RESTART': '1', 21:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:23 INFO - 'PWD': '/builds/slave/test', 21:36:23 INFO - 'SHELL': '/bin/bash', 21:36:23 INFO - 'SHLVL': '1', 21:36:23 INFO - 'TERM': 'linux', 21:36:23 INFO - 'TMOUT': '86400', 21:36:23 INFO - 'USER': 'cltbld', 21:36:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:23 INFO - '_': '/tools/buildbot/bin/python'} 21:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:24 INFO - Downloading/unpacking jsonschema 21:36:24 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 21:36:24 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 21:36:24 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 21:36:24 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 21:36:24 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 21:36:24 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 21:36:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:36:25 INFO - Downloading/unpacking functools32 (from jsonschema) 21:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:26 INFO - Downloading functools32-3.2.3-2.tar.gz 21:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:36:26 INFO - warning: no files found matching '*.txt' 21:36:26 INFO - no previously-included directories found matching 'build' 21:36:26 INFO - no previously-included directories found matching 'dist' 21:36:26 INFO - no previously-included directories found matching '.git*' 21:36:26 INFO - Installing collected packages: jsonschema, functools32 21:36:26 INFO - Running setup.py install for functools32 21:36:26 INFO - warning: no files found matching '*.txt' 21:36:26 INFO - no previously-included directories found matching 'build' 21:36:26 INFO - no previously-included directories found matching 'dist' 21:36:26 INFO - no previously-included directories found matching '.git*' 21:36:26 INFO - Successfully installed jsonschema functools32 21:36:26 INFO - Cleaning up... 21:36:26 INFO - Return code: 0 21:36:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f37d99f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a29770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, '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': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', '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 21:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:36:26 INFO - Using env: {'DISPLAY': ':0', 21:36:26 INFO - 'HOME': '/home/cltbld', 21:36:26 INFO - 'LANG': 'en_US.UTF-8', 21:36:26 INFO - 'LANGUAGE': 'en_US:en', 21:36:26 INFO - 'LOGNAME': 'cltbld', 21:36:26 INFO - 'MAIL': '/var/mail/cltbld', 21:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:26 INFO - 'MOZ_NO_REMOTE': '1', 21:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:26 INFO - 'NO_EM_RESTART': '1', 21:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:26 INFO - 'PWD': '/builds/slave/test', 21:36:26 INFO - 'SHELL': '/bin/bash', 21:36:26 INFO - 'SHLVL': '1', 21:36:26 INFO - 'TERM': 'linux', 21:36:26 INFO - 'TMOUT': '86400', 21:36:26 INFO - 'USER': 'cltbld', 21:36:26 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:26 INFO - '_': '/tools/buildbot/bin/python'} 21:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:26 INFO - Downloading/unpacking flake8 21:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:36:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:36:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:28 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 21:36:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:36:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:36:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:36:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:36:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:36:29 INFO - no previously-included directories found matching 'docs/_build' 21:36:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:36:29 INFO - Running setup.py install for pep8 21:36:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:36:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:36:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:36:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:36:29 INFO - no previously-included directories found matching 'docs/_build' 21:36:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:36:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:36:29 INFO - Cleaning up... 21:36:29 INFO - Return code: 0 21:36:29 INFO - Running post-action listener: _resource_record_post_action 21:36:29 INFO - Running post-action listener: _start_resource_monitoring 21:36:29 INFO - Starting resource monitoring. 21:36:29 INFO - ##### 21:36:29 INFO - ##### Finished create-virtualenv step (success) 21:36:29 INFO - ##### 21:36:29 INFO - ##### 21:36:29 INFO - ##### Running install step. 21:36:29 INFO - ##### 21:36:29 INFO - Running pre-action listener: _resource_record_pre_action 21:36:29 INFO - Running main action method: install 21:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:29 INFO - Reading from file tmpfile_stdout 21:36:29 INFO - Detecting whether we're running mozinstall >=1.0... 21:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:36:29 INFO - Reading from file tmpfile_stdout 21:36:29 INFO - Output received: 21:36:29 INFO - Usage: mozinstall [options] installer 21:36:29 INFO - Options: 21:36:29 INFO - -h, --help show this help message and exit 21:36:29 INFO - -d DEST, --destination=DEST 21:36:29 INFO - Directory to install application into. [default: 21:36:29 INFO - "/builds/slave/test"] 21:36:29 INFO - --app=APP Application being installed. [default: firefox] 21:36:29 INFO - mkdir: /builds/slave/test/build/application 21:36:29 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'] 21:36:29 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 21:36:45 INFO - Reading from file tmpfile_stdout 21:36:45 INFO - Output received: 21:36:45 INFO - /builds/slave/test/build/application/firefox/firefox 21:36:45 INFO - Running post-action listener: _resource_record_post_action 21:36:45 INFO - ##### 21:36:45 INFO - ##### Finished install step (success) 21:36:45 INFO - ##### 21:36:45 INFO - ##### 21:36:45 INFO - ##### Running run-tests step. 21:36:45 INFO - ##### 21:36:45 INFO - Running pre-action listener: _resource_record_pre_action 21:36:45 INFO - Running main action method: run_tests 21:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:36:45 INFO - Python 2.7.3 21:36:45 INFO - Return code: 0 21:36:45 INFO - grabbing minidump binary from tooltool 21:36:45 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')]} 21:36:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab89c0>, '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 0x2ab98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:36:45 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 21:36:45 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 21:36:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:36:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:36:45 INFO - Return code: 0 21:36:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:36:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:36:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:36:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:36:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:36:46 INFO - Return code: 0 21:36:46 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:36:46 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:36:46 INFO - Using env: {'DISPLAY': ':0', 21:36:46 INFO - 'HOME': '/home/cltbld', 21:36:46 INFO - 'LANG': 'en_US.UTF-8', 21:36:46 INFO - 'LANGUAGE': 'en_US:en', 21:36:46 INFO - 'LOGNAME': 'cltbld', 21:36:46 INFO - 'MAIL': '/var/mail/cltbld', 21:36:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:36:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:46 INFO - 'MOZ_NO_REMOTE': '1', 21:36:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:36:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:46 INFO - 'NO_EM_RESTART': '1', 21:36:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:46 INFO - 'PWD': '/builds/slave/test', 21:36:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:36:46 INFO - 'SHELL': '/bin/bash', 21:36:46 INFO - 'SHLVL': '1', 21:36:46 INFO - 'TERM': 'linux', 21:36:46 INFO - 'TMOUT': '86400', 21:36:46 INFO - 'USER': 'cltbld', 21:36:46 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461', 21:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:46 INFO - '_': '/tools/buildbot/bin/python'} 21:36:46 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:36:46 INFO - mozversion application_buildid: 20160512205746 21:36:46 INFO - mozversion application_changeset: e03a91e03791b7767a02b918ce207609f552bab9 21:36:46 INFO - mozversion application_display_name: Nightly 21:36:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:36:46 INFO - mozversion application_name: Firefox 21:36:46 INFO - mozversion application_remotingname: firefox 21:36:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:36:46 INFO - mozversion application_vendor: Mozilla 21:36:46 INFO - mozversion application_version: 49.0a1 21:36:46 INFO - mozversion platform_buildid: 20160512205746 21:36:46 INFO - mozversion platform_changeset: e03a91e03791b7767a02b918ce207609f552bab9 21:36:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:36:46 INFO - mozversion platform_version: 49.0a1 21:36:46 INFO - using testdate: 1463114206 21:36:46 INFO - actual date: 1463114206 21:36:46 INFO - starting webserver on 'localhost:40443' 21:36:46 INFO - SUITE-START | Running 2 tests 21:36:46 INFO - TEST-START | dromaeo_css 21:36:46 INFO - operating with platform_type : linux_ 21:36:46 INFO - Initialising browser for dromaeo_css test... 21:36:46 INFO - TEST-INFO | started process 25995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2eA9v2/profile http://localhost:40443/getInfo.html) 21:36:51 INFO - PROCESS | 25995 | __metrics Screen width/height:1600/1200 21:36:51 INFO - PROCESS | 25995 | colorDepth:24 21:36:51 INFO - PROCESS | 25995 | Browser inner width/height: 1024/697 21:36:51 INFO - PROCESS | 25995 | __metrics 21:36:51 ERROR - PROCESS | 25995 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:36:52 INFO - TEST-INFO | 25995: exit 0 21:36:52 INFO - Browser initialized. 21:36:52 INFO - Running cycle 1/1 for dromaeo_css test... 21:36:52 INFO - TEST-INFO | started process 26073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2eA9v2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:37:04 INFO - PROCESS | 26073 | RSS: Main: 154095616 21:37:04 INFO - PROCESS | 26073 | 21:37:56 INFO - PROCESS | 26073 | 1463114276442 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] 21:40:07 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40443/tests/dromaeo/cssquery-ext.html) 21:40:09 INFO - PROCESS | 26073 | RSS: Main: 229302272 21:40:09 INFO - PROCESS | 26073 | 21:43:11 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-ext.html (next: http://localhost:40443/tests/dromaeo/cssquery-jquery.html) 21:43:12 INFO - PROCESS | 26073 | RSS: Main: 237981696 21:43:12 INFO - PROCESS | 26073 | 21:46:14 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40443/tests/dromaeo/cssquery-mootools.html) 21:46:15 INFO - PROCESS | 26073 | RSS: Main: 237486080 21:46:15 INFO - PROCESS | 26073 | 21:49:17 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40443/tests/dromaeo/cssquery-prototype.html) 21:49:17 INFO - PROCESS | 26073 | RSS: Main: 228548608 21:49:17 INFO - PROCESS | 26073 | 21:52:20 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40443/tests/dromaeo/cssquery-yui.html) 21:52:21 INFO - PROCESS | 26073 | RSS: Main: 215523328 21:52:21 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | Cycle 1(1): loaded http://localhost:40443/tests/dromaeo/cssquery-yui.html (next: http://localhost:40443/tests/dromaeo/cssquery-dojo.html) 21:55:24 INFO - PROCESS | 26073 | RSS: Main: 298483712 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | __start_tp_report 21:55:24 INFO - PROCESS | 26073 | _x_x_mozilla_page_load 21:55:24 INFO - PROCESS | 26073 | _x_x_mozilla_page_load_details 21:55:24 INFO - PROCESS | 26073 | |i|pagename|runs| 21:55:24 INFO - PROCESS | 26073 | |0;dojo.html;2604.153650063729;2635.43338182291;2639.1818536253763;2677.7322267773225;2678.343805767587;4594.839180628679;4655.4413470383515;4759.500252473493;4843;4894.632902532307;4657.790399432025;4733.077049975248;4748.548887855657;4752.952470475294;4772.90454190916;4346.282863327548;4361.716488428242;4424.225760491917;4458;4544.590986811187;4414.80133393998;4497.437820272466;4511.819527218924;4516.051629157864;4528.569785870337;4279.293916503773;4330.696851220416;4335.111302183076;4336.631386332166;4368.235558777233;4374.234508960951;4380.693351465398;4382.0797632497215;4402.075564131536;4416.160929423431;4372.825086996548;4408.228560002019;4417.204903117437;4448.399466072071;4469.128519938613;4577.107464044512;4597.103564804865;4643.210654188796;4676.111538807616;4725.881852953669;5049.1164046292115;5085.440601533828;5099.184130539096;5103.030424219423;5103.642745007851;5060.2409638554145;5084.110280700863;5108.361454818148;5120.92318615221;5124.641275110744;4583;4713.9764301178275;4735.668503204812;4747.976260118747;4766.237402015661;5215.217717342429;5262.315898933114;5274;5280.947190528045;5285.048691235537;4989.151844186497;4990.90018199634;5037.244413338029;5052.7726252318735;5057.291979122926;4909.018196360742;5055.6713813602;5066.265391518209;5098.5411312982005;5150.253213284128;5053.974730126326;5079.034983353151;5083.466236045236;5096.490350964874;5131.640785145079;4962.578180854668;5069.923951140736;5079.4412614612365;5083.186690129562;5087.16061849798;4307.719998200107;4328.523862375137;4335.241332766753;4363.47638283408;4402.603765661106;3729.347364211252;3753.5495740511315;3758.0229140423685;3786.7917264550165;3790.3177428063213;4247.97876010618;4368.5194628590825;4378.146261478981;4379.0147216876585;4429.424174857248;5131.204663277198;5195.090859099642;5197.506236907488;5202.661826981234;5222.895542089137;4427.291633338715;4442.888927776767;4445.4220951276775;4447.288433850568;4466.798994045275;4460.977695111504;4547.704399214041;4673;4687;4717.9292310615365;4560.156371071429;4579.107074120515;4585.12882552314;4608.032313214128;4617.722936623813;5102.36220472441;5103.413107492686;5106.412762532356;5107.923381149211;5109.5912327013;4495.100979803987;4544.204764166191;4549.681522293407;4574.565416285448;4620.953790462187;4983.8504844854715;5002.449730529712;5004.84985450437;5008.84973450797;5051.823186188466;5034.572061374824;5091.490850914879;5096.43939166684;5097.898042039064;5127.512886275797;4864.002879409783;4921.138800709933;4938.061768264019;4971.627127965489;4986.102501549756;4912.410510738734;4938.950610493993;4965.900681986413;4975.154223782039;5043.4956504349275;4914.238293064581;4914.606831453403;4950.232713929347;4993.051320249141;4993.201087825931;4879.853604391874;4892;4914.680545764514;4956.430010548833;5087.313212716236;4842.394700662418;4873.512648735098;4874.2444921037295;4874.341963834837;4911.287863259879 21:55:24 INFO - PROCESS | 26073 | |1;ext.html;568.2045136808467;594.3551246897116;608.9817305480834;610.5420934299276;633.8605506788505;41714.58285417145;42995.78502107493;43066.138677226474;43073.56926430735;46701.53298467014;41854.58145418545;42305.576944230546;42312.57687423125;42343.57656423435;42422.15155696884;7791.688332466694;7922.326602238788;7953.681852725913;7961.761147165595;7979.361651067929;7897.407694422913;7983;7986.960065199666;7987.640556174986;7989.520628762264;7983.920160798376;8020;8020.318272946777;8021.959890200541;8027.638756255982;7939.444238903279;7969.203079692043;7976;7977.441579089466;7978.122406535276;7918.4061195409995;7969.561674107922;7977.880331795028;7983.68065277394;7990.480618759821;38674.41988370177;38851.61148388537;38938.80530597329;38970.61029389726;38993.22013559713;3186.9681303187135;3286.9342613147605;3394.4059789536977;3786.659200671953;4006.4991876015497;28483.28791780222;29978.40043199124;30350.69649303523;30479.695203047908;30807.845960770275;3551.1654761131113;3563.3051554946796;3575.1955809942815;3586.4620306953907;3588.9641103588892;234666;235312;235733;235840;237026.81486592628;56850;57001.71499142478;57276.42723572794;57280;57316.42683573111;37332.62667373346;38786.612133878865;38794.806025970254;38799.80600096982;38839.22321553553;15605.765913511306;15779.842201577838;15800.36798528046;15807.36770529189;15824.208789560476;11823.172377933462;11951.22317049389;11955.282683039046;11959.701007474709;11960.461779219782;19282.757244750417;19398.122666349293;19469.20241253558;19501.731894732788;19625.509362266057;7247.094113235846;7309.50465463736;7323.633818309063;7359.484836061424;7364.189939106694;878.1306506558589;958.4105774948496;1022.8772547294373;1026.4559783314855;1091.1979694924225;27845.58231626527;27955.44089118206;28895.71104288972;29168.476714052576;29403;950.9809803803885;1010.8433192855119;1027.9794404111876;1064.387976913278;1190.2679851891025;15005.849941500664;15248.788925605724;15529.223538823013;15694.896286723171;15842.44551440694;115978.42010789891;116702;116776.41611792057;117314;359850;51270.487295127314;51492.227616585784;51635.741821290656;51701.2244816328;51885.740571296905;1166.2186335155466;1278.0861683895873;1279.3858947705153;1300.3693208793727;1300.7918733002675;25988.8700556496;29663.25841853971;29869.402611948073;30276.84861575678;30463;42916.356254655584;43021.35467968046;43472.56527434685;43518.564814352714;43583.78208108939;267809;268333;268811;269479;269501;23827.523449531258;26317.605235921095;26412.339691507867;27108.32229194286;27170;42667;42855.3571696431;42923;43850.561494385925;44208.557914420446;36215;36574.634253657125;37192;37269.254614908095;37635.05912352213;36764.816175918946;37029.25941481209;37088.72587026141;37449.62550374462;37714.811425942695;36152.819235903655;36883.815580921924;36941.44587831131;37169.44245836369;37294.81352593219;32069;32826.50760238551;33076.83461582677;33371.49942750812;33609.327813444084;25384;25422.618660719734;25466.363340916625;25481;26044.739552604988 21:55:24 INFO - PROCESS | 26073 | |2;jquery.html;380.3606456173891;387.0690988173444;387.35698740091453;394.4773175542407;404.06862182668954;15821.446249381273;17331.653366932653;17854.464366069038;17934.73097903531;17936.641267174666;15989.440369587066;16436.83563164368;16709.164541772923;17476.475705728815;17506.737398939025;5124.205748109049;5284.630075894689;5303.65526240795;5319.12234481312;5344.732763361836;3736;3929.1748732766155;5723.8569035774235;6057.9701450753255;6058.787942422016;6107.877842443148;6167.044108163242;6169.074638804171;6181.07283907413;6194.101855230989;5964.433378829004;6073.969630151865;6086.421789929949;6165.568410211287;6170.814875553718;3442.8278586070596;5019.84940451787;5323.600729945231;5336.279602253685;5397.8110766123;20147.294844680368;20425.38723838287;20762.48093797652;20879.895600522053;20961.26635567763;2065.297798748418;2069.906854191565;2353.6116540770754;2355.1286024170995;2376.417777644488;12629.810552841715;13260.668483287995;13325.666858328523;13388.665283367896;13782.586522404345;1790.0960015192245;1797.973030404545;1800.468861685806;1836.5959488912551;1871.6163186546726;67138.66430667865;67326.66336668334;69178.65410672914;69208.6539567304;69761.65119174372;9543.42739435636;9787.265955053399;10109.949450252701;10137.695869123938;10284.897151028543;13812.171269723849;13946.651333716536;13962.860371396157;13995.440182392795;14002.439902403994;7375.336219740142;7533.54798712079;7619.047619047619;7722.691092356243;7770.844583108306;7328.706851725871;7331.413486921034;7404.184629988586;7476.1776204617445;7594;6178.227721534809;6339.334369891188;6347.52393570484;6402.583832050902;6439.388258115471;1662.384917580503;1688.36686242659;1801.1174524482908;1831.651986122659;1844.7048472244376;894.186290475664;916.134253130785;946.2713710442921;973.6592192732628;1051.9000694933968;16413.343466261456;16429.753553696704;17140.228689708994;17495.650086998445;17721.468355949084;921.2123634292717;928.5218112671979;942.6700654770913;970.7476056225291;1027.0653705128298;9554.82197360099;10109.848352274721;10137.34107283039;10171.54228059739;10268.691939241835;309450;310669;310867;383164;407324;82287.58856205801;85165;85993;86081.56959215165;86244;952.5332587032304;967.9322447428613;981.788915383196;987.7234374375187;1002.4837208837455;15624.140672263135;15686.529404117438;15756.212189390713;16068.919655401649;16339.836601633831;36798.8160059198;37368;38126.80936595299;38245.043873902265;38294.80852595831;51406.48593514017;53021;53994.730026349614;54086.72956635349;54250.57494250543;7746.49647772893;7772.883406748791;7874.13384527713;8003.319717823817;8157.918420815953;11147.331160130418;12200.389980500762;12239.510419583117;12259.938700306442;12308.138430309793;10490.423026733602;10624.20318476102;10646.574137034431;10663.253572249869;10894.564217431214;10599.205059620404;10676.83984740214;10719.410432426292;10824.134069274282;10925;10022.899771002196;10066.094051535398;10650.946745266225;10728.570857165938;11143.72140696489;7922.0889597697005;7944.523328600302;7993.6003199838615;8010.118886922551;8022.9197708022175;10092.697219083439;10369.896301037195;10388.065074143062;10397.272190946562;10406.947965260428 21:55:24 INFO - PROCESS | 26073 | |3;mootools.html;1336.6524703577068;1501.2118637715203;1528.763041728532;1694.279931029313;1861.7858946221188;7415.666295016724;7678.42411819113;7721.72973945912;7974.481658692181;7974.800629984239;7767.961160194205;7937.08723496798;7948.085970113439;7948.76153715388;7954.76135715928;2218.5784700906815;2247.3482690019873;2247.381969958258;2247.4830788918557;2251.0995601759264;2192.6601376786543;2237.2393386248673;2259.559385919746;2270.1146552844402;2271.1029143488313;2186.8469207155504;2220.7002054722648;2245.8427910046303;2248.1119957616734;2262.1177740681064;2177.444751588335;2177.477405422703;2195.209724499179;2206.3711842125;2217.057750456058;2119.9046042928107;2125.893705314728;2126.4046067101235;2127.1278775702044;2131.531063166101;7595.620218989084;7733;7772.7668169954995;7880.763577092696;7892.171322011163;4596.632269418438;4648.953510464886;4677.859664210079;4685.992511609986;4691.296305554194;2771.2794673385065;2778.472090302836;2783.178962206154;2813.7608303294246;2827.420378822357;863.5121156546531;867.600903584352;877.6664867350366;879.4503435352905;887.316766090107;10478.528466218962;10500.317479363817;10550.261481696285;10590.470476476223;10606.045455909005;4055;4089.7546147230664;4167.124903770182;4177.122804211089;4200.663946884242;8442;8447.408681392244;8454.830903381899;8541.145885411534;8571.528565928933;4577.107464044546;4579.244424669959;4626.745528995937;4635.582797548237;4639.188142075123;3674.3202507536193;4134.235166494208;4256.702030857872;4312.029793296483;4323.416338794223;3271.6401195868434;3287.8027318360496;3359.7816141951184;3364.158960259935;3366.444536651474;972.3485264872552;995.0944640377222;1008.9798204035878;1013.4831236069657;1021.6322124035341;885.2165833237502;889.4618755652866;905.8369493491103;917.4675910772631;919.5034681272039;5348.224507446398;5427.6472029317965;5447;5511.862203444946;5541.196526503634;394.0719605329465;412.5647441948773;429.81303133137186;434.3788382612831;446.17012357015926;3710.8144592770254;3773.7924414156937;3813.5042444482597;3813.6949044076964;3835.4055121456217;8679.696210632597;8966.327525435618;8988.730338089868;9082.45505269686;9117;8786.253168480624;8969.551522423853;8992.235659968845;9033.05152958943;9081.81836363269;6954.965225173842;6972.477064220204;7056.964715176391;7138.072050633488;7155.89266161008;5077.466865979019;5105.2086926526445;5208.088584497622;5272.736363181749;5292.655977361459;8306.335493160654;8326.45878017927;8383.832323353621;8391.538465384217;8490.193431623986;8284.50292982423;8355.122712115384;8634.956825215833;8649.264812490757;8721.91278087211;5573.247611572386;6169.722362493789;6206.751729930752;6215.285242197205;6250.906236406367;7354.595497247488;7622.428317876293;7626.351760100453;7636.503627264432;7657.88513172314;6665.466762659073;6830.897536536857;6896.620685862325;6922.826929326606;7003.964980175067;6701.195856497252;6711.530192886451;6800.6599670017295;6815.829604259933;6823.317668233137;6376.776812811529;6413.935860641333;6452.12896258999;6475.222973243241;6485.967570162119;4503.797329120263;4719.221328480762;4829.541193586604;4853.51464853526;4863.90272194547;4705.270683044134;4747.881302967454;4839.128956787812;4854.80580776765;4896.534829191221 21:55:24 INFO - PROCESS | 26073 | |4;prototype.html;312.0684756003331;314.4528520374549;317.6537574044294;318.5349389890758;319.4792488244162;4726.432828060633;4726.90546189076;4745.90508189836;4771.665983381165;4779.115863565238;4673.065386922621;4677.438707355113;4682.484926658073;4704.482506924235;4704.576588107069;3569.9464508032333;3674.761140525857;3789.374753165738;3794.3359912015426;3797.6328521732153;3730.608286129958;3749.5125633667585;3762.887113386589;3792.2415516896594;3803.296390167814;3561.5904171020275;3742.8771368589314;3750.268697603968;3757.3988161894104;3761.661450469457;3636.1273294409284;3779.45197946299;3780.3573392523335;3787.6969842412677;3793.848246070154;3332.783369081026;3370.679785420381;3373.4939759036097;3378.510116033186;3392.830358482066;4498.640108791289;4511.69160943326;4537.546245375469;4548.931766023512;4573.977130114361;4187.51843537994;4220.879416244417;4268.167707297078;4288.871333859989;4306.418633484471;4883.584895283905;4908.533689299511;4929;4930.707221142186;4943.184374578191;3664.743467957244;3685.631436856293;3689.797061161635;3690.6862916652117;3692.372296709539;4915.557599815998;5090.312807770941;5156.793728250866;5169.39748677911;5175.896482070338;3729.850805967731;3741.962580374216;3744.288585168864;3748.737588368788;3762.3227818992295;3817.7900215488417;3819.2170605025904;3820.5797362290127;3824.388097904322;3824.4263360496147;4258.212230737324;4266.296061149876;4285.3786201000685;4288.799136241857;4306.461692288464;4697.567241991225;4936.160852655057;4937.23472861707;4947.975260123676;4952.281919121707;1278.2905487454366;1283.8523569789593;1287.1826390242263;1289.8323217981601;1297.8118172864881;868.2619773192862;870.7910101575576;876.7106854737921;888.0764005434276;892.4199270474244;4313.266744653416;4316.208773359021;4325.199838029975;4353.521112677603;4355.542668019875;4983.6760610560195;5005.674631148963;5022.42242142151;5043.344365232496;5044.142495775727;4160.50073991123;4292.227399068135;4301.118270754488;4309.245881970643;4327.199468098349;2656.861226712515;2676.1035053256987;2676.946461070768;2688.220416079354;2704.6889607695366;4509.729416235036;4540.432445944257;4549.886252843706;4552.837402233912;4575.107853968444;4926.6551341406475;5071.39143302806;5146.819861304836;5149.940023990359;5164.251183578435;4513.887152821206;4522.615577675869;4538.387317712132;4547.545245475426;4598.954010459853;4976.776045077908;4996.675216110941;5013.82175188823;5014.623903207201;5015.673981191211;4740.312654665123;4899.975500122477;4902.828401005948;4919.901601968012;4943.283223932438;4497.617702495194;4538.909221815611;4542.841000564964;4544.363789069599;4550.612063320719;3755.1926335837925;3762.341590221755;3768.830402631943;3768.907016965159;3776.905577360588;4873.585745211697;4998.325226094431;5022.799088036438;5030.37120359955;5046.9747651261505;4842.4189097308545;4874.512548745239;4876.78106461552;4876.90246195067;4896.779644915916;4795.352627395397;4823.1800593899825;4828.806847726051;4865.829695960766;4876.902461950812;4636.281376386665;4798.5441383068555;4802.975985120052;4807.879803004953;4832.806687732451;4853.645981698803;4882.584980276574;4892.315075889307;4912.1894887344615;4921.089598424303;4759.571638552547;4768.9523104768505;4786.377770889762;4796.616270698266;4799.688020278671 21:55:24 INFO - PROCESS | 26073 | |5;yui.html;614.7909710698364;665.6838001949075;666.689989155043;682.7951614515644;703.419678765019;12780.616581502558;13226.801597976026;13361.53234636788;13363.398647060882;13456.865431345683;13386.59840204793;13658.795118073236;13788.931055344709;14164.50424235152;14258.928705356459;692.1417442371448;868.68727258187;869.6695255802786;872.6422166911536;873.7946582553095;842.8819965204876;872.5167215733247;879.0594064351147;881.2949640287744;881.6870011146048;902.7291812456269;905.1220316293185;906.6192199276287;908.0420156734642;910.499225426013;716.5199316457986;877.3200769403763;899.4738078224246;900.4327273817519;900.9144131307515;662.7282814046266;666.516775337881;667.686187491883;668.0513670587728;668.2181847238743;13145;13343.799843002364;13346.399412026482;13347.86652133476;13354.732905341936;10935.453227338681;10970.725731856688;11183.217174797768;11461.942690286494;11552.267159925994;11484.253523521027;11594.942025289904;11607.825882611767;11661.825072623917;11708.590199343067;4091.468109145792;4118.567550407224;4184.497860256758;4245.511766146902;4251.829926802924;49611.50388496105;50084.49915500835;50155;50744.74627626875;50761;9637.22902167825;9777.902220977841;10205.183585313158;10359;10362.533685984148;17663.293468261127;17684.115794210495;17701;17715.55711107207;17715.911420442815;6856.382925536626;6883.105196324445;6915.89626155608;6980.092587963532;6992.16094068715;3748.175401411685;3833.4441505982036;3843.135294558702;3846.076941534068;3868.477755502971;2855.857207139676;2944.896928607531;2948.0418863869327;2948.7346138847606;2950.1002194330936;514.3519165851054;517.5238780322113;520.2144761410223;527.1591811061351;527.6728428374432;498.5463228462082;504.095149207174;606.2512796696077;626.3079297376333;629.3266205160526;7627.694892204249;7726.420518461138;7750.844983100419;8014.759557213293;8095.83808323842;582.4612233683825;606.4451027309962;611.1932249430708;614.3273115938041;622.6730966242759;3750.1374683822874;3779.998300450383;3785.8674946376746;3838.961610383916;3936.4882565266903;45008;46167.76916115398;49677.751611242435;50388;50646;44204.77897610491;45598.77200614042;46191;46857.531424686;47035.76482117636;42393.576064238965;43086.56913430826;43342.349864752054;43811.90470238206;44252.77873610676;8172.713955011546;8375.916240837758;8448.95775521143;8541.14588541141;8582.485050896967;29406.41187176202;29764.255893602833;29894.25264368408;30141.547876782304;30863.2284192897;36471.63528364682;38392;38728.838134856;38789.6121038786;39250.80374598109;10717.089047430743;10768.569257229936;11083.667489975314;11175.664730058112;11328;11747.118966077407;11853.466594003463;11905.40472976365;11914.16600837933;12740.362981851056;11211.55153793839;11568;11836.289822610672;11894.524219031142;11987.880121198676;11767.705807354541;11851.822222666839;11904.821427678766;11929.28424294545;12092.939535302266;11201.88798112041;11222.028574424567;11329.263597866113;11563.132765042486;11977.880221197676;9381.624735010522;9438.905610943802;9637.132658060807;9658.517074146404;9967.35212211233;8758.518281494578;9203.76990575241;9355.766105847137;9424.905750942402;9489.667861625087 21:55:24 INFO - PROCESS | 26073 | __end_tp_report 21:55:24 INFO - PROCESS | 26073 | __start_cc_report 21:55:24 INFO - PROCESS | 26073 | _x_x_mozilla_cycle_collect,1068 21:55:24 INFO - PROCESS | 26073 | __end_cc_report 21:55:24 INFO - PROCESS | 26073 | __startTimestamp1463115324437__endTimestamp 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | ------- Summary: start ------- 21:55:24 INFO - PROCESS | 26073 | Number of tests: 6 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#0] dojo.html Cycles:165 Average:4696.40 Median:4772.90 stddev:494.33 (10.4%) stddev-sans-first:467.81 21:55:24 INFO - PROCESS | 26073 | Values: 2604.2 2635.4 2639.2 2677.7 2678.3 4594.8 4655.4 4759.5 4843.0 4894.6 4657.8 4733.1 4748.5 4753.0 4772.9 4346.3 4361.7 4424.2 4458.0 4544.6 4414.8 4497.4 4511.8 4516.1 4528.6 4279.3 4330.7 4335.1 4336.6 4368.2 4374.2 4380.7 4382.1 4402.1 4416.2 4372.8 4408.2 4417.2 4448.4 4469.1 4577.1 4597.1 4643.2 4676.1 4725.9 5049.1 5085.4 5099.2 5103.0 5103.6 5060.2 5084.1 5108.4 5120.9 5124.6 4583.0 4714.0 4735.7 4748.0 4766.2 5215.2 5262.3 5274.0 5280.9 5285.0 4989.2 4990.9 5037.2 5052.8 5057.3 4909.0 5055.7 5066.3 5098.5 5150.3 5054.0 5079.0 5083.5 5096.5 5131.6 4962.6 5069.9 5079.4 5083.2 5087.2 4307.7 4328.5 4335.2 4363.5 4402.6 3729.3 3753.5 3758.0 3786.8 3790.3 4248.0 4368.5 4378.1 4379.0 4429.4 5131.2 5195.1 5197.5 5202.7 5222.9 4427.3 4442.9 4445.4 4447.3 4466.8 4461.0 4547.7 4673.0 4687.0 4717.9 4560.2 4579.1 4585.1 4608.0 4617.7 5102.4 5103.4 5106.4 5107.9 5109.6 4495.1 4544.2 4549.7 4574.6 4621.0 4983.9 5002.4 5004.8 5008.8 5051.8 5034.6 5091.5 5096.4 5097.9 5127.5 4864.0 4921.1 4938.1 4971.6 4986.1 4912.4 4939.0 4965.9 4975.2 5043.5 4914.2 4914.6 4950.2 4993.1 4993.2 4879.9 4892.0 4914.7 4956.4 5087.3 4842.4 4873.5 4874.2 4874.3 4911.3 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#1] ext.html Cycles:180 Average:39801.05 Median:36985.35 stddev:61285.11 (165.7%) stddev-sans-first:61386.23 21:55:24 INFO - PROCESS | 26073 | Values: 568.2 594.4 609.0 610.5 633.9 41714.6 42995.8 43066.1 43073.6 46701.5 41854.6 42305.6 42312.6 42343.6 42422.2 7791.7 7922.3 7953.7 7961.8 7979.4 7897.4 7983.0 7987.0 7987.6 7989.5 7983.9 8020.0 8020.3 8022.0 8027.6 7939.4 7969.2 7976.0 7977.4 7978.1 7918.4 7969.6 7977.9 7983.7 7990.5 38674.4 38851.6 38938.8 38970.6 38993.2 3187.0 3286.9 3394.4 3786.7 4006.5 28483.3 29978.4 30350.7 30479.7 30807.8 3551.2 3563.3 3575.2 3586.5 3589.0 234666.0 235312.0 235733.0 235840.0 237026.8 56850.0 57001.7 57276.4 57280.0 57316.4 37332.6 38786.6 38794.8 38799.8 38839.2 15605.8 15779.8 15800.4 15807.4 15824.2 11823.2 11951.2 11955.3 11959.7 11960.5 19282.8 19398.1 19469.2 19501.7 19625.5 7247.1 7309.5 7323.6 7359.5 7364.2 878.1 958.4 1022.9 1026.5 1091.2 27845.6 27955.4 28895.7 29168.5 29403.0 951.0 1010.8 1028.0 1064.4 1190.3 15005.8 15248.8 15529.2 15694.9 15842.4 115978.4 116702.0 116776.4 117314.0 359850.0 51270.5 51492.2 51635.7 51701.2 51885.7 1166.2 1278.1 1279.4 1300.4 1300.8 25988.9 29663.3 29869.4 30276.8 30463.0 42916.4 43021.4 43472.6 43518.6 43583.8 267809.0 268333.0 268811.0 269479.0 269501.0 23827.5 26317.6 26412.3 27108.3 27170.0 42667.0 42855.4 42923.0 43850.6 44208.6 36215.0 36574.6 37192.0 37269.3 37635.1 36764.8 37029.3 37088.7 37449.6 37714.8 36152.8 36883.8 36941.4 37169.4 37294.8 32069.0 32826.5 33076.8 33371.5 33609.3 25384.0 25422.6 25466.4 25481.0 26044.7 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#2] jquery.html Cycles:180 Average:23675.32 Median:20552.00 stddev:57785.16 (281.2%) stddev-sans-first:57920.79 21:55:24 INFO - PROCESS | 26073 | Values: 380.4 387.1 387.4 394.5 404.1 15821.4 17331.7 17854.5 17934.7 17936.6 15989.4 16436.8 16709.2 17476.5 17506.7 5124.2 5284.6 5303.7 5319.1 5344.7 3736.0 3929.2 5723.9 6058.0 6058.8 6107.9 6167.0 6169.1 6181.1 6194.1 5964.4 6074.0 6086.4 6165.6 6170.8 3442.8 5019.8 5323.6 5336.3 5397.8 20147.3 20425.4 20762.5 20879.9 20961.3 2065.3 2069.9 2353.6 2355.1 2376.4 12629.8 13260.7 13325.7 13388.7 13782.6 1790.1 1798.0 1800.5 1836.6 1871.6 67138.7 67326.7 69178.7 69208.7 69761.7 9543.4 9787.3 10109.9 10137.7 10284.9 13812.2 13946.7 13962.9 13995.4 14002.4 7375.3 7533.5 7619.0 7722.7 7770.8 7328.7 7331.4 7404.2 7476.2 7594.0 6178.2 6339.3 6347.5 6402.6 6439.4 1662.4 1688.4 1801.1 1831.7 1844.7 894.2 916.1 946.3 973.7 1051.9 16413.3 16429.8 17140.2 17495.7 17721.5 921.2 928.5 942.7 970.7 1027.1 9554.8 10109.8 10137.3 10171.5 10268.7 309450.0 310669.0 310867.0 383164.0 407324.0 82287.6 85165.0 85993.0 86081.6 86244.0 952.5 967.9 981.8 987.7 1002.5 15624.1 15686.5 15756.2 16068.9 16339.8 36798.8 37368.0 38126.8 38245.0 38294.8 51406.5 53021.0 53994.7 54086.7 54250.6 7746.5 7772.9 7874.1 8003.3 8157.9 11147.3 12200.4 12239.5 12259.9 12308.1 10490.4 10624.2 10646.6 10663.3 10894.6 10599.2 10676.8 10719.4 10824.1 10925.0 10022.9 10066.1 10650.9 10728.6 11143.7 7922.1 7944.5 7993.6 8010.1 8022.9 10092.7 10369.9 10388.1 10397.3 10406.9 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#3] mootools.html Cycles:180 Average:5045.05 Median:5240.41 stddev:2775.08 (53.0%) stddev-sans-first:2768.87 21:55:24 INFO - PROCESS | 26073 | Values: 1336.7 1501.2 1528.8 1694.3 1861.8 7415.7 7678.4 7721.7 7974.5 7974.8 7768.0 7937.1 7948.1 7948.8 7954.8 2218.6 2247.3 2247.4 2247.5 2251.1 2192.7 2237.2 2259.6 2270.1 2271.1 2186.8 2220.7 2245.8 2248.1 2262.1 2177.4 2177.5 2195.2 2206.4 2217.1 2119.9 2125.9 2126.4 2127.1 2131.5 7595.6 7733.0 7772.8 7880.8 7892.2 4596.6 4649.0 4677.9 4686.0 4691.3 2771.3 2778.5 2783.2 2813.8 2827.4 863.5 867.6 877.7 879.5 887.3 10478.5 10500.3 10550.3 10590.5 10606.0 4055.0 4089.8 4167.1 4177.1 4200.7 8442.0 8447.4 8454.8 8541.1 8571.5 4577.1 4579.2 4626.7 4635.6 4639.2 3674.3 4134.2 4256.7 4312.0 4323.4 3271.6 3287.8 3359.8 3364.2 3366.4 972.3 995.1 1009.0 1013.5 1021.6 885.2 889.5 905.8 917.5 919.5 5348.2 5427.6 5447.0 5511.9 5541.2 394.1 412.6 429.8 434.4 446.2 3710.8 3773.8 3813.5 3813.7 3835.4 8679.7 8966.3 8988.7 9082.5 9117.0 8786.3 8969.6 8992.2 9033.1 9081.8 6955.0 6972.5 7057.0 7138.1 7155.9 5077.5 5105.2 5208.1 5272.7 5292.7 8306.3 8326.5 8383.8 8391.5 8490.2 8284.5 8355.1 8635.0 8649.3 8721.9 5573.2 6169.7 6206.8 6215.3 6250.9 7354.6 7622.4 7626.4 7636.5 7657.9 6665.5 6830.9 6896.6 6922.8 7004.0 6701.2 6711.5 6800.7 6815.8 6823.3 6376.8 6413.9 6452.1 6475.2 6486.0 4503.8 4719.2 4829.5 4853.5 4863.9 4705.3 4747.9 4839.1 4854.8 4896.5 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#4] prototype.html Cycles:180 Average:4092.37 Median:4543.60 stddev:1145.34 (25.2%) stddev-sans-first:1112.85 21:55:24 INFO - PROCESS | 26073 | Values: 312.1 314.5 317.7 318.5 319.5 4726.4 4726.9 4745.9 4771.7 4779.1 4673.1 4677.4 4682.5 4704.5 4704.6 3569.9 3674.8 3789.4 3794.3 3797.6 3730.6 3749.5 3762.9 3792.2 3803.3 3561.6 3742.9 3750.3 3757.4 3761.7 3636.1 3779.5 3780.4 3787.7 3793.8 3332.8 3370.7 3373.5 3378.5 3392.8 4498.6 4511.7 4537.5 4548.9 4574.0 4187.5 4220.9 4268.2 4288.9 4306.4 4883.6 4908.5 4929.0 4930.7 4943.2 3664.7 3685.6 3689.8 3690.7 3692.4 4915.6 5090.3 5156.8 5169.4 5175.9 3729.9 3742.0 3744.3 3748.7 3762.3 3817.8 3819.2 3820.6 3824.4 3824.4 4258.2 4266.3 4285.4 4288.8 4306.5 4697.6 4936.2 4937.2 4948.0 4952.3 1278.3 1283.9 1287.2 1289.8 1297.8 868.3 870.8 876.7 888.1 892.4 4313.3 4316.2 4325.2 4353.5 4355.5 4983.7 5005.7 5022.4 5043.3 5044.1 4160.5 4292.2 4301.1 4309.2 4327.2 2656.9 2676.1 2676.9 2688.2 2704.7 4509.7 4540.4 4549.9 4552.8 4575.1 4926.7 5071.4 5146.8 5149.9 5164.3 4513.9 4522.6 4538.4 4547.5 4599.0 4976.8 4996.7 5013.8 5014.6 5015.7 4740.3 4900.0 4902.8 4919.9 4943.3 4497.6 4538.9 4542.8 4544.4 4550.6 3755.2 3762.3 3768.8 3768.9 3776.9 4873.6 4998.3 5022.8 5030.4 5047.0 4842.4 4874.5 4876.8 4876.9 4896.8 4795.4 4823.2 4828.8 4865.8 4876.9 4636.3 4798.5 4803.0 4807.9 4832.8 4853.6 4882.6 4892.3 4912.2 4921.1 4759.6 4769.0 4786.4 4796.6 4799.7 21:55:24 INFO - PROCESS | 26073 | 21:55:24 INFO - PROCESS | 26073 | [#5] yui.html Cycles:180 Average:13021.39 Median:43577.13 stddev:14427.73 (33.1%) stddev-sans-first:14438.11 21:55:24 INFO - PROCESS | 26073 | Values: 614.8 665.7 666.7 682.8 703.4 12780.6 13226.8 13361.5 13363.4 13456.9 13386.6 13658.8 13788.9 14164.5 14258.9 692.1 868.7 869.7 872.6 873.8 842.9 872.5 879.1 881.3 881.7 902.7 905.1 906.6 908.0 910.5 716.5 877.3 899.5 900.4 900.9 662.7 666.5 667.7 668.1 668.2 13145.0 13343.8 13346.4 13347.9 13354.7 10935.5 10970.7 11183.2 11461.9 11552.3 11484.3 11594.9 11607.8 11661.8 11708.6 4091.5 4118.6 4184.5 4245.5 4251.8 49611.5 50084.5 50155.0 50744.7 50761.0 9637.2 9777.9 10205.2 10359.0 10362.5 17663.3 17684.1 17701.0 17715.6 17715.9 6856.4 6883.1 6915.9 6980.1 6992.2 3748.2 3833.4 3843.1 3846.1 3868.5 2855.9 2944.9 2948.0 2948.7 2950.1 514.4 517.5 520.2 527.2 527.7 498.5 504.1 606.3 626.3 629.3 7627.7 7726.4 7750.8 8014.8 8095.8 582.5 606.4 611.2 614.3 622.7 3750.1 3780.0 3785.9 3839.0 3936.5 45008.0 46167.8 49677.8 50388.0 50646.0 44204.8 45598.8 46191.0 46857.5 47035.8 42393.6 43086.6 43342.3 43811.9 44252.8 8172.7 8375.9 8449.0 8541.1 8582.5 29406.4 29764.3 29894.3 30141.5 30863.2 36471.6 38392.0 38728.8 38789.6 39250.8 10717.1 10768.6 11083.7 11175.7 11328.0 11747.1 11853.5 11905.4 11914.2 12740.4 11211.6 11568.0 11836.3 11894.5 11987.9 11767.7 11851.8 11904.8 11929.3 12092.9 11201.9 11222.0 11329.3 11563.1 11977.9 9381.6 9438.9 9637.1 9658.5 9967.4 8758.5 9203.8 9355.8 9424.9 9489.7 21:55:24 INFO - PROCESS | 26073 | -------- Summary: end -------- 21:55:24 INFO - PROCESS | 26073 | 21:55:25 INFO - TEST-INFO | 26073: exit 0 21:55:25 INFO - TEST-OK | dromaeo_css | took 1118436ms 21:55:25 INFO - TEST-START | kraken 21:55:25 INFO - operating with platform_type : linux_ 21:55:25 INFO - Initialising browser for kraken test... 21:55:25 INFO - TEST-INFO | started process 26249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpluLAL1/profile http://localhost:40443/getInfo.html) 21:55:29 INFO - PROCESS | 26249 | __metrics Screen width/height:1600/1200 21:55:29 INFO - PROCESS | 26249 | colorDepth:24 21:55:29 INFO - PROCESS | 26249 | Browser inner width/height: 1024/697 21:55:29 INFO - PROCESS | 26249 | __metrics 21:55:29 ERROR - PROCESS | 26249 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:55:30 INFO - TEST-INFO | 26249: exit 0 21:55:30 INFO - Browser initialized. 21:55:30 INFO - Running cycle 1/1 for kraken test... 21:55:30 INFO - TEST-INFO | started process 26333 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpluLAL1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:42 INFO - PROCESS | 26333 | RSS: Main: 153731072 21:55:42 INFO - PROCESS | 26333 | 21:55:45 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40443/tests/kraken/driver.html?testName=audio-beat-detection) 21:55:46 INFO - PROCESS | 26333 | RSS: Main: 209879040 21:55:46 INFO - PROCESS | 26333 | 21:55:49 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40443/tests/kraken/driver.html?testName=audio-dft) 21:55:49 INFO - PROCESS | 26333 | RSS: Main: 196521984 21:55:49 INFO - PROCESS | 26333 | 21:55:53 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40443/tests/kraken/driver.html?testName=audio-fft) 21:55:53 INFO - PROCESS | 26333 | RSS: Main: 266506240 21:55:53 INFO - PROCESS | 26333 | 21:55:56 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40443/tests/kraken/driver.html?testName=audio-oscillator) 21:55:56 INFO - PROCESS | 26333 | RSS: Main: 175833088 21:55:56 INFO - PROCESS | 26333 | 21:55:59 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40443/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:55:59 INFO - PROCESS | 26333 | RSS: Main: 180256768 21:55:59 INFO - PROCESS | 26333 | 21:56:02 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40443/tests/kraken/driver.html?testName=imaging-darkroom) 21:56:03 INFO - PROCESS | 26333 | RSS: Main: 216285184 21:56:03 INFO - PROCESS | 26333 | 21:56:07 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40443/tests/kraken/driver.html?testName=imaging-desaturate) 21:56:07 INFO - PROCESS | 26333 | RSS: Main: 215101440 21:56:07 INFO - PROCESS | 26333 | 21:56:10 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40443/tests/kraken/driver.html?testName=json-parse-financial) 21:56:11 INFO - PROCESS | 26333 | RSS: Main: 215126016 21:56:11 INFO - PROCESS | 26333 | 21:56:13 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40443/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:56:14 INFO - PROCESS | 26333 | RSS: Main: 205557760 21:56:14 INFO - PROCESS | 26333 | 21:56:15 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:56:16 INFO - PROCESS | 26333 | RSS: Main: 210300928 21:56:16 INFO - PROCESS | 26333 | 21:56:18 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:56:19 INFO - PROCESS | 26333 | RSS: Main: 214814720 21:56:19 INFO - PROCESS | 26333 | 21:56:22 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:56:23 INFO - PROCESS | 26333 | RSS: Main: 219471872 21:56:23 INFO - PROCESS | 26333 | 21:56:26 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:56:26 INFO - PROCESS | 26333 | RSS: Main: 218595328 21:56:26 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:40443/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40443/tests/kraken/driver.html?testName=ai-astar) 21:56:28 INFO - PROCESS | 26333 | RSS: Main: 264400896 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | __start_tp_report 21:56:28 INFO - PROCESS | 26333 | _x_x_mozilla_page_load 21:56:28 INFO - PROCESS | 26333 | _x_x_mozilla_page_load_details 21:56:28 INFO - PROCESS | 26333 | |i|pagename|runs| 21:56:28 INFO - PROCESS | 26333 | |0;ai-astar;115;109;114;116;117;114;114;116;114;116 21:56:28 INFO - PROCESS | 26333 | |1;audio-beat-detection;173;130;156;208;133;158;156;131;190;130 21:56:28 INFO - PROCESS | 26333 | |2;audio-dft;162;161;161;163;162;160;162;161;162;162 21:56:28 INFO - PROCESS | 26333 | |3;audio-fft;78;77;78;169;76;77;76;75;136;75 21:56:28 INFO - PROCESS | 26333 | |4;audio-oscillator;94;94;148;93;93;94;134;93;93;124 21:56:28 INFO - PROCESS | 26333 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;117;116 21:56:28 INFO - PROCESS | 26333 | |6;imaging-darkroom;146;146;147;146;146;146;147;145;145;146 21:56:28 INFO - PROCESS | 26333 | |7;imaging-desaturate;95;95;94;94;94;95;95;95;95;94 21:56:28 INFO - PROCESS | 26333 | |8;json-parse-financial;67;68;68;68;67;70;67;69;67;68 21:56:28 INFO - PROCESS | 26333 | |9;json-stringify-tinderbox;63;64;64;64;65;64;64;65;64;64 21:56:28 INFO - PROCESS | 26333 | |10;stanford-crypto-aes;84;83;83;90;91;87;91;87;164;84 21:56:28 INFO - PROCESS | 26333 | |11;stanford-crypto-ccm;136;136;132;130;129;128;131;134;149;146 21:56:28 INFO - PROCESS | 26333 | |12;stanford-crypto-pbkdf2;182;181;177;173;180;178;177;179;176;175 21:56:28 INFO - PROCESS | 26333 | |13;stanford-crypto-sha256-iterative;69;69;72;70;68;72;69;68;67;65 21:56:28 INFO - PROCESS | 26333 | __end_tp_report 21:56:28 INFO - PROCESS | 26333 | __start_cc_report 21:56:28 INFO - PROCESS | 26333 | _x_x_mozilla_cycle_collect,2081 21:56:28 INFO - PROCESS | 26333 | __end_cc_report 21:56:28 INFO - PROCESS | 26333 | __startTimestamp1463115388801__endTimestamp 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | ------- Summary: start ------- 21:56:28 INFO - PROCESS | 26333 | Number of tests: 14 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.50 stddev:2.22 (1.9%) stddev-sans-first:2.35 21:56:28 INFO - PROCESS | 26333 | Values: 115.0 109.0 114.0 116.0 117.0 114.0 114.0 116.0 114.0 116.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#1] audio-beat-detection Cycles:10 Average:156.50 Median:157.00 stddev:27.20 (17.3%) stddev-sans-first:28.18 21:56:28 INFO - PROCESS | 26333 | Values: 173.0 130.0 156.0 208.0 133.0 158.0 156.0 131.0 190.0 130.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 21:56:28 INFO - PROCESS | 26333 | Values: 162.0 161.0 161.0 163.0 162.0 160.0 162.0 161.0 162.0 162.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#3] audio-fft Cycles:10 Average:91.70 Median:76.50 stddev:32.99 (43.1%) stddev-sans-first:34.62 21:56:28 INFO - PROCESS | 26333 | Values: 78.0 77.0 78.0 169.0 76.0 77.0 76.0 75.0 136.0 75.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#4] audio-oscillator Cycles:10 Average:106.00 Median:93.00 stddev:21.03 (22.6%) stddev-sans-first:21.85 21:56:28 INFO - PROCESS | 26333 | Values: 94.0 94.0 148.0 93.0 93.0 94.0 134.0 93.0 93.0 124.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 21:56:28 INFO - PROCESS | 26333 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 21:56:28 INFO - PROCESS | 26333 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:56:28 INFO - PROCESS | 26333 | Values: 95.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 21:56:28 INFO - PROCESS | 26333 | Values: 67.0 68.0 68.0 68.0 67.0 70.0 67.0 69.0 67.0 68.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 21:56:28 INFO - PROCESS | 26333 | Values: 63.0 64.0 64.0 64.0 65.0 64.0 64.0 65.0 64.0 64.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:87.00 stddev:24.66 (28.3%) stddev-sans-first:25.87 21:56:28 INFO - PROCESS | 26333 | Values: 84.0 83.0 83.0 90.0 91.0 87.0 91.0 87.0 164.0 84.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:135.00 stddev:7.11 (5.3%) stddev-sans-first:7.53 21:56:28 INFO - PROCESS | 26333 | Values: 136.0 136.0 132.0 130.0 129.0 128.0 131.0 134.0 149.0 146.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:178.50 stddev:2.78 (1.6%) stddev-sans-first:2.50 21:56:28 INFO - PROCESS | 26333 | Values: 182.0 181.0 177.0 173.0 180.0 178.0 177.0 179.0 176.0 175.0 21:56:28 INFO - PROCESS | 26333 | 21:56:28 INFO - PROCESS | 26333 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:2.13 (3.1%) stddev-sans-first:2.26 21:56:28 INFO - PROCESS | 26333 | Values: 69.0 69.0 72.0 70.0 68.0 72.0 69.0 68.0 67.0 65.0 21:56:28 INFO - PROCESS | 26333 | -------- Summary: end -------- 21:56:28 INFO - PROCESS | 26333 | 21:56:29 INFO - TEST-INFO | 26333: exit 0 21:56:29 INFO - TEST-OK | kraken | took 64641ms 21:56:29 INFO - SUITE-END | took 1183s 21:56:29 INFO - Completed test suite (00:19:43) 21:56:29 INFO - javascript benchmark 21:56:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2604.153650063729, 2635.43338182291, 2639.1818536253763, 2677.7322267773225, 2678.343805767587, 4594.839180628679, 4655.4413470383515, 4759.500252473493, 4843.0, 4894.632902532307, 4657.790399432025, 4733.077049975248, 4748.548887855657, 4752.952470475294, 4772.90454190916, 4346.282863327548, 4361.716488428242, 4424.225760491917, 4458.0, 4544.590986811187, 4414.80133393998, 4497.437820272466, 4511.819527218924, 4516.051629157864, 4528.569785870337, 4279.293916503773, 4330.696851220416, 4335.111302183076, 4336.631386332166, 4368.235558777233, 4374.234508960951, 4380.693351465398, 4382.0797632497215, 4402.075564131536, 4416.160929423431, 4372.825086996548, 4408.228560002019, 4417.204903117437, 4448.399466072071, 4469.128519938613, 4577.107464044512, 4597.103564804865, 4643.210654188796, 4676.111538807616, 4725.881852953669, 5049.1164046292115, 5085.440601533828, 5099.184130539096, 5103.030424219423, 5103.642745007851, 5060.2409638554145, 5084.110280700863, 5108.361454818148, 5120.92318615221, 5124.641275110744, 4583.0, 4713.9764301178275, 4735.668503204812, 4747.976260118747, 4766.237402015661, 5215.217717342429, 5262.315898933114, 5274.0, 5280.947190528045, 5285.048691235537, 4989.151844186497, 4990.90018199634, 5037.244413338029, 5052.7726252318735, 5057.291979122926, 4909.018196360742, 5055.6713813602, 5066.265391518209, 5098.5411312982005, 5150.253213284128, 5053.974730126326, 5079.034983353151, 5083.466236045236, 5096.490350964874, 5131.640785145079, 4962.578180854668, 5069.923951140736, 5079.4412614612365, 5083.186690129562, 5087.16061849798, 4307.719998200107, 4328.523862375137, 4335.241332766753, 4363.47638283408, 4402.603765661106, 3729.347364211252, 3753.5495740511315, 3758.0229140423685, 3786.7917264550165, 3790.3177428063213, 4247.97876010618, 4368.5194628590825, 4378.146261478981, 4379.0147216876585, 4429.424174857248, 5131.204663277198, 5195.090859099642, 5197.506236907488, 5202.661826981234, 5222.895542089137, 4427.291633338715, 4442.888927776767, 4445.4220951276775, 4447.288433850568, 4466.798994045275, 4460.977695111504, 4547.704399214041, 4673.0, 4687.0, 4717.9292310615365, 4560.156371071429, 4579.107074120515, 4585.12882552314, 4608.032313214128, 4617.722936623813, 5102.36220472441, 5103.413107492686, 5106.412762532356, 5107.923381149211, 5109.5912327013, 4495.100979803987, 4544.204764166191, 4549.681522293407, 4574.565416285448, 4620.953790462187, 4983.8504844854715, 5002.449730529712, 5004.84985450437, 5008.84973450797, 5051.823186188466, 5034.572061374824, 5091.490850914879, 5096.43939166684, 5097.898042039064, 5127.512886275797, 4864.002879409783, 4921.138800709933, 4938.061768264019, 4971.627127965489, 4986.102501549756, 4912.410510738734, 4938.950610493993, 4965.900681986413, 4975.154223782039, 5043.4956504349275, 4914.238293064581, 4914.606831453403, 4950.232713929347, 4993.051320249141, 4993.201087825931, 4879.853604391874, 4892.0, 4914.680545764514, 4956.430010548833, 5087.313212716236, 4842.394700662418, 4873.512648735098, 4874.2444921037295, 4874.341963834837, 4911.287863259879], "value": 4664.760874875879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2045136808467, 594.3551246897116, 608.9817305480834, 610.5420934299276, 633.8605506788505, 41714.58285417145, 42995.78502107493, 43066.138677226474, 43073.56926430735, 46701.53298467014, 41854.58145418545, 42305.576944230546, 42312.57687423125, 42343.57656423435, 42422.15155696884, 7791.688332466694, 7922.326602238788, 7953.681852725913, 7961.761147165595, 7979.361651067929, 7897.407694422913, 7983.0, 7986.960065199666, 7987.640556174986, 7989.520628762264, 7983.920160798376, 8020.0, 8020.318272946777, 8021.959890200541, 8027.638756255982, 7939.444238903279, 7969.203079692043, 7976.0, 7977.441579089466, 7978.122406535276, 7918.4061195409995, 7969.561674107922, 7977.880331795028, 7983.68065277394, 7990.480618759821, 38674.41988370177, 38851.61148388537, 38938.80530597329, 38970.61029389726, 38993.22013559713, 3186.9681303187135, 3286.9342613147605, 3394.4059789536977, 3786.659200671953, 4006.4991876015497, 28483.28791780222, 29978.40043199124, 30350.69649303523, 30479.695203047908, 30807.845960770275, 3551.1654761131113, 3563.3051554946796, 3575.1955809942815, 3586.4620306953907, 3588.9641103588892, 234666.0, 235312.0, 235733.0, 235840.0, 237026.81486592628, 56850.0, 57001.71499142478, 57276.42723572794, 57280.0, 57316.42683573111, 37332.62667373346, 38786.612133878865, 38794.806025970254, 38799.80600096982, 38839.22321553553, 15605.765913511306, 15779.842201577838, 15800.36798528046, 15807.36770529189, 15824.208789560476, 11823.172377933462, 11951.22317049389, 11955.282683039046, 11959.701007474709, 11960.461779219782, 19282.757244750417, 19398.122666349293, 19469.20241253558, 19501.731894732788, 19625.509362266057, 7247.094113235846, 7309.50465463736, 7323.633818309063, 7359.484836061424, 7364.189939106694, 878.1306506558589, 958.4105774948496, 1022.8772547294373, 1026.4559783314855, 1091.1979694924225, 27845.58231626527, 27955.44089118206, 28895.71104288972, 29168.476714052576, 29403.0, 950.9809803803885, 1010.8433192855119, 1027.9794404111876, 1064.387976913278, 1190.2679851891025, 15005.849941500664, 15248.788925605724, 15529.223538823013, 15694.896286723171, 15842.44551440694, 115978.42010789891, 116702.0, 116776.41611792057, 117314.0, 359850.0, 51270.487295127314, 51492.227616585784, 51635.741821290656, 51701.2244816328, 51885.740571296905, 1166.2186335155466, 1278.0861683895873, 1279.3858947705153, 1300.3693208793727, 1300.7918733002675, 25988.8700556496, 29663.25841853971, 29869.402611948073, 30276.84861575678, 30463.0, 42916.356254655584, 43021.35467968046, 43472.56527434685, 43518.564814352714, 43583.78208108939, 267809.0, 268333.0, 268811.0, 269479.0, 269501.0, 23827.523449531258, 26317.605235921095, 26412.339691507867, 27108.32229194286, 27170.0, 42667.0, 42855.3571696431, 42923.0, 43850.561494385925, 44208.557914420446, 36215.0, 36574.634253657125, 37192.0, 37269.254614908095, 37635.05912352213, 36764.816175918946, 37029.25941481209, 37088.72587026141, 37449.62550374462, 37714.811425942695, 36152.819235903655, 36883.815580921924, 36941.44587831131, 37169.44245836369, 37294.81352593219, 32069.0, 32826.50760238551, 33076.83461582677, 33371.49942750812, 33609.327813444084, 25384.0, 25422.618660719734, 25466.363340916625, 25481.0, 26044.739552604988], "value": 17535.168734158404, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.3606456173891, 387.0690988173444, 387.35698740091453, 394.4773175542407, 404.06862182668954, 15821.446249381273, 17331.653366932653, 17854.464366069038, 17934.73097903531, 17936.641267174666, 15989.440369587066, 16436.83563164368, 16709.164541772923, 17476.475705728815, 17506.737398939025, 5124.205748109049, 5284.630075894689, 5303.65526240795, 5319.12234481312, 5344.732763361836, 3736.0, 3929.1748732766155, 5723.8569035774235, 6057.9701450753255, 6058.787942422016, 6107.877842443148, 6167.044108163242, 6169.074638804171, 6181.07283907413, 6194.101855230989, 5964.433378829004, 6073.969630151865, 6086.421789929949, 6165.568410211287, 6170.814875553718, 3442.8278586070596, 5019.84940451787, 5323.600729945231, 5336.279602253685, 5397.8110766123, 20147.294844680368, 20425.38723838287, 20762.48093797652, 20879.895600522053, 20961.26635567763, 2065.297798748418, 2069.906854191565, 2353.6116540770754, 2355.1286024170995, 2376.417777644488, 12629.810552841715, 13260.668483287995, 13325.666858328523, 13388.665283367896, 13782.586522404345, 1790.0960015192245, 1797.973030404545, 1800.468861685806, 1836.5959488912551, 1871.6163186546726, 67138.66430667865, 67326.66336668334, 69178.65410672914, 69208.6539567304, 69761.65119174372, 9543.42739435636, 9787.265955053399, 10109.949450252701, 10137.695869123938, 10284.897151028543, 13812.171269723849, 13946.651333716536, 13962.860371396157, 13995.440182392795, 14002.439902403994, 7375.336219740142, 7533.54798712079, 7619.047619047619, 7722.691092356243, 7770.844583108306, 7328.706851725871, 7331.413486921034, 7404.184629988586, 7476.1776204617445, 7594.0, 6178.227721534809, 6339.334369891188, 6347.52393570484, 6402.583832050902, 6439.388258115471, 1662.384917580503, 1688.36686242659, 1801.1174524482908, 1831.651986122659, 1844.7048472244376, 894.186290475664, 916.134253130785, 946.2713710442921, 973.6592192732628, 1051.9000694933968, 16413.343466261456, 16429.753553696704, 17140.228689708994, 17495.650086998445, 17721.468355949084, 921.2123634292717, 928.5218112671979, 942.6700654770913, 970.7476056225291, 1027.0653705128298, 9554.82197360099, 10109.848352274721, 10137.34107283039, 10171.54228059739, 10268.691939241835, 309450.0, 310669.0, 310867.0, 383164.0, 407324.0, 82287.58856205801, 85165.0, 85993.0, 86081.56959215165, 86244.0, 952.5332587032304, 967.9322447428613, 981.788915383196, 987.7234374375187, 1002.4837208837455, 15624.140672263135, 15686.529404117438, 15756.212189390713, 16068.919655401649, 16339.836601633831, 36798.8160059198, 37368.0, 38126.80936595299, 38245.043873902265, 38294.80852595831, 51406.48593514017, 53021.0, 53994.730026349614, 54086.72956635349, 54250.57494250543, 7746.49647772893, 7772.883406748791, 7874.13384527713, 8003.319717823817, 8157.918420815953, 11147.331160130418, 12200.389980500762, 12239.510419583117, 12259.938700306442, 12308.138430309793, 10490.423026733602, 10624.20318476102, 10646.574137034431, 10663.253572249869, 10894.564217431214, 10599.205059620404, 10676.83984740214, 10719.410432426292, 10824.134069274282, 10925.0, 10022.899771002196, 10066.094051535398, 10650.946745266225, 10728.570857165938, 11143.72140696489, 7922.0889597697005, 7944.523328600302, 7993.6003199838615, 8010.118886922551, 8022.9197708022175, 10092.697219083439, 10369.896301037195, 10388.065074143062, 10397.272190946562, 10406.947965260428], "value": 8642.373471103616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.6524703577068, 1501.2118637715203, 1528.763041728532, 1694.279931029313, 1861.7858946221188, 7415.666295016724, 7678.42411819113, 7721.72973945912, 7974.481658692181, 7974.800629984239, 7767.961160194205, 7937.08723496798, 7948.085970113439, 7948.76153715388, 7954.76135715928, 2218.5784700906815, 2247.3482690019873, 2247.381969958258, 2247.4830788918557, 2251.0995601759264, 2192.6601376786543, 2237.2393386248673, 2259.559385919746, 2270.1146552844402, 2271.1029143488313, 2186.8469207155504, 2220.7002054722648, 2245.8427910046303, 2248.1119957616734, 2262.1177740681064, 2177.444751588335, 2177.477405422703, 2195.209724499179, 2206.3711842125, 2217.057750456058, 2119.9046042928107, 2125.893705314728, 2126.4046067101235, 2127.1278775702044, 2131.531063166101, 7595.620218989084, 7733.0, 7772.7668169954995, 7880.763577092696, 7892.171322011163, 4596.632269418438, 4648.953510464886, 4677.859664210079, 4685.992511609986, 4691.296305554194, 2771.2794673385065, 2778.472090302836, 2783.178962206154, 2813.7608303294246, 2827.420378822357, 863.5121156546531, 867.600903584352, 877.6664867350366, 879.4503435352905, 887.316766090107, 10478.528466218962, 10500.317479363817, 10550.261481696285, 10590.470476476223, 10606.045455909005, 4055.0, 4089.7546147230664, 4167.124903770182, 4177.122804211089, 4200.663946884242, 8442.0, 8447.408681392244, 8454.830903381899, 8541.145885411534, 8571.528565928933, 4577.107464044546, 4579.244424669959, 4626.745528995937, 4635.582797548237, 4639.188142075123, 3674.3202507536193, 4134.235166494208, 4256.702030857872, 4312.029793296483, 4323.416338794223, 3271.6401195868434, 3287.8027318360496, 3359.7816141951184, 3364.158960259935, 3366.444536651474, 972.3485264872552, 995.0944640377222, 1008.9798204035878, 1013.4831236069657, 1021.6322124035341, 885.2165833237502, 889.4618755652866, 905.8369493491103, 917.4675910772631, 919.5034681272039, 5348.224507446398, 5427.6472029317965, 5447.0, 5511.862203444946, 5541.196526503634, 394.0719605329465, 412.5647441948773, 429.81303133137186, 434.3788382612831, 446.17012357015926, 3710.8144592770254, 3773.7924414156937, 3813.5042444482597, 3813.6949044076964, 3835.4055121456217, 8679.696210632597, 8966.327525435618, 8988.730338089868, 9082.45505269686, 9117.0, 8786.253168480624, 8969.551522423853, 8992.235659968845, 9033.05152958943, 9081.81836363269, 6954.965225173842, 6972.477064220204, 7056.964715176391, 7138.072050633488, 7155.89266161008, 5077.466865979019, 5105.2086926526445, 5208.088584497622, 5272.736363181749, 5292.655977361459, 8306.335493160654, 8326.45878017927, 8383.832323353621, 8391.538465384217, 8490.193431623986, 8284.50292982423, 8355.122712115384, 8634.956825215833, 8649.264812490757, 8721.91278087211, 5573.247611572386, 6169.722362493789, 6206.751729930752, 6215.285242197205, 6250.906236406367, 7354.595497247488, 7622.428317876293, 7626.351760100453, 7636.503627264432, 7657.88513172314, 6665.466762659073, 6830.897536536857, 6896.620685862325, 6922.826929326606, 7003.964980175067, 6701.195856497252, 6711.530192886451, 6800.6599670017295, 6815.829604259933, 6823.317668233137, 6376.776812811529, 6413.935860641333, 6452.12896258999, 6475.222973243241, 6485.967570162119, 4503.797329120263, 4719.221328480762, 4829.541193586604, 4853.51464853526, 4863.90272194547, 4705.270683044134, 4747.881302967454, 4839.128956787812, 4854.80580776765, 4896.534829191221], "value": 4019.5930911215546, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.0684756003331, 314.4528520374549, 317.6537574044294, 318.5349389890758, 319.4792488244162, 4726.432828060633, 4726.90546189076, 4745.90508189836, 4771.665983381165, 4779.115863565238, 4673.065386922621, 4677.438707355113, 4682.484926658073, 4704.482506924235, 4704.576588107069, 3569.9464508032333, 3674.761140525857, 3789.374753165738, 3794.3359912015426, 3797.6328521732153, 3730.608286129958, 3749.5125633667585, 3762.887113386589, 3792.2415516896594, 3803.296390167814, 3561.5904171020275, 3742.8771368589314, 3750.268697603968, 3757.3988161894104, 3761.661450469457, 3636.1273294409284, 3779.45197946299, 3780.3573392523335, 3787.6969842412677, 3793.848246070154, 3332.783369081026, 3370.679785420381, 3373.4939759036097, 3378.510116033186, 3392.830358482066, 4498.640108791289, 4511.69160943326, 4537.546245375469, 4548.931766023512, 4573.977130114361, 4187.51843537994, 4220.879416244417, 4268.167707297078, 4288.871333859989, 4306.418633484471, 4883.584895283905, 4908.533689299511, 4929.0, 4930.707221142186, 4943.184374578191, 3664.743467957244, 3685.631436856293, 3689.797061161635, 3690.6862916652117, 3692.372296709539, 4915.557599815998, 5090.312807770941, 5156.793728250866, 5169.39748677911, 5175.896482070338, 3729.850805967731, 3741.962580374216, 3744.288585168864, 3748.737588368788, 3762.3227818992295, 3817.7900215488417, 3819.2170605025904, 3820.5797362290127, 3824.388097904322, 3824.4263360496147, 4258.212230737324, 4266.296061149876, 4285.3786201000685, 4288.799136241857, 4306.461692288464, 4697.567241991225, 4936.160852655057, 4937.23472861707, 4947.975260123676, 4952.281919121707, 1278.2905487454366, 1283.8523569789593, 1287.1826390242263, 1289.8323217981601, 1297.8118172864881, 868.2619773192862, 870.7910101575576, 876.7106854737921, 888.0764005434276, 892.4199270474244, 4313.266744653416, 4316.208773359021, 4325.199838029975, 4353.521112677603, 4355.542668019875, 4983.6760610560195, 5005.674631148963, 5022.42242142151, 5043.344365232496, 5044.142495775727, 4160.50073991123, 4292.227399068135, 4301.118270754488, 4309.245881970643, 4327.199468098349, 2656.861226712515, 2676.1035053256987, 2676.946461070768, 2688.220416079354, 2704.6889607695366, 4509.729416235036, 4540.432445944257, 4549.886252843706, 4552.837402233912, 4575.107853968444, 4926.6551341406475, 5071.39143302806, 5146.819861304836, 5149.940023990359, 5164.251183578435, 4513.887152821206, 4522.615577675869, 4538.387317712132, 4547.545245475426, 4598.954010459853, 4976.776045077908, 4996.675216110941, 5013.82175188823, 5014.623903207201, 5015.673981191211, 4740.312654665123, 4899.975500122477, 4902.828401005948, 4919.901601968012, 4943.283223932438, 4497.617702495194, 4538.909221815611, 4542.841000564964, 4544.363789069599, 4550.612063320719, 3755.1926335837925, 3762.341590221755, 3768.830402631943, 3768.907016965159, 3776.905577360588, 4873.585745211697, 4998.325226094431, 5022.799088036438, 5030.37120359955, 5046.9747651261505, 4842.4189097308545, 4874.512548745239, 4876.78106461552, 4876.90246195067, 4896.779644915916, 4795.352627395397, 4823.1800593899825, 4828.806847726051, 4865.829695960766, 4876.902461950812, 4636.281376386665, 4798.5441383068555, 4802.975985120052, 4807.879803004953, 4832.806687732451, 4853.645981698803, 4882.584980276574, 4892.315075889307, 4912.1894887344615, 4921.089598424303, 4759.571638552547, 4768.9523104768505, 4786.377770889762, 4796.616270698266, 4799.688020278671], "value": 3736.607446517021, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.7909710698364, 665.6838001949075, 666.689989155043, 682.7951614515644, 703.419678765019, 12780.616581502558, 13226.801597976026, 13361.53234636788, 13363.398647060882, 13456.865431345683, 13386.59840204793, 13658.795118073236, 13788.931055344709, 14164.50424235152, 14258.928705356459, 692.1417442371448, 868.68727258187, 869.6695255802786, 872.6422166911536, 873.7946582553095, 842.8819965204876, 872.5167215733247, 879.0594064351147, 881.2949640287744, 881.6870011146048, 902.7291812456269, 905.1220316293185, 906.6192199276287, 908.0420156734642, 910.499225426013, 716.5199316457986, 877.3200769403763, 899.4738078224246, 900.4327273817519, 900.9144131307515, 662.7282814046266, 666.516775337881, 667.686187491883, 668.0513670587728, 668.2181847238743, 13145.0, 13343.799843002364, 13346.399412026482, 13347.86652133476, 13354.732905341936, 10935.453227338681, 10970.725731856688, 11183.217174797768, 11461.942690286494, 11552.267159925994, 11484.253523521027, 11594.942025289904, 11607.825882611767, 11661.825072623917, 11708.590199343067, 4091.468109145792, 4118.567550407224, 4184.497860256758, 4245.511766146902, 4251.829926802924, 49611.50388496105, 50084.49915500835, 50155.0, 50744.74627626875, 50761.0, 9637.22902167825, 9777.902220977841, 10205.183585313158, 10359.0, 10362.533685984148, 17663.293468261127, 17684.115794210495, 17701.0, 17715.55711107207, 17715.911420442815, 6856.382925536626, 6883.105196324445, 6915.89626155608, 6980.092587963532, 6992.16094068715, 3748.175401411685, 3833.4441505982036, 3843.135294558702, 3846.076941534068, 3868.477755502971, 2855.857207139676, 2944.896928607531, 2948.0418863869327, 2948.7346138847606, 2950.1002194330936, 514.3519165851054, 517.5238780322113, 520.2144761410223, 527.1591811061351, 527.6728428374432, 498.5463228462082, 504.095149207174, 606.2512796696077, 626.3079297376333, 629.3266205160526, 7627.694892204249, 7726.420518461138, 7750.844983100419, 8014.759557213293, 8095.83808323842, 582.4612233683825, 606.4451027309962, 611.1932249430708, 614.3273115938041, 622.6730966242759, 3750.1374683822874, 3779.998300450383, 3785.8674946376746, 3838.961610383916, 3936.4882565266903, 45008.0, 46167.76916115398, 49677.751611242435, 50388.0, 50646.0, 44204.77897610491, 45598.77200614042, 46191.0, 46857.531424686, 47035.76482117636, 42393.576064238965, 43086.56913430826, 43342.349864752054, 43811.90470238206, 44252.77873610676, 8172.713955011546, 8375.916240837758, 8448.95775521143, 8541.14588541141, 8582.485050896967, 29406.41187176202, 29764.255893602833, 29894.25264368408, 30141.547876782304, 30863.2284192897, 36471.63528364682, 38392.0, 38728.838134856, 38789.6121038786, 39250.80374598109, 10717.089047430743, 10768.569257229936, 11083.667489975314, 11175.664730058112, 11328.0, 11747.118966077407, 11853.466594003463, 11905.40472976365, 11914.16600837933, 12740.362981851056, 11211.55153793839, 11568.0, 11836.289822610672, 11894.524219031142, 11987.880121198676, 11767.705807354541, 11851.822222666839, 11904.821427678766, 11929.28424294545, 12092.939535302266, 11201.88798112041, 11222.028574424567, 11329.263597866113, 11563.132765042486, 11977.880221197676, 9381.624735010522, 9438.905610943802, 9637.132658060807, 9658.517074146404, 9967.35212211233, 8758.518281494578, 9203.76990575241, 9355.766105847137, 9424.905750942402, 9489.667861625087], "value": 6131.232969706842, "unit": "score"}], "name": "dromaeo_css", "value": 6342.668610288265, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 114.0, 116.0, 117.0, 114.0, 114.0, 116.0, 114.0, 116.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 130.0, 156.0, 208.0, 133.0, 158.0, 156.0, 131.0, 190.0, 130.0], "value": 156.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 163.0, 162.0, 160.0, 162.0, 161.0, 162.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 169.0, 76.0, 77.0, 76.0, 75.0, 136.0, 75.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 148.0, 93.0, 93.0, 94.0, 134.0, 93.0, 93.0, 124.0], "value": 106.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 70.0, 67.0, 69.0, 67.0, 68.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 90.0, 91.0, 87.0, 91.0, 87.0, 164.0, 84.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 132.0, 130.0, 129.0, 128.0, 131.0, 134.0, 149.0, 146.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 181.0, 177.0, 173.0, 180.0, 178.0, 177.0, 179.0, 176.0, 175.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 72.0, 70.0, 68.0, 72.0, 69.0, 68.0, 67.0, 65.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1595.8000000000002, "alertThreshold": 2.0}]} 21:56:30 INFO - Return code: 0 21:56:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:56:30 ERROR - # TBPL SUCCESS # 21:56:30 INFO - Running post-action listener: _resource_record_post_action 21:56:30 INFO - ##### 21:56:30 INFO - ##### Finished run-tests step (success) 21:56:30 INFO - ##### 21:56:30 INFO - Running post-run listener: _resource_record_post_run 21:56:31 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530632704; Read time: 48; Write time: 381104 21:56:31 INFO - TinderboxPrint: CPU usage
13.1% 21:56:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 48 21:56:31 INFO - TinderboxPrint: I/O write bytes / time
530,632,704 / 381,104 21:56:31 INFO - TinderboxPrint: CPU idle
8,324.3 (86.9%) 21:56:31 INFO - TinderboxPrint: CPU user
1,212.4 (12.7%) 21:56:31 INFO - TinderboxPrint: Swap in / out
0 / 0 21:56:31 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234917888; Read time: 0; Write time: 225648 21:56:31 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295714816; Read time: 48; Write time: 155456 21:56:31 INFO - Running post-run listener: _upload_blobber_files 21:56:31 INFO - Blob upload gear active. 21:56:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:56:31 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 21:56:31 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'] 21:56:31 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 21:56:31 INFO - (blobuploader) - INFO - Open directory for files ... 21:56:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:56:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:56:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:32 INFO - (blobuploader) - INFO - Done attempting. 21:56:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:56:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:56:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:33 INFO - (blobuploader) - INFO - Done attempting. 21:56:33 INFO - (blobuploader) - INFO - Iteration through files over. 21:56:33 INFO - Return code: 0 21:56:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:56:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:56:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa67af588f56ce30af4b65cf6456f1dd605f0188db11f5b82e17e5f509589e90ed1aafffc33c28d175ad14ad6d468653681391f7d6559f98243ea27dbd823c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bafcb53c51f5a355bc4313e50c940e1d0e0edce234216d1ec348207fe5bb3d04adfd35b2b04891a02bef7d3802722a188f5361ad161cc4e28337365b7e0aa253"} 21:56:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:56:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:56:33 INFO - Contents: 21:56:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa67af588f56ce30af4b65cf6456f1dd605f0188db11f5b82e17e5f509589e90ed1aafffc33c28d175ad14ad6d468653681391f7d6559f98243ea27dbd823c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bafcb53c51f5a355bc4313e50c940e1d0e0edce234216d1ec348207fe5bb3d04adfd35b2b04891a02bef7d3802722a188f5361ad161cc4e28337365b7e0aa253"} 21:56:33 INFO - Running post-run listener: copy_logs_to_upload_dir 21:56:33 INFO - Copying logs to upload dir... 21:56:33 INFO - mkdir: /builds/slave/test/build/upload/logs 21:56:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.238080 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-12 21:56:32.868297) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 21:56:32.871899) ========= 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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa67af588f56ce30af4b65cf6456f1dd605f0188db11f5b82e17e5f509589e90ed1aafffc33c28d175ad14ad6d468653681391f7d6559f98243ea27dbd823c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bafcb53c51f5a355bc4313e50c940e1d0e0edce234216d1ec348207fe5bb3d04adfd35b2b04891a02bef7d3802722a188f5361ad161cc4e28337365b7e0aa253"} build_url:https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/c8kr85fHQLmzhhHtQTf1gQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa67af588f56ce30af4b65cf6456f1dd605f0188db11f5b82e17e5f509589e90ed1aafffc33c28d175ad14ad6d468653681391f7d6559f98243ea27dbd823c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bafcb53c51f5a355bc4313e50c940e1d0e0edce234216d1ec348207fe5bb3d04adfd35b2b04891a02bef7d3802722a188f5361ad161cc4e28337365b7e0aa253"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 21:56:32.896956) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:56:32.897317) ========= 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=3a3620fb28aea1604ab609ef000001d2-1463113846.528600-312518461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004332 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 21:56:32.980422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 21:56:32.980757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 21:56:32.981083) ========= ========= Total master_lag: 0.15 =========