builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-002 starttime: 1462666240.91 results: success (0) buildid: 20160507165048 builduid: 130fe3b8846c4d81b0cd11182a72d8df revision: 794c7ee92f4805df1b31624b79b74d4a91878d5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:40.912126) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:40.913774) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:40.914736) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:41.000229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:41.000751) ========= 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=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-07 17:10:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.3M=0s 2016-05-07 17:10:41 (38.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099596 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:41.114730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:41.115055) ========= 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=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015640 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:41.164727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-07 17:10:41.165112) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 794c7ee92f4805df1b31624b79b74d4a91878d5e --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 794c7ee92f4805df1b31624b79b74d4a91878d5e --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=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-07 17:10:42,058 truncating revision to first 12 chars 2016-05-07 17:10:42,058 Setting DEBUG logging. 2016-05-07 17:10:42,058 attempt 1/10 2016-05-07 17:10:42,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/794c7ee92f48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-07 17:10:43,177 unpacking tar archive at: fx-team-794c7ee92f48/testing/mozharness/ program finished with exit code 0 elapsedTime=1.403188 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-07 17:10:42.580950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:42.581261) ========= script_repo_revision: 794c7ee92f4805df1b31624b79b74d4a91878d5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:42.581706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:42.582011) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 17:10:42.590956) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-07 17:10:42.591391) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:10:43 INFO - MultiFileLogger online at 20160507 17:10:43 in /builds/slave/test 17:10:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 17:10:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:10:43 INFO - {'append_to_log': False, 17:10:43 INFO - 'base_work_dir': '/builds/slave/test', 17:10:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:10:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:10:43 INFO - 'branch': 'Fx-Team-Non-PGO', 17:10:43 INFO - 'buildbot_json_path': 'buildprops.json', 17:10:43 INFO - 'config_files': ('talos/linux_config.py',), 17:10:43 INFO - 'default_actions': ('clobber', 17:10:43 INFO - 'read-buildbot-config', 17:10:43 INFO - 'download-and-extract', 17:10:43 INFO - 'populate-webroot', 17:10:43 INFO - 'create-virtualenv', 17:10:43 INFO - 'install', 17:10:43 INFO - 'run-tests'), 17:10:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:10:43 INFO - 'download_minidump_stackwalk': True, 17:10:43 INFO - 'download_symbols': 'ondemand', 17:10:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:10:43 INFO - 'tooltool.py': '/tools/tooltool.py', 17:10:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:10:43 INFO - '/tools/misc-python/virtualenv.py')}, 17:10:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:10:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:10:43 INFO - 'installer_path': 'installer.exe', 17:10:43 INFO - 'log_level': 'info', 17:10:43 INFO - 'log_name': 'talos', 17:10:43 INFO - 'log_to_console': True, 17:10:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:10:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:10:43 INFO - 'opt_config_files': (), 17:10:43 INFO - 'pip_index': False, 17:10:43 INFO - 'sps_profile': False, 17:10:43 INFO - 'sps_profile_interval': 0, 17:10:43 INFO - 'suite': 'dromaeojs-e10s', 17:10:43 INFO - 'system_bits': '32', 17:10:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:10:43 INFO - 'title': 'talos-linux64-ix-002', 17:10:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:10:43 INFO - 'use_talos_json': True, 17:10:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:10:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:10:43 INFO - 'work_dir': 'build'} 17:10:43 INFO - ##### 17:10:43 INFO - ##### Running clobber step. 17:10:43 INFO - ##### 17:10:43 INFO - Running pre-action listener: _resource_record_pre_action 17:10:43 INFO - Running main action method: clobber 17:10:43 INFO - rmtree: /builds/slave/test/build 17:10:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:10:44 INFO - Running post-action listener: _resource_record_post_action 17:10:44 INFO - ##### 17:10:44 INFO - ##### Finished clobber step (success) 17:10:44 INFO - ##### 17:10:44 INFO - ##### 17:10:44 INFO - ##### Running read-buildbot-config step. 17:10:44 INFO - ##### 17:10:44 INFO - Running pre-action listener: _resource_record_pre_action 17:10:44 INFO - Running main action method: read_buildbot_config 17:10:44 INFO - Using buildbot properties: 17:10:44 INFO - { 17:10:44 INFO - "project": "", 17:10:44 INFO - "product": "firefox", 17:10:44 INFO - "script_repo_revision": "production", 17:10:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 17:10:44 INFO - "repository": "", 17:10:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 17:10:44 INFO - "buildid": "20160507165048", 17:10:44 INFO - "pgo_build": "False", 17:10:44 INFO - "basedir": "/builds/slave/test", 17:10:44 INFO - "buildnumber": 1489, 17:10:44 INFO - "slavename": "talos-linux64-ix-002", 17:10:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:10:44 INFO - "platform": "ubuntu64_hw", 17:10:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:10:44 INFO - "branch": "fx-team", 17:10:44 INFO - "repo_path": "integration/fx-team", 17:10:44 INFO - "revision": "794c7ee92f4805df1b31624b79b74d4a91878d5e", 17:10:44 INFO - "stage_platform": "linux64", 17:10:44 INFO - "builduid": "130fe3b8846c4d81b0cd11182a72d8df", 17:10:44 INFO - "slavebuilddir": "test" 17:10:44 INFO - } 17:10:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:10:44 INFO - Running post-action listener: _resource_record_post_action 17:10:44 INFO - ##### 17:10:44 INFO - ##### Finished read-buildbot-config step (success) 17:10:44 INFO - ##### 17:10:44 INFO - ##### 17:10:44 INFO - ##### Running download-and-extract step. 17:10:44 INFO - ##### 17:10:44 INFO - Running pre-action listener: _resource_record_pre_action 17:10:44 INFO - Running main action method: download_and_extract 17:10:44 INFO - mkdir: /builds/slave/test/build/tests 17:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:10:44 INFO - https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:10:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:10:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:10:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:46 INFO - Downloaded 1361 bytes. 17:10:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:10:46 INFO - Using the following test package requirements: 17:10:46 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:10:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:10:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:10:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'jsshell-linux-x86_64.zip'], 17:10:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:10:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:10:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:10:46 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:10:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:10:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:10:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:10:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:10:46 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')]} 17:10:46 INFO - https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:10:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:10:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:10:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:49 INFO - Downloaded 22321825 bytes. 17:10:49 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'] 17:10:49 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 17:10:50 INFO - Return code: 0 17:10:50 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 17:10:50 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')]} 17:10:50 INFO - https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:10:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:10:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:10:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:53 INFO - Downloaded 22321825 bytes. 17:10:53 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'] 17:10:53 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 17:10:55 INFO - Return code: 0 17:10:55 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')]} 17:10:55 INFO - https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:10:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:10:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:10:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:56 INFO - Downloaded 10840152 bytes. 17:10:56 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'] 17:10:56 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 17:10:56 INFO - Return code: 0 17:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:10:56 INFO - https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:10:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:10:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:10:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOUqxarMSvyiWF1hcfDlnw/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 17:10:57 INFO - Downloaded 54844432 bytes. 17:10:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:10:57 INFO - mkdir: /builds/slave/test/properties 17:10:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:10:57 INFO - Writing to file /builds/slave/test/properties/build_url 17:10:57 INFO - Contents: 17:10:57 INFO - build_url:https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:10:58 INFO - Running post-action listener: _resource_record_post_action 17:10:58 INFO - Running post-action listener: set_extra_try_arguments 17:10:58 INFO - ##### 17:10:58 INFO - ##### Finished download-and-extract step (success) 17:10:58 INFO - ##### 17:10:58 INFO - ##### 17:10:58 INFO - ##### Running populate-webroot step. 17:10:58 INFO - ##### 17:10:58 INFO - Running pre-action listener: _resource_record_pre_action 17:10:58 INFO - Running main action method: populate_webroot 17:10:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:10:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:10:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:10:58 INFO - '--tppagecycles', 17:10:58 INFO - '7'], 17:10:58 INFO - 'tests': ['tsvgm']}}, 17:10:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:10:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:10:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:10:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:10:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:10:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:10:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['damp', 'tps']}, 17:10:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'tests': ['damp', 'tps']}, 17:10:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['dromaeo_dom']}, 17:10:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:10:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'other-e10s': {'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['a11yr', 17:10:58 INFO - 'ts_paint', 17:10:58 INFO - 'tpaint', 17:10:58 INFO - 'sessionrestore', 17:10:58 INFO - 'sessionrestore_no_auto_restore', 17:10:58 INFO - 'tabpaint']}, 17:10:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['tsvgx', 17:10:58 INFO - 'tsvgr_opacity', 17:10:58 INFO - 'tart', 17:10:58 INFO - 'tscrollx', 17:10:58 INFO - 'cart']}, 17:10:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:10:58 INFO - 'tsvgr_opacity', 17:10:58 INFO - 'tart', 17:10:58 INFO - 'tscrollx', 17:10:58 INFO - 'cart']}, 17:10:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'talos_options': ['--disable-e10s'], 17:10:58 INFO - 'tests': ['tp5o']}, 17:10:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'tests': ['tp5o']}, 17:10:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'talos_options': ['--disable-e10s', 17:10:58 INFO - '--xperf_path', 17:10:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:10:58 INFO - 'tests': ['tp5n']}, 17:10:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:58 INFO - 'talos_options': ['--xperf_path', 17:10:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:10:58 INFO - 'tests': ['tp5n']}}, 17:10:58 INFO - 'talos.zip': {'path': '', 17:10:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:10:58 INFO - Running post-action listener: _resource_record_post_action 17:10:58 INFO - ##### 17:10:58 INFO - ##### Finished populate-webroot step (success) 17:10:58 INFO - ##### 17:10:58 INFO - ##### 17:10:58 INFO - ##### Running create-virtualenv step. 17:10:58 INFO - ##### 17:10:58 INFO - Running pre-action listener: _resource_record_pre_action 17:10:58 INFO - Running main action method: create_virtualenv 17:10:58 INFO - Creating virtualenv /builds/slave/test/build/venv 17:10:58 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 17:10:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:10:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:10:58 INFO - Using real prefix '/usr' 17:10:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:10:58 INFO - Installing distribute.............................................................................................................................................................................................done. 17:11:00 INFO - Installing pip.................done. 17:11:00 INFO - Return code: 0 17:11:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:11:00 INFO - Using env: {'DISPLAY': ':0', 17:11:00 INFO - 'HOME': '/home/cltbld', 17:11:00 INFO - 'LANG': 'en_US.UTF-8', 17:11:00 INFO - 'LANGUAGE': 'en_US:en', 17:11:00 INFO - 'LOGNAME': 'cltbld', 17:11:00 INFO - 'MAIL': '/var/mail/cltbld', 17:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:00 INFO - 'MOZ_NO_REMOTE': '1', 17:11:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:00 INFO - 'NO_EM_RESTART': '1', 17:11:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:00 INFO - 'PWD': '/builds/slave/test', 17:11:00 INFO - 'SHELL': '/bin/bash', 17:11:00 INFO - 'SHLVL': '1', 17:11:00 INFO - 'TERM': 'linux', 17:11:00 INFO - 'TMOUT': '86400', 17:11:00 INFO - 'USER': 'cltbld', 17:11:00 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:00 INFO - '_': '/tools/buildbot/bin/python'} 17:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:01 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 17:11:01 INFO - Cleaning up... 17:11:01 INFO - Return code: 0 17:11:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:11: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')]} 17:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11: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')]} 17:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:01 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:01 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 17:11:01 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 17:11:01 INFO - Using env: {'DISPLAY': ':0', 17:11:01 INFO - 'HOME': '/home/cltbld', 17:11:01 INFO - 'LANG': 'en_US.UTF-8', 17:11:01 INFO - 'LANGUAGE': 'en_US:en', 17:11:01 INFO - 'LOGNAME': 'cltbld', 17:11:01 INFO - 'MAIL': '/var/mail/cltbld', 17:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:01 INFO - 'MOZ_NO_REMOTE': '1', 17:11:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:01 INFO - 'NO_EM_RESTART': '1', 17:11:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:01 INFO - 'PWD': '/builds/slave/test', 17:11:01 INFO - 'SHELL': '/bin/bash', 17:11:01 INFO - 'SHLVL': '1', 17:11:01 INFO - 'TERM': 'linux', 17:11:01 INFO - 'TMOUT': '86400', 17:11:01 INFO - 'USER': 'cltbld', 17:11:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:01 INFO - '_': '/tools/buildbot/bin/python'} 17:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:01 INFO - Downloading/unpacking psutil>=0.7.1 17:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:11:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:11:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:11:03 INFO - Installing collected packages: psutil 17:11:03 INFO - Running setup.py install for psutil 17:11:03 INFO - building 'psutil._psutil_linux' extension 17:11:03 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 17:11:03 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 17:11:03 INFO - building 'psutil._psutil_posix' extension 17:11:03 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 17:11:03 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 17:11:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:11:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:11:03 INFO - Successfully installed psutil 17:11:03 INFO - Cleaning up... 17:11:03 INFO - Return code: 0 17:11:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:11:03 INFO - Using env: {'DISPLAY': ':0', 17:11:03 INFO - 'HOME': '/home/cltbld', 17:11:03 INFO - 'LANG': 'en_US.UTF-8', 17:11:03 INFO - 'LANGUAGE': 'en_US:en', 17:11:03 INFO - 'LOGNAME': 'cltbld', 17:11:03 INFO - 'MAIL': '/var/mail/cltbld', 17:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:03 INFO - 'MOZ_NO_REMOTE': '1', 17:11:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:03 INFO - 'NO_EM_RESTART': '1', 17:11:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:03 INFO - 'PWD': '/builds/slave/test', 17:11:03 INFO - 'SHELL': '/bin/bash', 17:11:03 INFO - 'SHLVL': '1', 17:11:03 INFO - 'TERM': 'linux', 17:11:03 INFO - 'TMOUT': '86400', 17:11:03 INFO - 'USER': 'cltbld', 17:11:03 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:03 INFO - '_': '/tools/buildbot/bin/python'} 17:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:11:04 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 17:11:04 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 17:11:04 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 17:11:04 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 17:11:04 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 17:11:04 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 17:11:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:11:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:11:06 INFO - Installing collected packages: mozsystemmonitor 17:11:06 INFO - Running setup.py install for mozsystemmonitor 17:11:06 INFO - Successfully installed mozsystemmonitor 17:11:06 INFO - Cleaning up... 17:11:06 INFO - Return code: 0 17:11:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:11:06 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')]} 17:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:06 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')]} 17:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:06 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:06 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 17:11:06 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 17:11:06 INFO - Using env: {'DISPLAY': ':0', 17:11:06 INFO - 'HOME': '/home/cltbld', 17:11:06 INFO - 'LANG': 'en_US.UTF-8', 17:11:06 INFO - 'LANGUAGE': 'en_US:en', 17:11:06 INFO - 'LOGNAME': 'cltbld', 17:11:06 INFO - 'MAIL': '/var/mail/cltbld', 17:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:06 INFO - 'MOZ_NO_REMOTE': '1', 17:11:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:06 INFO - 'NO_EM_RESTART': '1', 17:11:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:06 INFO - 'PWD': '/builds/slave/test', 17:11:06 INFO - 'SHELL': '/bin/bash', 17:11:06 INFO - 'SHLVL': '1', 17:11:06 INFO - 'TERM': 'linux', 17:11:06 INFO - 'TMOUT': '86400', 17:11:06 INFO - 'USER': 'cltbld', 17:11:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:06 INFO - '_': '/tools/buildbot/bin/python'} 17:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:06 INFO - Downloading/unpacking blobuploader==1.2.4 17:11:06 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 17:11:06 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 17:11:06 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 17:11:06 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 17:11:06 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 17:11:06 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 17:11:08 INFO - Downloading blobuploader-1.2.4.tar.gz 17:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:11:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:11:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11:08 INFO - Downloading docopt-0.6.1.tar.gz 17:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:11:08 INFO - Installing collected packages: blobuploader, requests, docopt 17:11:08 INFO - Running setup.py install for blobuploader 17:11:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:11:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:11:09 INFO - Running setup.py install for requests 17:11:09 INFO - Running setup.py install for docopt 17:11:09 INFO - Successfully installed blobuploader requests docopt 17:11:09 INFO - Cleaning up... 17:11:09 INFO - Return code: 0 17:11:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:09 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:09 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 17:11:09 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 17:11:09 INFO - Using env: {'DISPLAY': ':0', 17:11:09 INFO - 'HOME': '/home/cltbld', 17:11:09 INFO - 'LANG': 'en_US.UTF-8', 17:11:09 INFO - 'LANGUAGE': 'en_US:en', 17:11:09 INFO - 'LOGNAME': 'cltbld', 17:11:09 INFO - 'MAIL': '/var/mail/cltbld', 17:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:09 INFO - 'MOZ_NO_REMOTE': '1', 17:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:09 INFO - 'NO_EM_RESTART': '1', 17:11:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:09 INFO - 'PWD': '/builds/slave/test', 17:11:09 INFO - 'SHELL': '/bin/bash', 17:11:09 INFO - 'SHLVL': '1', 17:11:09 INFO - 'TERM': 'linux', 17:11:09 INFO - 'TMOUT': '86400', 17:11:09 INFO - 'USER': 'cltbld', 17:11:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:09 INFO - '_': '/tools/buildbot/bin/python'} 17:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:11:09 INFO - Running setup.py (path:/tmp/pip-CNBz9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:11:09 INFO - Running setup.py (path:/tmp/pip-v14HEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:11:09 INFO - Running setup.py (path:/tmp/pip-s6AYIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:11:10 INFO - Running setup.py (path:/tmp/pip-LHAH8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:11:10 INFO - Running setup.py (path:/tmp/pip-TyxX8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:11:10 INFO - Running setup.py (path:/tmp/pip-0mr_MY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:11:10 INFO - Running setup.py (path:/tmp/pip-zEFk5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:11:10 INFO - Running setup.py (path:/tmp/pip-6wDzbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:11:10 INFO - Running setup.py (path:/tmp/pip-RThg8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:11:10 INFO - Running setup.py (path:/tmp/pip-HhNVke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:11:10 INFO - Running setup.py (path:/tmp/pip-z_FLjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:11:10 INFO - Running setup.py (path:/tmp/pip-uIAZaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:11:10 INFO - Running setup.py (path:/tmp/pip-aApoKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:11:10 INFO - Running setup.py (path:/tmp/pip-GJAoGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:11:10 INFO - Running setup.py (path:/tmp/pip-L1maOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:11:10 INFO - Running setup.py (path:/tmp/pip-QuLQ0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:11:10 INFO - Running setup.py (path:/tmp/pip-6gzTvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:11:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:11:11 INFO - Running setup.py install for manifestparser 17:11:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Running setup.py install for mozcrash 17:11:11 INFO - Running setup.py install for mozdebug 17:11:11 INFO - Running setup.py install for mozdevice 17:11:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Running setup.py install for mozfile 17:11:11 INFO - Running setup.py install for mozhttpd 17:11:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Running setup.py install for mozinfo 17:11:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Running setup.py install for mozInstall 17:11:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:11:11 INFO - Running setup.py install for mozleak 17:11:11 INFO - Running setup.py install for mozlog 17:11:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Running setup.py install for moznetwork 17:11:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Running setup.py install for mozprocess 17:11:12 INFO - Running setup.py install for mozprofile 17:11:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Running setup.py install for mozrunner 17:11:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Running setup.py install for mozscreenshot 17:11:12 INFO - Running setup.py install for moztest 17:11:12 INFO - Running setup.py install for mozversion 17:11:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:11:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:11:12 INFO - Cleaning up... 17:11:12 INFO - Return code: 0 17:11:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:12 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:12 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 17:11:12 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 17:11:12 INFO - Using env: {'DISPLAY': ':0', 17:11:12 INFO - 'HOME': '/home/cltbld', 17:11:12 INFO - 'LANG': 'en_US.UTF-8', 17:11:12 INFO - 'LANGUAGE': 'en_US:en', 17:11:12 INFO - 'LOGNAME': 'cltbld', 17:11:12 INFO - 'MAIL': '/var/mail/cltbld', 17:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:12 INFO - 'MOZ_NO_REMOTE': '1', 17:11:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:12 INFO - 'NO_EM_RESTART': '1', 17:11:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:12 INFO - 'PWD': '/builds/slave/test', 17:11:12 INFO - 'SHELL': '/bin/bash', 17:11:12 INFO - 'SHLVL': '1', 17:11:12 INFO - 'TERM': 'linux', 17:11:12 INFO - 'TMOUT': '86400', 17:11:12 INFO - 'USER': 'cltbld', 17:11:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:12 INFO - '_': '/tools/buildbot/bin/python'} 17:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:11:13 INFO - Running setup.py (path:/tmp/pip-bvntV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:11:13 INFO - Running setup.py (path:/tmp/pip-bEyRRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:11:13 INFO - Running setup.py (path:/tmp/pip-CLjDwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:11:13 INFO - Running setup.py (path:/tmp/pip-njUCbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:11:13 INFO - Running setup.py (path:/tmp/pip-z0Qx8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:11:13 INFO - Running setup.py (path:/tmp/pip-iJASPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:11:13 INFO - Running setup.py (path:/tmp/pip-h7jxYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:11:13 INFO - Running setup.py (path:/tmp/pip-jYQVlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:11:13 INFO - Running setup.py (path:/tmp/pip-cn2Ymg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:11:13 INFO - Running setup.py (path:/tmp/pip-lpXkGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:11:13 INFO - Running setup.py (path:/tmp/pip-icaaBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:11:13 INFO - Running setup.py (path:/tmp/pip-FgUbBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:11:13 INFO - Running setup.py (path:/tmp/pip-j2iRXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:11:13 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)) 17:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:11:13 INFO - Running setup.py (path:/tmp/pip-s6pw6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:11:14 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)) 17:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:11:14 INFO - Running setup.py (path:/tmp/pip-CUp1ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:11:14 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)) 17:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:11:14 INFO - Running setup.py (path:/tmp/pip-arNBtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:11:14 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)) 17:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:11:14 INFO - Running setup.py (path:/tmp/pip-3UvogX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:11:14 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)) 17:11:14 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)) 17:11:14 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)) 17:11:14 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)) 17:11:14 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)) 17:11:14 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)) 17:11:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:11:16 INFO - Downloading blessings-1.6.tar.gz 17:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:11:16 INFO - Installing collected packages: blessings 17:11:16 INFO - Running setup.py install for blessings 17:11:16 INFO - Successfully installed blessings 17:11:16 INFO - Cleaning up... 17:11:16 INFO - Return code: 0 17:11:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:11:16 INFO - Reading from file tmpfile_stdout 17:11:16 INFO - Current package versions: 17:11:16 INFO - argparse == 1.2.1 17:11:16 INFO - blessings == 1.6 17:11:16 INFO - blobuploader == 1.2.4 17:11:16 INFO - docopt == 0.6.1 17:11:16 INFO - manifestparser == 1.1 17:11:16 INFO - mozInstall == 1.12 17:11:16 INFO - mozcrash == 0.17 17:11:16 INFO - mozdebug == 0.1 17:11:16 INFO - mozdevice == 0.48 17:11:16 INFO - mozfile == 1.2 17:11:16 INFO - mozhttpd == 0.7 17:11:16 INFO - mozinfo == 0.9 17:11:16 INFO - mozleak == 0.1 17:11:16 INFO - mozlog == 3.1 17:11:16 INFO - moznetwork == 0.27 17:11:16 INFO - mozprocess == 0.22 17:11:16 INFO - mozprofile == 0.28 17:11:16 INFO - mozrunner == 6.11 17:11:16 INFO - mozscreenshot == 0.1 17:11:16 INFO - mozsystemmonitor == 0.0 17:11:16 INFO - moztest == 0.7 17:11:16 INFO - mozversion == 1.4 17:11:16 INFO - psutil == 3.1.1 17:11:16 INFO - requests == 1.2.3 17:11:16 INFO - wsgiref == 0.1.2 17:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:11: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')]} 17:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11: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')]} 17:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:16 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:16 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 17:11:16 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 17:11:16 INFO - Using env: {'DISPLAY': ':0', 17:11:16 INFO - 'HOME': '/home/cltbld', 17:11:16 INFO - 'LANG': 'en_US.UTF-8', 17:11:16 INFO - 'LANGUAGE': 'en_US:en', 17:11:16 INFO - 'LOGNAME': 'cltbld', 17:11:16 INFO - 'MAIL': '/var/mail/cltbld', 17:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:16 INFO - 'MOZ_NO_REMOTE': '1', 17:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:16 INFO - 'NO_EM_RESTART': '1', 17:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:16 INFO - 'PWD': '/builds/slave/test', 17:11:16 INFO - 'SHELL': '/bin/bash', 17:11:16 INFO - 'SHLVL': '1', 17:11:16 INFO - 'TERM': 'linux', 17:11:16 INFO - 'TMOUT': '86400', 17:11:16 INFO - 'USER': 'cltbld', 17:11:16 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:16 INFO - '_': '/tools/buildbot/bin/python'} 17:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 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)) 17:11:16 INFO - Cleaning up... 17:11:16 INFO - Return code: 0 17:11:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:11: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')]} 17:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11: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')]} 17:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:11:16 INFO - Using env: {'DISPLAY': ':0', 17:11:16 INFO - 'HOME': '/home/cltbld', 17:11:16 INFO - 'LANG': 'en_US.UTF-8', 17:11:16 INFO - 'LANGUAGE': 'en_US:en', 17:11:16 INFO - 'LOGNAME': 'cltbld', 17:11:16 INFO - 'MAIL': '/var/mail/cltbld', 17:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:16 INFO - 'MOZ_NO_REMOTE': '1', 17:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:16 INFO - 'NO_EM_RESTART': '1', 17:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:16 INFO - 'PWD': '/builds/slave/test', 17:11:16 INFO - 'SHELL': '/bin/bash', 17:11:16 INFO - 'SHLVL': '1', 17:11:16 INFO - 'TERM': 'linux', 17:11:16 INFO - 'TMOUT': '86400', 17:11:16 INFO - 'USER': 'cltbld', 17:11:16 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:16 INFO - '_': '/tools/buildbot/bin/python'} 17:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:17 INFO - Downloading/unpacking jsonschema 17:11:17 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 17:11:17 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 17:11:17 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 17:11:17 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 17:11:17 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 17:11:17 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 17:11:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:11:19 INFO - Downloading/unpacking functools32 (from jsonschema) 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 INFO - Downloading functools32-3.2.3-2.tar.gz 17:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:11:19 INFO - warning: no files found matching '*.txt' 17:11:19 INFO - no previously-included directories found matching 'build' 17:11:19 INFO - no previously-included directories found matching 'dist' 17:11:19 INFO - no previously-included directories found matching '.git*' 17:11:19 INFO - Installing collected packages: jsonschema, functools32 17:11:19 INFO - Running setup.py install for functools32 17:11:19 INFO - warning: no files found matching '*.txt' 17:11:19 INFO - no previously-included directories found matching 'build' 17:11:19 INFO - no previously-included directories found matching 'dist' 17:11:19 INFO - no previously-included directories found matching '.git*' 17:11:19 INFO - Successfully installed jsonschema functools32 17:11:19 INFO - Cleaning up... 17:11:19 INFO - Return code: 0 17:11:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:11: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')]} 17:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11: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')]} 17:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:11:19 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 0x7faf50368f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, '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': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', '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 17:11:19 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 17:11:19 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 17:11:19 INFO - Using env: {'DISPLAY': ':0', 17:11:19 INFO - 'HOME': '/home/cltbld', 17:11:19 INFO - 'LANG': 'en_US.UTF-8', 17:11:19 INFO - 'LANGUAGE': 'en_US:en', 17:11:19 INFO - 'LOGNAME': 'cltbld', 17:11:19 INFO - 'MAIL': '/var/mail/cltbld', 17:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:19 INFO - 'MOZ_NO_REMOTE': '1', 17:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:19 INFO - 'NO_EM_RESTART': '1', 17:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:19 INFO - 'PWD': '/builds/slave/test', 17:11:19 INFO - 'SHELL': '/bin/bash', 17:11:19 INFO - 'SHLVL': '1', 17:11:19 INFO - 'TERM': 'linux', 17:11:19 INFO - 'TMOUT': '86400', 17:11:19 INFO - 'USER': 'cltbld', 17:11:19 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:19 INFO - '_': '/tools/buildbot/bin/python'} 17:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:11:19 INFO - Downloading/unpacking flake8 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:19 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 17:11:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:11:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:11:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11: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 17:11:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:11:22 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 17:11:22 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 17:11:22 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 17:11:22 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 17:11:22 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 17:11:22 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 17:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:11:22 INFO - no previously-included directories found matching 'docs/_build' 17:11:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:11:22 INFO - Running setup.py install for pep8 17:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:11:22 INFO - no previously-included directories found matching 'docs/_build' 17:11:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:11:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:11:22 INFO - Cleaning up... 17:11:22 INFO - Return code: 0 17:11:22 INFO - Running post-action listener: _resource_record_post_action 17:11:22 INFO - Running post-action listener: _start_resource_monitoring 17:11:22 INFO - Starting resource monitoring. 17:11:22 INFO - ##### 17:11:22 INFO - ##### Finished create-virtualenv step (success) 17:11:22 INFO - ##### 17:11:22 INFO - ##### 17:11:22 INFO - ##### Running install step. 17:11:22 INFO - ##### 17:11:22 INFO - Running pre-action listener: _resource_record_pre_action 17:11:22 INFO - Running main action method: install 17:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:11:22 INFO - Reading from file tmpfile_stdout 17:11:22 INFO - Detecting whether we're running mozinstall >=1.0... 17:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:11:22 INFO - Reading from file tmpfile_stdout 17:11:22 INFO - Output received: 17:11:22 INFO - Usage: mozinstall [options] installer 17:11:22 INFO - Options: 17:11:22 INFO - -h, --help show this help message and exit 17:11:22 INFO - -d DEST, --destination=DEST 17:11:22 INFO - Directory to install application into. [default: 17:11:22 INFO - "/builds/slave/test"] 17:11:22 INFO - --app=APP Application being installed. [default: firefox] 17:11:22 INFO - mkdir: /builds/slave/test/build/application 17:11:22 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'] 17:11:22 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 17:11:38 INFO - Reading from file tmpfile_stdout 17:11:38 INFO - Output received: 17:11:38 INFO - /builds/slave/test/build/application/firefox/firefox 17:11:38 INFO - Running post-action listener: _resource_record_post_action 17:11:38 INFO - ##### 17:11:38 INFO - ##### Finished install step (success) 17:11:38 INFO - ##### 17:11:38 INFO - ##### 17:11:38 INFO - ##### Running run-tests step. 17:11:38 INFO - ##### 17:11:38 INFO - Running pre-action listener: _resource_record_pre_action 17:11:38 INFO - Running main action method: run_tests 17:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:11:38 INFO - Python 2.7.3 17:11:38 INFO - Return code: 0 17:11:38 INFO - grabbing minidump binary from tooltool 17:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:11:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291b9c0>, '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 0x291c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291cd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:11:38 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 17:11:38 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 17:11:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:11:38 INFO - Return code: 0 17:11:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:11:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:11:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:11:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:11:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:11:39 INFO - Return code: 0 17:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:11:39 INFO - Using env: {'DISPLAY': ':0', 17:11:39 INFO - 'HOME': '/home/cltbld', 17:11:39 INFO - 'LANG': 'en_US.UTF-8', 17:11:39 INFO - 'LANGUAGE': 'en_US:en', 17:11:39 INFO - 'LOGNAME': 'cltbld', 17:11:39 INFO - 'MAIL': '/var/mail/cltbld', 17:11:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:11:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:39 INFO - 'MOZ_NO_REMOTE': '1', 17:11:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:39 INFO - 'NO_EM_RESTART': '1', 17:11:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:39 INFO - 'PWD': '/builds/slave/test', 17:11:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:11:39 INFO - 'SHELL': '/bin/bash', 17:11:39 INFO - 'SHLVL': '1', 17:11:39 INFO - 'TERM': 'linux', 17:11:39 INFO - 'TMOUT': '86400', 17:11:39 INFO - 'USER': 'cltbld', 17:11:39 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647', 17:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:39 INFO - '_': '/tools/buildbot/bin/python'} 17:11:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:11:39 INFO - mozversion application_buildid: 20160507165048 17:11:39 INFO - mozversion application_changeset: 794c7ee92f4805df1b31624b79b74d4a91878d5e 17:11:39 INFO - mozversion application_display_name: Nightly 17:11:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:11:39 INFO - mozversion application_name: Firefox 17:11:39 INFO - mozversion application_remotingname: firefox 17:11:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:11:39 INFO - mozversion application_vendor: Mozilla 17:11:39 INFO - mozversion application_version: 49.0a1 17:11:39 INFO - mozversion platform_buildid: 20160507165048 17:11:39 INFO - mozversion platform_changeset: 794c7ee92f4805df1b31624b79b74d4a91878d5e 17:11:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:11:39 INFO - mozversion platform_version: 49.0a1 17:11:39 INFO - using testdate: 1462666299 17:11:39 INFO - actual date: 1462666299 17:11:39 INFO - starting webserver on 'localhost:34674' 17:11:39 INFO - SUITE-START | Running 2 tests 17:11:39 INFO - TEST-START | dromaeo_css 17:11:39 INFO - operating with platform_type : linux_ 17:11:39 INFO - Initialising browser for dromaeo_css test... 17:11:39 INFO - TEST-INFO | started process 16107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcK1XNZ/profile http://localhost:34674/getInfo.html) 17:11:43 INFO - PROCESS | 16107 | 17:11:43 INFO - PROCESS | 16107 | (process:16173): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:11:44 INFO - PROCESS | 16107 | __metrics Screen width/height:1600/1200 17:11:44 INFO - PROCESS | 16107 | colorDepth:24 17:11:44 INFO - PROCESS | 16107 | Browser inner width/height: 1024/697 17:11:44 INFO - PROCESS | 16107 | __metrics 17:11:45 ERROR - PROCESS | 16107 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 17:11:46 INFO - TEST-INFO | 16107: exit 0 17:11:46 INFO - Browser initialized. 17:11:46 INFO - Running cycle 1/1 for dromaeo_css test... 17:11:46 INFO - TEST-INFO | started process 16224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcK1XNZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:11:48 INFO - PROCESS | 16224 | 17:11:48 INFO - PROCESS | 16224 | (process:16285): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:11:58 INFO - PROCESS | 16224 | RSS: Main: 164478976 17:11:58 INFO - PROCESS | 16224 | 17:12:47 INFO - PROCESS | 16224 | 1462666367798 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] 17:14:59 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34674/tests/dromaeo/cssquery-ext.html) 17:15:00 INFO - PROCESS | 16224 | RSS: Main: 165982208 17:15:00 INFO - PROCESS | 16224 | 17:18:03 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-ext.html (next: http://localhost:34674/tests/dromaeo/cssquery-jquery.html) 17:18:03 INFO - PROCESS | 16224 | RSS: Main: 166903808 17:18:03 INFO - PROCESS | 16224 | 17:21:05 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34674/tests/dromaeo/cssquery-mootools.html) 17:21:06 INFO - PROCESS | 16224 | RSS: Main: 168144896 17:21:06 INFO - PROCESS | 16224 | 17:24:08 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34674/tests/dromaeo/cssquery-prototype.html) 17:24:08 INFO - PROCESS | 16224 | RSS: Main: 165650432 17:24:08 INFO - PROCESS | 16224 | 17:27:11 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34674/tests/dromaeo/cssquery-yui.html) 17:27:12 INFO - PROCESS | 16224 | RSS: Main: 167120896 17:27:12 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | Cycle 1(1): loaded http://localhost:34674/tests/dromaeo/cssquery-yui.html (next: http://localhost:34674/tests/dromaeo/cssquery-dojo.html) 17:30:14 INFO - PROCESS | 16224 | RSS: Main: 169148416 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | __start_tp_report 17:30:14 INFO - PROCESS | 16224 | _x_x_mozilla_page_load 17:30:14 INFO - PROCESS | 16224 | _x_x_mozilla_page_load_details 17:30:14 INFO - PROCESS | 16224 | |i|pagename|runs| 17:30:14 INFO - PROCESS | 16224 | |0;dojo.html;2616.725243849396;2624.042224588026;2629.2506635608856;2638.4854953284107;2650.059228973714;4644.3730096436975;4653.581177694007;4661.230896902016;4671.509491503394;4732.739699316537;4497.572730590588;4592.563706447891;4627.537246275371;4632.1893668607945;4654.441467023962;4220.155968806235;4257.723247988886;4348.282533381988;4358.716683415568;4364.301711726124;4308.116836048603;4330.458692663417;4330.761808100553;4367.563243675639;4367.563243675639;4158.209940111369;4185.539590645027;4214.936775948363;4259.574042595731;4325.394444777734;4233.23801715691;4240.109576988836;4244.405783190356;4247.936280955788;4271.530131685512;4255.829766809324;4272.764997925113;4278.914421711548;4317.97841010796;4321.373400856858;4570.862874113782;4571.245744452194;4634.3048542718525;4635.165670179366;4656.743879086649;4992.026554821811;5006.173981293082;5016.272640467112;5019.19692849146;5054.1407960646775;4807.543283388072;5027.5977921766535;5045.672031317989;5050.696958182521;5104.668196567211;4609.677322587387;4627.514111018362;4638.582527572467;4661.160991021615;4662.533746625379;5039.77321020555;5042.168042273057;5062.974685126551;5073.909109541466;5097.64316497849;4676.18166820805;4683.555062269079;4805.567498925114;4821.63837712166;4860.659753817199;4795.760211989386;4836.274558816135;4849.248366503198;4860.392450943632;4910.779014944336;4739.980904105634;4834.854954351375;4847.224444088999;4863.975680121647;4922.4339200992345;4648;4713.387259656222;4866.294387313819;4880.951190488121;4920.901581968341;4166.291730405838;4172.603602657743;4177.185448837508;4181.038361176947;4182.665386769051;3632.5277713897026;3683.208110256308;3737.121776382548;3741.1769410729594;3743.157789497341;4230.661547076226;4249.532551419341;4250.659947204216;4257.23369793434;4263;4807.4471435784635;4876.463589005207;4924.3352147460355;4955.0585388776735;5022.924656130161;4237.957620423756;4287.271163902082;4300.741955482681;4316.69783115179;4327.307630779061;4498.977505112453;4500.3249512573375;4507.842225522157;4518.954810451919;4571.6799824012;4636.327732478839;4655.208614535605;4671.205894997926;4680.742559159279;4702;4982.975085124696;4988.5260900214425;4990.076835785391;5002.824901128443;5015.3730783652045;4653.418322709661;4654.767261636973;4690.835820746258;4700.529947005272;4745.169595320736;4745.240761478147;4828.806847726051;4844.152273352079;4849.224124140262;4853.296272040605;4942.901141977069;4943.184374578154;4951.851444456672;4952.77712502931;4967.379077615298;4710.222813235915;4805.183118869731;4806.543378379049;4822.95177048225;4858.708477491362;4703.318018887174;4770.737609431515;4849.2726091086615;4860.538248866352;4890.951090489049;4684.625229981525;4700;4705.12955103307;4737.857864264078;4760.262159365304;4817.855464336076;4828.420589529279;4835.44392394865;4847.5394837490385;4855.077535268288;4633.629309655287;4720.362751028567;4727.550882666142;4750.976245118753;4777.880552986203 17:30:14 INFO - PROCESS | 16224 | |1;ext.html;573.7704918032787;607.8146165419547;611.9020956646938;619.6746707978313;638.9680515974206;36481.81759091208;41194.176116477654;41345.379819302696;41357;41438;39820.60179398205;40635.79682101593;40648.59351406485;40658.79670601643;40747.59252407475;7861.21387861215;7945.602719863984;7966.920330796675;7979.162187970238;7988.281054705076;7945.801354966115;7978.960105199466;8017.238362355567;8025.839483210332;8037.397195210353;7980.241877021703;7995.520268783863;8010.319122874563;8040.075391330013;8050.677972881078;7955.72154974576;8031.718889838828;8034.718784842561;8035.678572857108;8057.677692892248;7856.646450909723;7908.2882540571045;7924.326432253265;7927.128015918244;7948.761537153895;38048.80975595132;38236.42645360322;38273.23453530941;38367.42448863269;38410.42384364237;3219.7585181111513;3334.3498017886523;3347.681970212826;3374.4432168692138;3389.5932488101585;28934;29270.946138145428;29501.557476637867;30197.547036794465;30210.09369718912;3475.4439289713778;3491.423915054013;3529.0647978285774;3531;3541.8760343388117;239044;294234;296227;300095;419334.9033254845;57143.71428142833;57533.42466575364;57623.71188144032;57767;57841.42158578445;34571.82714086414;35473.46789798155;35632;37915.24169516594;60765.69617151886;15181.392744290326;15284.46504372364;15295.847041529665;15300.770488442682;15381.384744610314;10616.416097114577;10697;10757.946210269056;10758.139348852073;10766.78466430667;19253.22987080529;19319.710204346946;19334.655959062115;19376.128074236694;19413.902930485256;7371.373433258126;7372.631368431557;7409.518381305198;7493.325600695964;7618.314351708373;828.6064119543169;920.3370585071453;938.7746940734174;980.4068538534084;1097.6806909339648;28561.71438285632;28991.42017159645;29025.274368140967;29109;29201.853990730335;954.8663187153804;982.9655962041294;1075.1237741240864;1121.3664279681955;1160.1241062997383;15111.31999060045;15448.38206471751;15553;15821.367145314289;15839.841601584067;344790;346136;346201;347320;349665;50438;51594.74202628963;51866.74066629643;52126.47873521292;52129.47870521246;1299.4152631315756;1302.1666133674455;1319.769040417923;1320.7292505036444;1322.5569434239637;27797.16608501748;28507.572386414635;29136.56295155532;29361.85319073391;29568.408631827675;45555.54444455513;50055;50079;50423.49576504188;50547.945205479155;325127;329092;329302;330760;331500;39419.80290098531;39491.235613507924;40109.79945100256;40822.59177408307;40950.18099637931;44279.77860110679;44982.77508612436;45000.099997999205;45785.77107114443;45930.770346148056;37476.62523374731;37483.250334993696;37838;37907.24185516219;38183;36984;38063.61936380601;38135.42796858105;38268.425973610974;38527;37095.814520927226;37323;37334;37941.62058379492;38215.61784382121;32969.01092967215;33928.66071339255;34330.82834585811;34384.82807585946;34785.65214347824;25880.870595646902;26363;26444;26600.600990984763;26633.467330653664 17:30:14 INFO - PROCESS | 16224 | |2;jquery.html;379.3569899021159;380.53384603860275;382.4435446425451;388.6210944329281;393.1135289921227;15779.28993195306;16863;16966.40617578385;17387.913060434697;17407.216675249612;10481.37111773294;16587.91706041471;17145.91427042866;17316.13419329035;17328.56678583036;4642.442906851183;4834.685745426554;4875.975620121894;4879.219324908016;4885.780139893695;3526.2242306692488;5258.474152584749;5548.5838562108;5560.110382338827;5610.579206559504;5374.650647707906;5559.249501317331;5560.471755183271;5593.636413633101;5607.074832652607;5559.805406810742;5564.916526252109;5581.6371935824245;5584.385717571064;5589.189567512708;5156.123459011939;5369.140937450028;5400.297961265011;5414.269073675043;5470.261514695505;12643;12726.236425814388;12743;12876.80684789729;13357.933210333984;1972.7435433393566;1990.3829812757947;2005.3883565512513;2030.5126769575343;2097.769245383007;9254.907450925471;9471.337006409554;9608.855867161998;9611.134997850157;9770.26722995771;1789.7046987247088;1808.5659441734022;1833.2392057296206;1895.810418958107;1900.809919008102;66338.66830665864;69159;69502.65248673774;69782.65108674475;70760.29239707589;9348.392354496937;9501.762455938657;9750.171235445066;9838.212942964547;9877.456739879375;12918.224906505451;13003.674908127372;13011.414486348138;13020.088593798344;13094.214347139203;7050.788476345718;7099.290070992859;7110.857782844314;7303;7338.816529586803;6753.729850805913;6954.826129346706;6971.407430368374;6997.405220556309;7042.401395881408;4037.212743515045;4042.656374208166;4074.511058672919;4128.318827393507;4134.627883490501;1607.397226040235;1635.3540351561305;1744.6859565278119;1785.2948085506152;1886.6604011277827;846.9364797640202;910.1899309614316;911.3529394130242;917.866909298148;939.6523286384082;15242.23788810555;16673.24970376336;17014.744778828328;17221.483355499357;17284.827151728572;902.5261737587819;921.4195057113975;927.3091546797679;973.3867663372172;999.9300048996646;9632.711018669452;9644.614215431304;9724.124828765445;9735.610575576897;9890.060444257784;350261;356387;356588;358554;359008;72765.6361718188;75665.62167189129;77286.61356693292;86639.56680216559;89059.55470222607;961.8220629183623;980.0738302304396;995.4226548601938;1012.964546240878;1018.3737001744022;16047.277872495531;16124.355025799307;16216.513504594881;16292.51122466328;16298.837011630207;36359;36923.261534768615;36969;37002.814985924895;37214;49874.750626246634;50821.237681435545;51875;53339;53496.465035349145;5469.206964990134;7278.053852999288;7292.525985810906;7306.7077316906725;7506.812329691801;8567.871481927652;10820.783584328427;11473.099146290302;11482.253653512495;11726.179167458537;9763.365381250196;9782;9933.751656208653;10041.548130334299;10307.175425965755;7591.962040189764;9829.066238707312;9922.603095876084;10039.799204015733;10113.342632728849;9873.210143188384;9996.450195239331;10188.79622407533;10320.380777153396;10822.080123189617;6609.405153536205;6944.618046007518;6964.616946068015;7269.927300726925;7313.414926805949;9398.812023759623;10104.292699510963;10329.276950613385;10420.895791042296;10504.317219380715 17:30:14 INFO - PROCESS | 16224 | |3;mootools.html;1499.8950073494857;1529.2659523428752;1695.4405046334712;1854.5641774183073;1854.8516118710504;6647.169103862017;7146.928530714691;7322.780316590498;7657.7319793807155;7668.118166410864;7340.339369456748;7552.509086909347;7584;7591.9240807591905;7598.31615154636;2222.4999375140624;2283.0297123722435;2283.771622837711;2288.6567014947723;2288.794008539231;2204.217502786512;2237.19460994042;2240.4622890506325;2245.2141750387395;2258.785415385544;2149.8602590831624;2154.806067453929;2154.859934104286;2179.3788770200495;2213.2806837777707;2113.556153207828;2156.6549352103666;2159.9892000539976;2160.3626930055593;2167.046499540197;2108.694239335288;2121.8514703970727;2123.3205374280237;2123.575284943018;2132.115172203534;7435.776926692208;7477.214892436325;7498.512596681253;7500.174980752113;7502.62486875654;4363.2364336241035;4503.166914120898;4522.570355816192;4527.320901864714;4529.818807247706;2915.300327921305;2954.8079374840568;2958.082994271783;3020.1845501714415;3030.3181784098624;949.9762505937338;971.4705485510414;982.4007355513131;992.9354591951573;1778.36867911892;9856.408615483095;10151.543180556819;10180.694579162637;10261.846072308846;10295.639652612123;4146.709730318909;4169.124483858363;4209.284421648266;4217.704760666724;4225.091605304874;8177.591120443954;8237.341012719087;8237.917620823835;8260.586970651444;8287.875681864776;4504;4521.095780843844;4568.70303430276;4592.288195329729;4609.95390046102;3677.687396571268;4122.711410201257;4198.307279298942;4251.532331443539;4258.488981322261;3372.2412457197424;3377.915552111217;3391.033555436689;3454.360943225511;3518.4722291655944;1019.5157300282455;1023.1661196125134;1025.5897640943683;1026.5585798106886;1030.2376241581176;850.4897061762892;893.410349169558;897.52879738138;921.1341339141186;922.8108237811233;4837.419509658864;5026.698398096126;5119.702039515597;5248.553872920768;5293.153095504701;411.3336395040054;417.75352542000365;418.26594326955995;420.1009838944661;426.84846879357383;3543.6633519815578;3545.290941811648;3546.627604101584;3595.1012246938267;3652.5982141964364;8588.269997050196;8934.151255630704;8940.329475289249;9025.097490251053;9046.095390460901;8806.86789698155;8808.339374546935;8879;8924.06297338783;8927.508987005776;6754.290799466084;6868.519203655796;6908.032875397448;6953.6523173842115;6956.930430695729;5046.545810877039;5050.595952323879;5059.089363914531;5082.390113186441;5261.184516399964;8139.430239883388;8169.632366543638;8363.121872203332;8388.203120703523;8451.112633173429;8213.95893020531;8537.31701463869;8585.785355366166;8614.526201059001;8754.08082151365;6067.878642427215;6394.328595497592;6601.273859875506;6683.598984060972;6814.727410903508;7368;7374.557526548424;7421.2949769771785;7430.4798664093;7433.516821406592;6645.966770166312;6771.085903402977;6846.691898864663;6861.176658800976;6873.5875847449315;6685.498587605852;6700.83247918806;6729.2934241903895;6755.256921738703;6842.93157068423;6150.415710507495;6207.689615519115;6262.499000079892;6289.088082228142;6292.716827742855;4599.057193275304;4650.093231819898;4720.645951553579;4726.976365118152;4758.809647614056;4426.247537918497;4488.5287044861125;4515.480719717143;4574.954250457586;4597.264437689953 17:30:14 INFO - PROCESS | 16224 | |4;prototype.html;307.2211942725192;315.6575115999141;316.4556962025316;319.0333290130904;321.4422976136403;4711.340412342275;4779.856604301876;4869.220924652056;4885.193942999405;4924.089043526946;4808.495108013661;4810.615150787938;4813.903721925568;4823.493533179009;4839.588634966032;3793.7154713396535;3797.3354662934016;3797.924041519168;3809.9141744602794;3818.8663396781117;3718.219173973469;3814.179951310468;3842.692584593226;3849.40334248192;3855.5373355197275;3731.190372669253;3744.7191460640424;3749.1939233064886;3753.8123093845334;3785.9432108518395;3699.6670299673024;3739.551253849528;3773.792441415721;3777.5089238399105;3803.353429916921;3176.9682303177133;3263.5640318260125;3288;3381.9492707609406;3390.7456940729544;4674.929876051862;4705.600023997964;4715.552022557886;4719.528047195287;4726.976365118187;4213.831446742127;4241.427407300006;4251.957480425187;4273.636740877029;4296.8281268749215;4854.781534830941;5015.241617110611;5043.293938848564;5068.239764035424;5086.109930762102;3530.9823450882836;3533.8796721966874;3537.9292414151832;3542.3446662367546;3553.8223088845452;5272.789088436496;5295.126304159809;5312.309399778004;5326.547243484309;5368.892622147574;3702.555693316819;3714.1271801126713;3722.3299806034634;3733.197362567061;3744.64425879541;3500.722236383702;3722.813859307024;3738.775673459601;3741.3826718591126;3750.5311836020496;4204.33928598202;4235.9364609530885;4241.406203131564;4244.236037513278;4244.469441319835;5021.271915572222;5021.974890125526;5023.995200959823;5041.218611115283;5048.646594738333;1177.2936238256978;1212.5271144253747;1230.5939040116739;1242.726600147966;1249.762545116425;855.9529225892511;860.875173099897;869.4392117084445;871.1810897756089;878.6221924572495;4327.5239723630375;4334.241507736197;4338.219120558329;4338.436003319548;4340.370646256275;5019.196928491424;5045.394552653632;5049.823256186016;5055.183336580858;5069.188929771219;4291.1548034344905;4304.117655880599;4307.956920430818;4323.913521729611;4325.853648783075;2617.659704238437;2691.2733561938167;2691.5558932776266;2702.1083042596288;2723.891044358243;4773.284007398918;4776.546228108324;4778.211595086772;4798.976005119952;4809.759512024385;4865.7323847188745;5156.664816786972;5160.251763494272;5161.38063432383;5168.095583272912;4657.231556793159;4664.323673067386;4674.610907092331;4682.882927926829;4711.9293210602555;4932.901341973212;4933.457319694759;4937.5556199942175;4945.357103576656;4980.402351717817;4738.147133516;4837.4920633334405;4843.321934929183;4851.223804191312;4856.708597484162;4720.905581888274;4721.7403042832975;4724.622030237504;4741.36854045994;4765.380500534909;3554.2713743683;3583.4624806279267;3595.6943659789213;3603.7837729736298;3613.8193090345903;4979.879527106372;4985.351904252424;4993;5001.799928002839;5017.2724954880605;4921.212605983025;5034.496550344937;5037.168867136881;5045.697258164522;5054.3176671150395;5036.974815125901;5056.558880718977;5058.317127187783;5068.960863022998;5073.006925913729;4748.165774340643;4901.5588597026435;4941.950580494149;4945.6043516517875;4958.7024778513405;5016;5072.340595722532;5097.311862898461;5118.181091025418;5134.176203088212;4911.287863259879;4927.915858511122;4946.703197808143;4998.150314446625;5000.349954505891 17:30:14 INFO - PROCESS | 16224 | |5;yui.html;735.3161559749434;789.3527307607764;789.6841263494601;823.5676269958271;839.1985653700716;12509.437075331609;13385.933070334648;13389.66525836852;13448;13454.260015699132;12429.254244745322;12437.25376477412;12498.500059997612;12536.56122035729;12622.495100196003;665.1851481934627;832.567065126134;865.1348651348651;867.6919693508809;870.5255635678539;854.3293514591047;857.0572370392581;860.8751730999002;863.2058506174303;866.0386970462813;899.4603238057178;902.8149229407956;906.2568693671192;906.4561263242068;906.6373450619739;889.9987514046729;892.6384814150215;893.3835653399134;896.2874514760748;896.7623579751338;655.0403658640067;658.7365053978399;663.3101574362657;663.4261363920174;663.648266418794;11800.704982375424;11817.054635629129;11828.34944078084;11903.88096119045;11925.165238433314;12761.93619031899;12869.227846329157;12882.355882205853;12947.67630809228;13008.674783130498;9914.9504252479;9929.553170107363;9936.403815771077;10180.490975451272;10270.89729102707;3386.339663765567;3413.112590726429;3441.1225137590004;3453.067671728619;3526;44180.558194417965;44247;44266.33600495995;46360.53639463596;46398.30402543965;10046.598136074621;10103.19174466041;10242.6415075472;10352.792944141225;10654.147668186526;18572.535686607942;18604;18656.906715466335;18731.812681873005;18744.343947961755;6420.807375778734;6478.514111441661;6538.411542961157;6586.769463068866;6784.525083244125;3786.5645450772963;3793.5138351681485;3817.61823817616;3842.51968503937;3885.4560361549406;2925.9122226333243;3024.6370435547874;3025.667176610571;3030.31817840984;3037.0433313506173;503.39843886555946;510.3314657798296;527.0986612891921;529.6107361089596;530.0247544518105;632.8196464007827;645.2353960556756;646.5571083807607;652.5171373183905;671.771597656799;7982.1618730032515;8007.6796928123385;8144.267015968473;8199.139090395423;8215.740671315316;617.718937883262;634.5354365166244;634.6509419819081;635.7711223959371;636.859890824018;3834.9424758628643;3845.442410850467;3927.509061367329;3996.9200615987515;4011.618896204856;48493.75753121212;48839;49683.50316496861;49893.25160122601;50042.24936625954;44379.77810110929;45490.31764523535;46167.769161154654;46345.53654463479;46569;41159;41391;41552.1689566213;41646.791766041584;42323.57676423196;7009.824754381181;7186.209516953236;7298.160711518031;7417.8145546361775;7499.2500749924575;27255.72744272611;28149.71850281471;28515;28824.85587572049;28998.710012899603;35854.10364740902;35878.461823073194;35922.64077359193;36246;36478.635213647525;8380.53907035119;8450.704225352083;8622.741317760478;8640.222379985831;8755;9931;9998.400095994264;9998.800023999625;10021.498925053864;10365.378077315385;9301.906980930375;9432;9486.098820612031;9740.415575065519;9847.359921605073;9607.759806004626;9859.507024648883;9957.900420995697;10214.897851021691;10228.437435941094;9084.756558780122;9385.483798391155;9480.762980925532;9549.665761698307;9616.278779091455;7770.572618506037;7817.1791961845465;7979.441439099207;8014.19858014194;8032.83934321322;8301;8526.360522960678;8707.434016788888;8958.596863141305;8967.865482017905 17:30:14 INFO - PROCESS | 16224 | __end_tp_report 17:30:14 INFO - PROCESS | 16224 | __start_cc_report 17:30:14 INFO - PROCESS | 16224 | _x_x_mozilla_cycle_collect,367 17:30:14 INFO - PROCESS | 16224 | __end_cc_report 17:30:14 INFO - PROCESS | 16224 | __startTimestamp1462667414917__endTimestamp 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | ------- Summary: start ------- 17:30:14 INFO - PROCESS | 16224 | Number of tests: 6 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#0] dojo.html Cycles:165 Average:4570.22 Median:4680.74 stddev:461.76 (9.9%) stddev-sans-first:437.01 17:30:14 INFO - PROCESS | 16224 | Values: 2616.7 2624.0 2629.3 2638.5 2650.1 4644.4 4653.6 4661.2 4671.5 4732.7 4497.6 4592.6 4627.5 4632.2 4654.4 4220.2 4257.7 4348.3 4358.7 4364.3 4308.1 4330.5 4330.8 4367.6 4367.6 4158.2 4185.5 4214.9 4259.6 4325.4 4233.2 4240.1 4244.4 4247.9 4271.5 4255.8 4272.8 4278.9 4318.0 4321.4 4570.9 4571.2 4634.3 4635.2 4656.7 4992.0 5006.2 5016.3 5019.2 5054.1 4807.5 5027.6 5045.7 5050.7 5104.7 4609.7 4627.5 4638.6 4661.2 4662.5 5039.8 5042.2 5063.0 5073.9 5097.6 4676.2 4683.6 4805.6 4821.6 4860.7 4795.8 4836.3 4849.2 4860.4 4910.8 4740.0 4834.9 4847.2 4864.0 4922.4 4648.0 4713.4 4866.3 4881.0 4920.9 4166.3 4172.6 4177.2 4181.0 4182.7 3632.5 3683.2 3737.1 3741.2 3743.2 4230.7 4249.5 4250.7 4257.2 4263.0 4807.4 4876.5 4924.3 4955.1 5022.9 4238.0 4287.3 4300.7 4316.7 4327.3 4499.0 4500.3 4507.8 4519.0 4571.7 4636.3 4655.2 4671.2 4680.7 4702.0 4983.0 4988.5 4990.1 5002.8 5015.4 4653.4 4654.8 4690.8 4700.5 4745.2 4745.2 4828.8 4844.2 4849.2 4853.3 4942.9 4943.2 4951.9 4952.8 4967.4 4710.2 4805.2 4806.5 4823.0 4858.7 4703.3 4770.7 4849.3 4860.5 4891.0 4684.6 4700.0 4705.1 4737.9 4760.3 4817.9 4828.4 4835.4 4847.5 4855.1 4633.6 4720.4 4727.6 4751.0 4777.9 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#1] ext.html Cycles:180 Average:49166.74 Median:37928.43 stddev:86800.43 (228.9%) stddev-sans-first:86967.25 17:30:14 INFO - PROCESS | 16224 | Values: 573.8 607.8 611.9 619.7 639.0 36481.8 41194.2 41345.4 41357.0 41438.0 39820.6 40635.8 40648.6 40658.8 40747.6 7861.2 7945.6 7966.9 7979.2 7988.3 7945.8 7979.0 8017.2 8025.8 8037.4 7980.2 7995.5 8010.3 8040.1 8050.7 7955.7 8031.7 8034.7 8035.7 8057.7 7856.6 7908.3 7924.3 7927.1 7948.8 38048.8 38236.4 38273.2 38367.4 38410.4 3219.8 3334.3 3347.7 3374.4 3389.6 28934.0 29270.9 29501.6 30197.5 30210.1 3475.4 3491.4 3529.1 3531.0 3541.9 239044.0 294234.0 296227.0 300095.0 419334.9 57143.7 57533.4 57623.7 57767.0 57841.4 34571.8 35473.5 35632.0 37915.2 60765.7 15181.4 15284.5 15295.8 15300.8 15381.4 10616.4 10697.0 10757.9 10758.1 10766.8 19253.2 19319.7 19334.7 19376.1 19413.9 7371.4 7372.6 7409.5 7493.3 7618.3 828.6 920.3 938.8 980.4 1097.7 28561.7 28991.4 29025.3 29109.0 29201.9 954.9 983.0 1075.1 1121.4 1160.1 15111.3 15448.4 15553.0 15821.4 15839.8 344790.0 346136.0 346201.0 347320.0 349665.0 50438.0 51594.7 51866.7 52126.5 52129.5 1299.4 1302.2 1319.8 1320.7 1322.6 27797.2 28507.6 29136.6 29361.9 29568.4 45555.5 50055.0 50079.0 50423.5 50547.9 325127.0 329092.0 329302.0 330760.0 331500.0 39419.8 39491.2 40109.8 40822.6 40950.2 44279.8 44982.8 45000.1 45785.8 45930.8 37476.6 37483.3 37838.0 37907.2 38183.0 36984.0 38063.6 38135.4 38268.4 38527.0 37095.8 37323.0 37334.0 37941.6 38215.6 32969.0 33928.7 34330.8 34384.8 34785.7 25880.9 26363.0 26444.0 26600.6 26633.5 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#2] jquery.html Cycles:180 Average:23132.50 Median:28515.56 stddev:59207.30 (207.6%) stddev-sans-first:59348.75 17:30:14 INFO - PROCESS | 16224 | Values: 379.4 380.5 382.4 388.6 393.1 15779.3 16863.0 16966.4 17387.9 17407.2 10481.4 16587.9 17145.9 17316.1 17328.6 4642.4 4834.7 4876.0 4879.2 4885.8 3526.2 5258.5 5548.6 5560.1 5610.6 5374.7 5559.2 5560.5 5593.6 5607.1 5559.8 5564.9 5581.6 5584.4 5589.2 5156.1 5369.1 5400.3 5414.3 5470.3 12643.0 12726.2 12743.0 12876.8 13357.9 1972.7 1990.4 2005.4 2030.5 2097.8 9254.9 9471.3 9608.9 9611.1 9770.3 1789.7 1808.6 1833.2 1895.8 1900.8 66338.7 69159.0 69502.7 69782.7 70760.3 9348.4 9501.8 9750.2 9838.2 9877.5 12918.2 13003.7 13011.4 13020.1 13094.2 7050.8 7099.3 7110.9 7303.0 7338.8 6753.7 6954.8 6971.4 6997.4 7042.4 4037.2 4042.7 4074.5 4128.3 4134.6 1607.4 1635.4 1744.7 1785.3 1886.7 846.9 910.2 911.4 917.9 939.7 15242.2 16673.2 17014.7 17221.5 17284.8 902.5 921.4 927.3 973.4 999.9 9632.7 9644.6 9724.1 9735.6 9890.1 350261.0 356387.0 356588.0 358554.0 359008.0 72765.6 75665.6 77286.6 86639.6 89059.6 961.8 980.1 995.4 1013.0 1018.4 16047.3 16124.4 16216.5 16292.5 16298.8 36359.0 36923.3 36969.0 37002.8 37214.0 49874.8 50821.2 51875.0 53339.0 53496.5 5469.2 7278.1 7292.5 7306.7 7506.8 8567.9 10820.8 11473.1 11482.3 11726.2 9763.4 9782.0 9933.8 10041.5 10307.2 7592.0 9829.1 9922.6 10039.8 10113.3 9873.2 9996.5 10188.8 10320.4 10822.1 6609.4 6944.6 6964.6 7269.9 7313.4 9398.8 10104.3 10329.3 10420.9 10504.3 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#3] mootools.html Cycles:180 Average:4965.44 Median:4932.06 stddev:2686.49 (54.5%) stddev-sans-first:2681.40 17:30:14 INFO - PROCESS | 16224 | Values: 1499.9 1529.3 1695.4 1854.6 1854.9 6647.2 7146.9 7322.8 7657.7 7668.1 7340.3 7552.5 7584.0 7591.9 7598.3 2222.5 2283.0 2283.8 2288.7 2288.8 2204.2 2237.2 2240.5 2245.2 2258.8 2149.9 2154.8 2154.9 2179.4 2213.3 2113.6 2156.7 2160.0 2160.4 2167.0 2108.7 2121.9 2123.3 2123.6 2132.1 7435.8 7477.2 7498.5 7500.2 7502.6 4363.2 4503.2 4522.6 4527.3 4529.8 2915.3 2954.8 2958.1 3020.2 3030.3 950.0 971.5 982.4 992.9 1778.4 9856.4 10151.5 10180.7 10261.8 10295.6 4146.7 4169.1 4209.3 4217.7 4225.1 8177.6 8237.3 8237.9 8260.6 8287.9 4504.0 4521.1 4568.7 4592.3 4610.0 3677.7 4122.7 4198.3 4251.5 4258.5 3372.2 3377.9 3391.0 3454.4 3518.5 1019.5 1023.2 1025.6 1026.6 1030.2 850.5 893.4 897.5 921.1 922.8 4837.4 5026.7 5119.7 5248.6 5293.2 411.3 417.8 418.3 420.1 426.8 3543.7 3545.3 3546.6 3595.1 3652.6 8588.3 8934.2 8940.3 9025.1 9046.1 8806.9 8808.3 8879.0 8924.1 8927.5 6754.3 6868.5 6908.0 6953.7 6956.9 5046.5 5050.6 5059.1 5082.4 5261.2 8139.4 8169.6 8363.1 8388.2 8451.1 8214.0 8537.3 8585.8 8614.5 8754.1 6067.9 6394.3 6601.3 6683.6 6814.7 7368.0 7374.6 7421.3 7430.5 7433.5 6646.0 6771.1 6846.7 6861.2 6873.6 6685.5 6700.8 6729.3 6755.3 6842.9 6150.4 6207.7 6262.5 6289.1 6292.7 4599.1 4650.1 4720.6 4727.0 4758.8 4426.2 4488.5 4515.5 4575.0 4597.3 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#4] prototype.html Cycles:180 Average:4140.64 Median:4721.32 stddev:1193.58 (25.3%) stddev-sans-first:1161.73 17:30:14 INFO - PROCESS | 16224 | Values: 307.2 315.7 316.5 319.0 321.4 4711.3 4779.9 4869.2 4885.2 4924.1 4808.5 4810.6 4813.9 4823.5 4839.6 3793.7 3797.3 3797.9 3809.9 3818.9 3718.2 3814.2 3842.7 3849.4 3855.5 3731.2 3744.7 3749.2 3753.8 3785.9 3699.7 3739.6 3773.8 3777.5 3803.4 3177.0 3263.6 3288.0 3381.9 3390.7 4674.9 4705.6 4715.6 4719.5 4727.0 4213.8 4241.4 4252.0 4273.6 4296.8 4854.8 5015.2 5043.3 5068.2 5086.1 3531.0 3533.9 3537.9 3542.3 3553.8 5272.8 5295.1 5312.3 5326.5 5368.9 3702.6 3714.1 3722.3 3733.2 3744.6 3500.7 3722.8 3738.8 3741.4 3750.5 4204.3 4235.9 4241.4 4244.2 4244.5 5021.3 5022.0 5024.0 5041.2 5048.6 1177.3 1212.5 1230.6 1242.7 1249.8 856.0 860.9 869.4 871.2 878.6 4327.5 4334.2 4338.2 4338.4 4340.4 5019.2 5045.4 5049.8 5055.2 5069.2 4291.2 4304.1 4308.0 4323.9 4325.9 2617.7 2691.3 2691.6 2702.1 2723.9 4773.3 4776.5 4778.2 4799.0 4809.8 4865.7 5156.7 5160.3 5161.4 5168.1 4657.2 4664.3 4674.6 4682.9 4711.9 4932.9 4933.5 4937.6 4945.4 4980.4 4738.1 4837.5 4843.3 4851.2 4856.7 4720.9 4721.7 4724.6 4741.4 4765.4 3554.3 3583.5 3595.7 3603.8 3613.8 4979.9 4985.4 4993.0 5001.8 5017.3 4921.2 5034.5 5037.2 5045.7 5054.3 5037.0 5056.6 5058.3 5069.0 5073.0 4748.2 4901.6 4942.0 4945.6 4958.7 5016.0 5072.3 5097.3 5118.2 5134.2 4911.3 4927.9 4946.7 4998.2 5000.3 17:30:14 INFO - PROCESS | 16224 | 17:30:14 INFO - PROCESS | 16224 | [#5] yui.html Cycles:180 Average:12302.46 Median:573.87 stddev:13948.08 (2430.5%) stddev-sans-first:13960.16 17:30:14 INFO - PROCESS | 16224 | Values: 735.3 789.4 789.7 823.6 839.2 12509.4 13385.9 13389.7 13448.0 13454.3 12429.3 12437.3 12498.5 12536.6 12622.5 665.2 832.6 865.1 867.7 870.5 854.3 857.1 860.9 863.2 866.0 899.5 902.8 906.3 906.5 906.6 890.0 892.6 893.4 896.3 896.8 655.0 658.7 663.3 663.4 663.6 11800.7 11817.1 11828.3 11903.9 11925.2 12761.9 12869.2 12882.4 12947.7 13008.7 9915.0 9929.6 9936.4 10180.5 10270.9 3386.3 3413.1 3441.1 3453.1 3526.0 44180.6 44247.0 44266.3 46360.5 46398.3 10046.6 10103.2 10242.6 10352.8 10654.1 18572.5 18604.0 18656.9 18731.8 18744.3 6420.8 6478.5 6538.4 6586.8 6784.5 3786.6 3793.5 3817.6 3842.5 3885.5 2925.9 3024.6 3025.7 3030.3 3037.0 503.4 510.3 527.1 529.6 530.0 632.8 645.2 646.6 652.5 671.8 7982.2 8007.7 8144.3 8199.1 8215.7 617.7 634.5 634.7 635.8 636.9 3834.9 3845.4 3927.5 3996.9 4011.6 48493.8 48839.0 49683.5 49893.3 50042.2 44379.8 45490.3 46167.8 46345.5 46569.0 41159.0 41391.0 41552.2 41646.8 42323.6 7009.8 7186.2 7298.2 7417.8 7499.3 27255.7 28149.7 28515.0 28824.9 28998.7 35854.1 35878.5 35922.6 36246.0 36478.6 8380.5 8450.7 8622.7 8640.2 8755.0 9931.0 9998.4 9998.8 10021.5 10365.4 9301.9 9432.0 9486.1 9740.4 9847.4 9607.8 9859.5 9957.9 10214.9 10228.4 9084.8 9385.5 9480.8 9549.7 9616.3 7770.6 7817.2 7979.4 8014.2 8032.8 8301.0 8526.4 8707.4 8958.6 8967.9 17:30:14 INFO - PROCESS | 16224 | -------- Summary: end -------- 17:30:14 INFO - PROCESS | 16224 | 17:30:15 INFO - TEST-INFO | 16224: exit 0 17:30:15 INFO - TEST-OK | dromaeo_css | took 1115801ms 17:30:15 INFO - TEST-START | kraken 17:30:15 INFO - operating with platform_type : linux_ 17:30:15 INFO - Initialising browser for kraken test... 17:30:15 INFO - TEST-INFO | started process 16461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRtqE1U/profile http://localhost:34674/getInfo.html) 17:30:19 INFO - PROCESS | 16461 | 17:30:19 INFO - PROCESS | 16461 | (process:16528): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:30:20 INFO - PROCESS | 16461 | __metrics Screen width/height:1600/1200 17:30:20 INFO - PROCESS | 16461 | colorDepth:24 17:30:20 INFO - PROCESS | 16461 | Browser inner width/height: 1024/697 17:30:20 INFO - PROCESS | 16461 | __metrics 17:30:21 ERROR - PROCESS | 16461 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 17:30:22 INFO - TEST-INFO | 16461: exit 0 17:30:22 INFO - Browser initialized. 17:30:22 INFO - Running cycle 1/1 for kraken test... 17:30:22 INFO - TEST-INFO | started process 16579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRtqE1U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:30:24 INFO - PROCESS | 16579 | 17:30:24 INFO - PROCESS | 16579 | (process:16638): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:30:34 INFO - PROCESS | 16579 | RSS: Main: 164106240 17:30:34 INFO - PROCESS | 16579 | 17:30:36 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34674/tests/kraken/driver.html?testName=audio-beat-detection) 17:30:37 INFO - PROCESS | 16579 | RSS: Main: 168194048 17:30:37 INFO - PROCESS | 16579 | 17:30:40 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34674/tests/kraken/driver.html?testName=audio-dft) 17:30:41 INFO - PROCESS | 16579 | RSS: Main: 168996864 17:30:41 INFO - PROCESS | 16579 | 17:30:44 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34674/tests/kraken/driver.html?testName=audio-fft) 17:30:44 INFO - PROCESS | 16579 | RSS: Main: 163770368 17:30:44 INFO - PROCESS | 16579 | 17:30:46 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34674/tests/kraken/driver.html?testName=audio-oscillator) 17:30:47 INFO - PROCESS | 16579 | RSS: Main: 163901440 17:30:47 INFO - PROCESS | 16579 | 17:30:49 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34674/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:30:50 INFO - PROCESS | 16579 | RSS: Main: 164577280 17:30:50 INFO - PROCESS | 16579 | 17:30:53 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34674/tests/kraken/driver.html?testName=imaging-darkroom) 17:30:54 INFO - PROCESS | 16579 | RSS: Main: 164495360 17:30:54 INFO - PROCESS | 16579 | 17:30:57 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34674/tests/kraken/driver.html?testName=imaging-desaturate) 17:30:58 INFO - PROCESS | 16579 | RSS: Main: 162701312 17:30:58 INFO - PROCESS | 16579 | 17:31:01 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34674/tests/kraken/driver.html?testName=json-parse-financial) 17:31:01 INFO - PROCESS | 16579 | RSS: Main: 163401728 17:31:01 INFO - PROCESS | 16579 | 17:31:03 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34674/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:31:04 INFO - PROCESS | 16579 | RSS: Main: 163516416 17:31:04 INFO - PROCESS | 16579 | 17:31:05 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:31:06 INFO - PROCESS | 16579 | RSS: Main: 163635200 17:31:06 INFO - PROCESS | 16579 | 17:31:08 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:31:09 INFO - PROCESS | 16579 | RSS: Main: 163831808 17:31:09 INFO - PROCESS | 16579 | 17:31:12 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:31:12 INFO - PROCESS | 16579 | RSS: Main: 163848192 17:31:12 INFO - PROCESS | 16579 | 17:31:15 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:31:16 INFO - PROCESS | 16579 | RSS: Main: 163532800 17:31:16 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | Cycle 1(1): loaded http://localhost:34674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34674/tests/kraken/driver.html?testName=ai-astar) 17:31:18 INFO - PROCESS | 16579 | RSS: Main: 165089280 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | __start_tp_report 17:31:18 INFO - PROCESS | 16579 | _x_x_mozilla_page_load 17:31:18 INFO - PROCESS | 16579 | _x_x_mozilla_page_load_details 17:31:18 INFO - PROCESS | 16579 | |i|pagename|runs| 17:31:18 INFO - PROCESS | 16579 | |0;ai-astar;110;114;106;114;114;114;107;113;113;113 17:31:18 INFO - PROCESS | 16579 | |1;audio-beat-detection;162;132;155;178;131;161;159;134;196;137 17:31:18 INFO - PROCESS | 16579 | |2;audio-dft;161;164;162;166;169;165;164;163;162;163 17:31:18 INFO - PROCESS | 16579 | |3;audio-fft;77;76;75;154;77;78;77;77;164;77 17:31:18 INFO - PROCESS | 16579 | |4;audio-oscillator;94;93;145;93;94;93;139;92;94;142 17:31:18 INFO - PROCESS | 16579 | |5;imaging-gaussian-blur;119;118;120;118;118;118;118;119;119;118 17:31:18 INFO - PROCESS | 16579 | |6;imaging-darkroom;146;147;148;147;148;147;147;146;148;146 17:31:18 INFO - PROCESS | 16579 | |7;imaging-desaturate;95;94;95;95;96;97;95;96;95;95 17:31:18 INFO - PROCESS | 16579 | |8;json-parse-financial;69;68;69;70;68;68;68;83;67;66 17:31:18 INFO - PROCESS | 16579 | |9;json-stringify-tinderbox;79;65;64;65;66;64;64;66;64;65 17:31:18 INFO - PROCESS | 16579 | |10;stanford-crypto-aes;85;82;83;83;84;84;85;83;83;84 17:31:18 INFO - PROCESS | 16579 | |11;stanford-crypto-ccm;134;137;141;131;133;132;134;147;168;140 17:31:18 INFO - PROCESS | 16579 | |12;stanford-crypto-pbkdf2;174;177;174;174;172;175;171;181;177;181 17:31:18 INFO - PROCESS | 16579 | |13;stanford-crypto-sha256-iterative;70;70;69;67;69;70;68;68;70;68 17:31:18 INFO - PROCESS | 16579 | __end_tp_report 17:31:18 INFO - PROCESS | 16579 | __start_cc_report 17:31:18 INFO - PROCESS | 16579 | _x_x_mozilla_cycle_collect,902 17:31:18 INFO - PROCESS | 16579 | __end_cc_report 17:31:18 INFO - PROCESS | 16579 | __startTimestamp1462667478553__endTimestamp 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | ------- Summary: start ------- 17:31:18 INFO - PROCESS | 16579 | Number of tests: 14 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.50 stddev:3.05 (2.7%) stddev-sans-first:3.16 17:31:18 INFO - PROCESS | 16579 | Values: 110.0 114.0 106.0 114.0 114.0 114.0 107.0 113.0 113.0 113.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#1] audio-beat-detection Cycles:10 Average:154.50 Median:160.00 stddev:21.55 (13.5%) stddev-sans-first:22.68 17:31:18 INFO - PROCESS | 16579 | Values: 162.0 132.0 155.0 178.0 131.0 161.0 159.0 134.0 196.0 137.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:2.33 (1.4%) stddev-sans-first:2.22 17:31:18 INFO - PROCESS | 16579 | Values: 161.0 164.0 162.0 166.0 169.0 165.0 164.0 163.0 162.0 163.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#3] audio-fft Cycles:10 Average:93.20 Median:77.00 stddev:34.77 (45.2%) stddev-sans-first:36.38 17:31:18 INFO - PROCESS | 16579 | Values: 77.0 76.0 75.0 154.0 77.0 78.0 77.0 77.0 164.0 77.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#4] audio-oscillator Cycles:10 Average:107.90 Median:93.00 stddev:23.58 (25.4%) stddev-sans-first:24.47 17:31:18 INFO - PROCESS | 16579 | Values: 94.0 93.0 145.0 93.0 94.0 93.0 139.0 92.0 94.0 142.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 17:31:18 INFO - PROCESS | 16579 | Values: 119.0 118.0 120.0 118.0 118.0 118.0 118.0 119.0 119.0 118.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 17:31:18 INFO - PROCESS | 16579 | Values: 146.0 147.0 148.0 147.0 148.0 147.0 147.0 146.0 148.0 146.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 17:31:18 INFO - PROCESS | 16579 | Values: 95.0 94.0 95.0 95.0 96.0 97.0 95.0 96.0 95.0 95.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#8] json-parse-financial Cycles:10 Average:69.60 Median:68.50 stddev:4.84 (7.1%) stddev-sans-first:5.12 17:31:18 INFO - PROCESS | 16579 | Values: 69.0 68.0 69.0 70.0 68.0 68.0 68.0 83.0 67.0 66.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:65.00 stddev:4.57 (7.0%) stddev-sans-first:0.83 17:31:18 INFO - PROCESS | 16579 | Values: 79.0 65.0 64.0 65.0 66.0 64.0 64.0 66.0 64.0 65.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:0.97 (1.2%) stddev-sans-first:0.88 17:31:18 INFO - PROCESS | 16579 | Values: 85.0 82.0 83.0 83.0 84.0 84.0 85.0 83.0 83.0 84.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:138.50 stddev:11.10 (8.0%) stddev-sans-first:11.58 17:31:18 INFO - PROCESS | 16579 | Values: 134.0 137.0 141.0 131.0 133.0 132.0 134.0 147.0 168.0 140.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:176.00 stddev:3.41 (1.9%) stddev-sans-first:3.56 17:31:18 INFO - PROCESS | 16579 | Values: 174.0 177.0 174.0 174.0 172.0 175.0 171.0 181.0 177.0 181.0 17:31:18 INFO - PROCESS | 16579 | 17:31:18 INFO - PROCESS | 16579 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:1.10 (1.6%) stddev-sans-first:1.09 17:31:18 INFO - PROCESS | 16579 | Values: 70.0 70.0 69.0 67.0 69.0 70.0 68.0 68.0 70.0 68.0 17:31:18 INFO - PROCESS | 16579 | -------- Summary: end -------- 17:31:18 INFO - PROCESS | 16579 | 17:31:19 INFO - TEST-INFO | 16579: exit 0 17:31:19 INFO - TEST-OK | kraken | took 63861ms 17:31:19 INFO - SUITE-END | took 1179s 17:31:19 INFO - Completed test suite (00:19:39) 17:31:19 INFO - javascript benchmark 17:31:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2616.725243849396, 2624.042224588026, 2629.2506635608856, 2638.4854953284107, 2650.059228973714, 4644.3730096436975, 4653.581177694007, 4661.230896902016, 4671.509491503394, 4732.739699316537, 4497.572730590588, 4592.563706447891, 4627.537246275371, 4632.1893668607945, 4654.441467023962, 4220.155968806235, 4257.723247988886, 4348.282533381988, 4358.716683415568, 4364.301711726124, 4308.116836048603, 4330.458692663417, 4330.761808100553, 4367.563243675639, 4367.563243675639, 4158.209940111369, 4185.539590645027, 4214.936775948363, 4259.574042595731, 4325.394444777734, 4233.23801715691, 4240.109576988836, 4244.405783190356, 4247.936280955788, 4271.530131685512, 4255.829766809324, 4272.764997925113, 4278.914421711548, 4317.97841010796, 4321.373400856858, 4570.862874113782, 4571.245744452194, 4634.3048542718525, 4635.165670179366, 4656.743879086649, 4992.026554821811, 5006.173981293082, 5016.272640467112, 5019.19692849146, 5054.1407960646775, 4807.543283388072, 5027.5977921766535, 5045.672031317989, 5050.696958182521, 5104.668196567211, 4609.677322587387, 4627.514111018362, 4638.582527572467, 4661.160991021615, 4662.533746625379, 5039.77321020555, 5042.168042273057, 5062.974685126551, 5073.909109541466, 5097.64316497849, 4676.18166820805, 4683.555062269079, 4805.567498925114, 4821.63837712166, 4860.659753817199, 4795.760211989386, 4836.274558816135, 4849.248366503198, 4860.392450943632, 4910.779014944336, 4739.980904105634, 4834.854954351375, 4847.224444088999, 4863.975680121647, 4922.4339200992345, 4648.0, 4713.387259656222, 4866.294387313819, 4880.951190488121, 4920.901581968341, 4166.291730405838, 4172.603602657743, 4177.185448837508, 4181.038361176947, 4182.665386769051, 3632.5277713897026, 3683.208110256308, 3737.121776382548, 3741.1769410729594, 3743.157789497341, 4230.661547076226, 4249.532551419341, 4250.659947204216, 4257.23369793434, 4263.0, 4807.4471435784635, 4876.463589005207, 4924.3352147460355, 4955.0585388776735, 5022.924656130161, 4237.957620423756, 4287.271163902082, 4300.741955482681, 4316.69783115179, 4327.307630779061, 4498.977505112453, 4500.3249512573375, 4507.842225522157, 4518.954810451919, 4571.6799824012, 4636.327732478839, 4655.208614535605, 4671.205894997926, 4680.742559159279, 4702.0, 4982.975085124696, 4988.5260900214425, 4990.076835785391, 5002.824901128443, 5015.3730783652045, 4653.418322709661, 4654.767261636973, 4690.835820746258, 4700.529947005272, 4745.169595320736, 4745.240761478147, 4828.806847726051, 4844.152273352079, 4849.224124140262, 4853.296272040605, 4942.901141977069, 4943.184374578154, 4951.851444456672, 4952.77712502931, 4967.379077615298, 4710.222813235915, 4805.183118869731, 4806.543378379049, 4822.95177048225, 4858.708477491362, 4703.318018887174, 4770.737609431515, 4849.2726091086615, 4860.538248866352, 4890.951090489049, 4684.625229981525, 4700.0, 4705.12955103307, 4737.857864264078, 4760.262159365304, 4817.855464336076, 4828.420589529279, 4835.44392394865, 4847.5394837490385, 4855.077535268288, 4633.629309655287, 4720.362751028567, 4727.550882666142, 4750.976245118753, 4777.880552986203], "value": 4541.913317519351, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7704918032787, 607.8146165419547, 611.9020956646938, 619.6746707978313, 638.9680515974206, 36481.81759091208, 41194.176116477654, 41345.379819302696, 41357.0, 41438.0, 39820.60179398205, 40635.79682101593, 40648.59351406485, 40658.79670601643, 40747.59252407475, 7861.21387861215, 7945.602719863984, 7966.920330796675, 7979.162187970238, 7988.281054705076, 7945.801354966115, 7978.960105199466, 8017.238362355567, 8025.839483210332, 8037.397195210353, 7980.241877021703, 7995.520268783863, 8010.319122874563, 8040.075391330013, 8050.677972881078, 7955.72154974576, 8031.718889838828, 8034.718784842561, 8035.678572857108, 8057.677692892248, 7856.646450909723, 7908.2882540571045, 7924.326432253265, 7927.128015918244, 7948.761537153895, 38048.80975595132, 38236.42645360322, 38273.23453530941, 38367.42448863269, 38410.42384364237, 3219.7585181111513, 3334.3498017886523, 3347.681970212826, 3374.4432168692138, 3389.5932488101585, 28934.0, 29270.946138145428, 29501.557476637867, 30197.547036794465, 30210.09369718912, 3475.4439289713778, 3491.423915054013, 3529.0647978285774, 3531.0, 3541.8760343388117, 239044.0, 294234.0, 296227.0, 300095.0, 419334.9033254845, 57143.71428142833, 57533.42466575364, 57623.71188144032, 57767.0, 57841.42158578445, 34571.82714086414, 35473.46789798155, 35632.0, 37915.24169516594, 60765.69617151886, 15181.392744290326, 15284.46504372364, 15295.847041529665, 15300.770488442682, 15381.384744610314, 10616.416097114577, 10697.0, 10757.946210269056, 10758.139348852073, 10766.78466430667, 19253.22987080529, 19319.710204346946, 19334.655959062115, 19376.128074236694, 19413.902930485256, 7371.373433258126, 7372.631368431557, 7409.518381305198, 7493.325600695964, 7618.314351708373, 828.6064119543169, 920.3370585071453, 938.7746940734174, 980.4068538534084, 1097.6806909339648, 28561.71438285632, 28991.42017159645, 29025.274368140967, 29109.0, 29201.853990730335, 954.8663187153804, 982.9655962041294, 1075.1237741240864, 1121.3664279681955, 1160.1241062997383, 15111.31999060045, 15448.38206471751, 15553.0, 15821.367145314289, 15839.841601584067, 344790.0, 346136.0, 346201.0, 347320.0, 349665.0, 50438.0, 51594.74202628963, 51866.74066629643, 52126.47873521292, 52129.47870521246, 1299.4152631315756, 1302.1666133674455, 1319.769040417923, 1320.7292505036444, 1322.5569434239637, 27797.16608501748, 28507.572386414635, 29136.56295155532, 29361.85319073391, 29568.408631827675, 45555.54444455513, 50055.0, 50079.0, 50423.49576504188, 50547.945205479155, 325127.0, 329092.0, 329302.0, 330760.0, 331500.0, 39419.80290098531, 39491.235613507924, 40109.79945100256, 40822.59177408307, 40950.18099637931, 44279.77860110679, 44982.77508612436, 45000.099997999205, 45785.77107114443, 45930.770346148056, 37476.62523374731, 37483.250334993696, 37838.0, 37907.24185516219, 38183.0, 36984.0, 38063.61936380601, 38135.42796858105, 38268.425973610974, 38527.0, 37095.814520927226, 37323.0, 37334.0, 37941.62058379492, 38215.61784382121, 32969.01092967215, 33928.66071339255, 34330.82834585811, 34384.82807585946, 34785.65214347824, 25880.870595646902, 26363.0, 26444.0, 26600.600990984763, 26633.467330653664], "value": 18378.518024559275, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3569899021159, 380.53384603860275, 382.4435446425451, 388.6210944329281, 393.1135289921227, 15779.28993195306, 16863.0, 16966.40617578385, 17387.913060434697, 17407.216675249612, 10481.37111773294, 16587.91706041471, 17145.91427042866, 17316.13419329035, 17328.56678583036, 4642.442906851183, 4834.685745426554, 4875.975620121894, 4879.219324908016, 4885.780139893695, 3526.2242306692488, 5258.474152584749, 5548.5838562108, 5560.110382338827, 5610.579206559504, 5374.650647707906, 5559.249501317331, 5560.471755183271, 5593.636413633101, 5607.074832652607, 5559.805406810742, 5564.916526252109, 5581.6371935824245, 5584.385717571064, 5589.189567512708, 5156.123459011939, 5369.140937450028, 5400.297961265011, 5414.269073675043, 5470.261514695505, 12643.0, 12726.236425814388, 12743.0, 12876.80684789729, 13357.933210333984, 1972.7435433393566, 1990.3829812757947, 2005.3883565512513, 2030.5126769575343, 2097.769245383007, 9254.907450925471, 9471.337006409554, 9608.855867161998, 9611.134997850157, 9770.26722995771, 1789.7046987247088, 1808.5659441734022, 1833.2392057296206, 1895.810418958107, 1900.809919008102, 66338.66830665864, 69159.0, 69502.65248673774, 69782.65108674475, 70760.29239707589, 9348.392354496937, 9501.762455938657, 9750.171235445066, 9838.212942964547, 9877.456739879375, 12918.224906505451, 13003.674908127372, 13011.414486348138, 13020.088593798344, 13094.214347139203, 7050.788476345718, 7099.290070992859, 7110.857782844314, 7303.0, 7338.816529586803, 6753.729850805913, 6954.826129346706, 6971.407430368374, 6997.405220556309, 7042.401395881408, 4037.212743515045, 4042.656374208166, 4074.511058672919, 4128.318827393507, 4134.627883490501, 1607.397226040235, 1635.3540351561305, 1744.6859565278119, 1785.2948085506152, 1886.6604011277827, 846.9364797640202, 910.1899309614316, 911.3529394130242, 917.866909298148, 939.6523286384082, 15242.23788810555, 16673.24970376336, 17014.744778828328, 17221.483355499357, 17284.827151728572, 902.5261737587819, 921.4195057113975, 927.3091546797679, 973.3867663372172, 999.9300048996646, 9632.711018669452, 9644.614215431304, 9724.124828765445, 9735.610575576897, 9890.060444257784, 350261.0, 356387.0, 356588.0, 358554.0, 359008.0, 72765.6361718188, 75665.62167189129, 77286.61356693292, 86639.56680216559, 89059.55470222607, 961.8220629183623, 980.0738302304396, 995.4226548601938, 1012.964546240878, 1018.3737001744022, 16047.277872495531, 16124.355025799307, 16216.513504594881, 16292.51122466328, 16298.837011630207, 36359.0, 36923.261534768615, 36969.0, 37002.814985924895, 37214.0, 49874.750626246634, 50821.237681435545, 51875.0, 53339.0, 53496.465035349145, 5469.206964990134, 7278.053852999288, 7292.525985810906, 7306.7077316906725, 7506.812329691801, 8567.871481927652, 10820.783584328427, 11473.099146290302, 11482.253653512495, 11726.179167458537, 9763.365381250196, 9782.0, 9933.751656208653, 10041.548130334299, 10307.175425965755, 7591.962040189764, 9829.066238707312, 9922.603095876084, 10039.799204015733, 10113.342632728849, 9873.210143188384, 9996.450195239331, 10188.79622407533, 10320.380777153396, 10822.080123189617, 6609.405153536205, 6944.618046007518, 6964.616946068015, 7269.927300726925, 7313.414926805949, 9398.812023759623, 10104.292699510963, 10329.276950613385, 10420.895791042296, 10504.317219380715], "value": 8032.197844918541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.8950073494857, 1529.2659523428752, 1695.4405046334712, 1854.5641774183073, 1854.8516118710504, 6647.169103862017, 7146.928530714691, 7322.780316590498, 7657.7319793807155, 7668.118166410864, 7340.339369456748, 7552.509086909347, 7584.0, 7591.9240807591905, 7598.31615154636, 2222.4999375140624, 2283.0297123722435, 2283.771622837711, 2288.6567014947723, 2288.794008539231, 2204.217502786512, 2237.19460994042, 2240.4622890506325, 2245.2141750387395, 2258.785415385544, 2149.8602590831624, 2154.806067453929, 2154.859934104286, 2179.3788770200495, 2213.2806837777707, 2113.556153207828, 2156.6549352103666, 2159.9892000539976, 2160.3626930055593, 2167.046499540197, 2108.694239335288, 2121.8514703970727, 2123.3205374280237, 2123.575284943018, 2132.115172203534, 7435.776926692208, 7477.214892436325, 7498.512596681253, 7500.174980752113, 7502.62486875654, 4363.2364336241035, 4503.166914120898, 4522.570355816192, 4527.320901864714, 4529.818807247706, 2915.300327921305, 2954.8079374840568, 2958.082994271783, 3020.1845501714415, 3030.3181784098624, 949.9762505937338, 971.4705485510414, 982.4007355513131, 992.9354591951573, 1778.36867911892, 9856.408615483095, 10151.543180556819, 10180.694579162637, 10261.846072308846, 10295.639652612123, 4146.709730318909, 4169.124483858363, 4209.284421648266, 4217.704760666724, 4225.091605304874, 8177.591120443954, 8237.341012719087, 8237.917620823835, 8260.586970651444, 8287.875681864776, 4504.0, 4521.095780843844, 4568.70303430276, 4592.288195329729, 4609.95390046102, 3677.687396571268, 4122.711410201257, 4198.307279298942, 4251.532331443539, 4258.488981322261, 3372.2412457197424, 3377.915552111217, 3391.033555436689, 3454.360943225511, 3518.4722291655944, 1019.5157300282455, 1023.1661196125134, 1025.5897640943683, 1026.5585798106886, 1030.2376241581176, 850.4897061762892, 893.410349169558, 897.52879738138, 921.1341339141186, 922.8108237811233, 4837.419509658864, 5026.698398096126, 5119.702039515597, 5248.553872920768, 5293.153095504701, 411.3336395040054, 417.75352542000365, 418.26594326955995, 420.1009838944661, 426.84846879357383, 3543.6633519815578, 3545.290941811648, 3546.627604101584, 3595.1012246938267, 3652.5982141964364, 8588.269997050196, 8934.151255630704, 8940.329475289249, 9025.097490251053, 9046.095390460901, 8806.86789698155, 8808.339374546935, 8879.0, 8924.06297338783, 8927.508987005776, 6754.290799466084, 6868.519203655796, 6908.032875397448, 6953.6523173842115, 6956.930430695729, 5046.545810877039, 5050.595952323879, 5059.089363914531, 5082.390113186441, 5261.184516399964, 8139.430239883388, 8169.632366543638, 8363.121872203332, 8388.203120703523, 8451.112633173429, 8213.95893020531, 8537.31701463869, 8585.785355366166, 8614.526201059001, 8754.08082151365, 6067.878642427215, 6394.328595497592, 6601.273859875506, 6683.598984060972, 6814.727410903508, 7368.0, 7374.557526548424, 7421.2949769771785, 7430.4798664093, 7433.516821406592, 6645.966770166312, 6771.085903402977, 6846.691898864663, 6861.176658800976, 6873.5875847449315, 6685.498587605852, 6700.83247918806, 6729.2934241903895, 6755.256921738703, 6842.93157068423, 6150.415710507495, 6207.689615519115, 6262.499000079892, 6289.088082228142, 6292.716827742855, 4599.057193275304, 4650.093231819898, 4720.645951553579, 4726.976365118152, 4758.809647614056, 4426.247537918497, 4488.5287044861125, 4515.480719717143, 4574.954250457586, 4597.264437689953], "value": 4002.573534973405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.2211942725192, 315.6575115999141, 316.4556962025316, 319.0333290130904, 321.4422976136403, 4711.340412342275, 4779.856604301876, 4869.220924652056, 4885.193942999405, 4924.089043526946, 4808.495108013661, 4810.615150787938, 4813.903721925568, 4823.493533179009, 4839.588634966032, 3793.7154713396535, 3797.3354662934016, 3797.924041519168, 3809.9141744602794, 3818.8663396781117, 3718.219173973469, 3814.179951310468, 3842.692584593226, 3849.40334248192, 3855.5373355197275, 3731.190372669253, 3744.7191460640424, 3749.1939233064886, 3753.8123093845334, 3785.9432108518395, 3699.6670299673024, 3739.551253849528, 3773.792441415721, 3777.5089238399105, 3803.353429916921, 3176.9682303177133, 3263.5640318260125, 3288.0, 3381.9492707609406, 3390.7456940729544, 4674.929876051862, 4705.600023997964, 4715.552022557886, 4719.528047195287, 4726.976365118187, 4213.831446742127, 4241.427407300006, 4251.957480425187, 4273.636740877029, 4296.8281268749215, 4854.781534830941, 5015.241617110611, 5043.293938848564, 5068.239764035424, 5086.109930762102, 3530.9823450882836, 3533.8796721966874, 3537.9292414151832, 3542.3446662367546, 3553.8223088845452, 5272.789088436496, 5295.126304159809, 5312.309399778004, 5326.547243484309, 5368.892622147574, 3702.555693316819, 3714.1271801126713, 3722.3299806034634, 3733.197362567061, 3744.64425879541, 3500.722236383702, 3722.813859307024, 3738.775673459601, 3741.3826718591126, 3750.5311836020496, 4204.33928598202, 4235.9364609530885, 4241.406203131564, 4244.236037513278, 4244.469441319835, 5021.271915572222, 5021.974890125526, 5023.995200959823, 5041.218611115283, 5048.646594738333, 1177.2936238256978, 1212.5271144253747, 1230.5939040116739, 1242.726600147966, 1249.762545116425, 855.9529225892511, 860.875173099897, 869.4392117084445, 871.1810897756089, 878.6221924572495, 4327.5239723630375, 4334.241507736197, 4338.219120558329, 4338.436003319548, 4340.370646256275, 5019.196928491424, 5045.394552653632, 5049.823256186016, 5055.183336580858, 5069.188929771219, 4291.1548034344905, 4304.117655880599, 4307.956920430818, 4323.913521729611, 4325.853648783075, 2617.659704238437, 2691.2733561938167, 2691.5558932776266, 2702.1083042596288, 2723.891044358243, 4773.284007398918, 4776.546228108324, 4778.211595086772, 4798.976005119952, 4809.759512024385, 4865.7323847188745, 5156.664816786972, 5160.251763494272, 5161.38063432383, 5168.095583272912, 4657.231556793159, 4664.323673067386, 4674.610907092331, 4682.882927926829, 4711.9293210602555, 4932.901341973212, 4933.457319694759, 4937.5556199942175, 4945.357103576656, 4980.402351717817, 4738.147133516, 4837.4920633334405, 4843.321934929183, 4851.223804191312, 4856.708597484162, 4720.905581888274, 4721.7403042832975, 4724.622030237504, 4741.36854045994, 4765.380500534909, 3554.2713743683, 3583.4624806279267, 3595.6943659789213, 3603.7837729736298, 3613.8193090345903, 4979.879527106372, 4985.351904252424, 4993.0, 5001.799928002839, 5017.2724954880605, 4921.212605983025, 5034.496550344937, 5037.168867136881, 5045.697258164522, 5054.3176671150395, 5036.974815125901, 5056.558880718977, 5058.317127187783, 5068.960863022998, 5073.006925913729, 4748.165774340643, 4901.5588597026435, 4941.950580494149, 4945.6043516517875, 4958.7024778513405, 5016.0, 5072.340595722532, 5097.311862898461, 5118.181091025418, 5134.176203088212, 4911.287863259879, 4927.915858511122, 4946.703197808143, 4998.150314446625, 5000.349954505891], "value": 3765.9167984754927, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.3161559749434, 789.3527307607764, 789.6841263494601, 823.5676269958271, 839.1985653700716, 12509.437075331609, 13385.933070334648, 13389.66525836852, 13448.0, 13454.260015699132, 12429.254244745322, 12437.25376477412, 12498.500059997612, 12536.56122035729, 12622.495100196003, 665.1851481934627, 832.567065126134, 865.1348651348651, 867.6919693508809, 870.5255635678539, 854.3293514591047, 857.0572370392581, 860.8751730999002, 863.2058506174303, 866.0386970462813, 899.4603238057178, 902.8149229407956, 906.2568693671192, 906.4561263242068, 906.6373450619739, 889.9987514046729, 892.6384814150215, 893.3835653399134, 896.2874514760748, 896.7623579751338, 655.0403658640067, 658.7365053978399, 663.3101574362657, 663.4261363920174, 663.648266418794, 11800.704982375424, 11817.054635629129, 11828.34944078084, 11903.88096119045, 11925.165238433314, 12761.93619031899, 12869.227846329157, 12882.355882205853, 12947.67630809228, 13008.674783130498, 9914.9504252479, 9929.553170107363, 9936.403815771077, 10180.490975451272, 10270.89729102707, 3386.339663765567, 3413.112590726429, 3441.1225137590004, 3453.067671728619, 3526.0, 44180.558194417965, 44247.0, 44266.33600495995, 46360.53639463596, 46398.30402543965, 10046.598136074621, 10103.19174466041, 10242.6415075472, 10352.792944141225, 10654.147668186526, 18572.535686607942, 18604.0, 18656.906715466335, 18731.812681873005, 18744.343947961755, 6420.807375778734, 6478.514111441661, 6538.411542961157, 6586.769463068866, 6784.525083244125, 3786.5645450772963, 3793.5138351681485, 3817.61823817616, 3842.51968503937, 3885.4560361549406, 2925.9122226333243, 3024.6370435547874, 3025.667176610571, 3030.31817840984, 3037.0433313506173, 503.39843886555946, 510.3314657798296, 527.0986612891921, 529.6107361089596, 530.0247544518105, 632.8196464007827, 645.2353960556756, 646.5571083807607, 652.5171373183905, 671.771597656799, 7982.1618730032515, 8007.6796928123385, 8144.267015968473, 8199.139090395423, 8215.740671315316, 617.718937883262, 634.5354365166244, 634.6509419819081, 635.7711223959371, 636.859890824018, 3834.9424758628643, 3845.442410850467, 3927.509061367329, 3996.9200615987515, 4011.618896204856, 48493.75753121212, 48839.0, 49683.50316496861, 49893.25160122601, 50042.24936625954, 44379.77810110929, 45490.31764523535, 46167.769161154654, 46345.53654463479, 46569.0, 41159.0, 41391.0, 41552.1689566213, 41646.791766041584, 42323.57676423196, 7009.824754381181, 7186.209516953236, 7298.160711518031, 7417.8145546361775, 7499.2500749924575, 27255.72744272611, 28149.71850281471, 28515.0, 28824.85587572049, 28998.710012899603, 35854.10364740902, 35878.461823073194, 35922.64077359193, 36246.0, 36478.635213647525, 8380.53907035119, 8450.704225352083, 8622.741317760478, 8640.222379985831, 8755.0, 9931.0, 9998.400095994264, 9998.800023999625, 10021.498925053864, 10365.378077315385, 9301.906980930375, 9432.0, 9486.098820612031, 9740.415575065519, 9847.359921605073, 9607.759806004626, 9859.507024648883, 9957.900420995697, 10214.897851021691, 10228.437435941094, 9084.756558780122, 9385.483798391155, 9480.762980925532, 9549.665761698307, 9616.278779091455, 7770.572618506037, 7817.1791961845465, 7979.441439099207, 8014.19858014194, 8032.83934321322, 8301.0, 8526.360522960678, 8707.434016788888, 8958.596863141305, 8967.865482017905], "value": 5859.639546279635, "unit": "score"}], "name": "dromaeo_css", "value": 6243.379847265506, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 106.0, 114.0, 114.0, 114.0, 107.0, 113.0, 113.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 132.0, 155.0, 178.0, 131.0, 161.0, 159.0, 134.0, 196.0, 137.0], "value": 154.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 162.0, 166.0, 169.0, 165.0, 164.0, 163.0, 162.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 154.0, 77.0, 78.0, 77.0, 77.0, 164.0, 77.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 145.0, 93.0, 94.0, 93.0, 139.0, 92.0, 94.0, 142.0], "value": 107.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 120.0, 118.0, 118.0, 118.0, 118.0, 119.0, 119.0, 118.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0, 146.0, 148.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 96.0, 97.0, 95.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 70.0, 68.0, 68.0, 68.0, 83.0, 67.0, 66.0], "value": 69.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 65.0, 64.0, 65.0, 66.0, 64.0, 64.0, 66.0, 64.0, 65.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 83.0, 84.0, 84.0, 85.0, 83.0, 83.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 141.0, 131.0, 133.0, 132.0, 134.0, 147.0, 168.0, 140.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 174.0, 174.0, 172.0, 175.0, 171.0, 181.0, 177.0, 181.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 67.0, 69.0, 70.0, 68.0, 68.0, 70.0, 68.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1595.7, "alertThreshold": 2.0}]} 17:31:19 INFO - Return code: 0 17:31:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:31:19 ERROR - # TBPL SUCCESS # 17:31:19 INFO - Running post-action listener: _resource_record_post_action 17:31:19 INFO - ##### 17:31:19 INFO - ##### Finished run-tests step (success) 17:31:19 INFO - ##### 17:31:19 INFO - Running post-run listener: _resource_record_post_run 17:31:20 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 12288; Write bytes: 518696960; Read time: 312; Write time: 426032 17:31:20 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 234844160; Read time: 156; Write time: 273476 17:31:20 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 8192; Write bytes: 283852800; Read time: 156; Write time: 152556 17:31:20 INFO - Running post-run listener: _upload_blobber_files 17:31:20 INFO - Blob upload gear active. 17:31:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:31:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:31:20 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'] 17:31:20 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 17:31:21 INFO - (blobuploader) - INFO - Open directory for files ... 17:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:31:22 INFO - (blobuploader) - INFO - Done attempting. 17:31:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:31:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:31:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:31:22 INFO - (blobuploader) - INFO - Done attempting. 17:31:22 INFO - (blobuploader) - INFO - Iteration through files over. 17:31:22 INFO - Return code: 0 17:31:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:31:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:31:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e61e79347710a4be2215849988dd38d212930e96df7f90e152c64b389673ce66858f034ca0ecec69f7afc2020647ed905518138d6f82fd711f2dccc3ef64e3c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5ef4e2adc2250a01ace9eab770bab98cbcd20c60fcc4f7b6529fcdd759f3c8f85f52608ef8a0a67c47b64bc7561e57084c009f479e69800e82d786d965f5bf2d"} 17:31:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:31:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:31:22 INFO - Contents: 17:31:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e61e79347710a4be2215849988dd38d212930e96df7f90e152c64b389673ce66858f034ca0ecec69f7afc2020647ed905518138d6f82fd711f2dccc3ef64e3c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5ef4e2adc2250a01ace9eab770bab98cbcd20c60fcc4f7b6529fcdd759f3c8f85f52608ef8a0a67c47b64bc7561e57084c009f479e69800e82d786d965f5bf2d"} 17:31:22 INFO - Running post-run listener: copy_logs_to_upload_dir 17:31:22 INFO - Copying logs to upload dir... 17:31:22 INFO - mkdir: /builds/slave/test/build/upload/logs 17:31:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.481047 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-07 17:31:22.095754) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 17:31:22.099107) ========= 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=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e61e79347710a4be2215849988dd38d212930e96df7f90e152c64b389673ce66858f034ca0ecec69f7afc2020647ed905518138d6f82fd711f2dccc3ef64e3c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5ef4e2adc2250a01ace9eab770bab98cbcd20c60fcc4f7b6529fcdd759f3c8f85f52608ef8a0a67c47b64bc7561e57084c009f479e69800e82d786d965f5bf2d"} build_url:https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/KOUqxarMSvyiWF1hcfDlnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e61e79347710a4be2215849988dd38d212930e96df7f90e152c64b389673ce66858f034ca0ecec69f7afc2020647ed905518138d6f82fd711f2dccc3ef64e3c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5ef4e2adc2250a01ace9eab770bab98cbcd20c60fcc4f7b6529fcdd759f3c8f85f52608ef8a0a67c47b64bc7561e57084c009f479e69800e82d786d965f5bf2d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 17:31:22.128155) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:31:22.128490) ========= 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=a0d2d2de893abaa8702a9954000001a7-1462642568.207761-1957198647 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 17:31:22.213630) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 17:31:22.214423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 17:31:22.215132) ========= ========= Total master_lag: 0.18 =========