builder: larch_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-050 starttime: 1464831077.98 results: success (0) buildid: 20160601171646 builduid: 53752fd2ea174b3482276bb52bef4de6 revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.984622) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.985080) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:17.985363) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.030396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.030686) ========= 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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 18:31:18-- 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% 66.2M=0s 2016-06-01 18:31:18 (66.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.156914) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.157218) ========= 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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019679 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.207099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.207432) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 18:31:18,723 truncating revision to first 12 chars 2016-06-01 18:31:18,723 Setting DEBUG logging. 2016-06-01 18:31:18,723 attempt 1/10 2016-06-01 18:31:18,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/279597150b92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 18:31:19,033 unpacking tar archive at: larch-279597150b92/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627072 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.850612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.850904) ========= script_repo_revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.851280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.851559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 18:31:18.865004) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-06-01 18:31:18.865292) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:31:19 INFO - MultiFileLogger online at 20160601 18:31:19 in /builds/slave/test 18:31:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 18:31:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:31:19 INFO - {'append_to_log': False, 18:31:19 INFO - 'base_work_dir': '/builds/slave/test', 18:31:19 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 18:31:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:31:19 INFO - 'branch': 'Larch-Non-PGO', 18:31:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:31:19 INFO - 'config_files': ('talos/linux_config.py',), 18:31:19 INFO - 'default_actions': ('clobber', 18:31:19 INFO - 'read-buildbot-config', 18:31:19 INFO - 'download-and-extract', 18:31:19 INFO - 'populate-webroot', 18:31:19 INFO - 'create-virtualenv', 18:31:19 INFO - 'install', 18:31:19 INFO - 'run-tests'), 18:31:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:31:19 INFO - 'download_minidump_stackwalk': True, 18:31:19 INFO - 'download_symbols': 'ondemand', 18:31:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:31:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:31:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:31:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:31:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:31:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:31:19 INFO - 'installer_path': 'installer.exe', 18:31:19 INFO - 'log_level': 'info', 18:31:19 INFO - 'log_name': 'talos', 18:31:19 INFO - 'log_to_console': True, 18:31:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:31:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:31:19 INFO - 'opt_config_files': (), 18:31:19 INFO - 'pip_index': False, 18:31:19 INFO - 'sps_profile': False, 18:31:19 INFO - 'sps_profile_interval': 0, 18:31:19 INFO - 'suite': 'dromaeojs-e10s', 18:31:19 INFO - 'system_bits': '32', 18:31:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:31:19 INFO - 'title': 'talos-linux64-ix-050', 18:31:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:31:19 INFO - 'use_talos_json': True, 18:31:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:31:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:31:19 INFO - 'work_dir': 'build'} 18:31:19 INFO - ##### 18:31:19 INFO - ##### Running clobber step. 18:31:19 INFO - ##### 18:31:19 INFO - Running pre-action listener: _resource_record_pre_action 18:31:19 INFO - Running main action method: clobber 18:31:19 INFO - rmtree: /builds/slave/test/build 18:31:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:31:20 INFO - Running post-action listener: _resource_record_post_action 18:31:20 INFO - ##### 18:31:20 INFO - ##### Finished clobber step (success) 18:31:20 INFO - ##### 18:31:20 INFO - ##### 18:31:20 INFO - ##### Running read-buildbot-config step. 18:31:20 INFO - ##### 18:31:20 INFO - Running pre-action listener: _resource_record_pre_action 18:31:20 INFO - Running main action method: read_buildbot_config 18:31:20 INFO - Using buildbot properties: 18:31:20 INFO - { 18:31:20 INFO - "project": "", 18:31:20 INFO - "product": "firefox", 18:31:20 INFO - "script_repo_revision": "production", 18:31:20 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 18:31:20 INFO - "repository": "", 18:31:20 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs-e10s", 18:31:20 INFO - "buildid": "20160601171646", 18:31:20 INFO - "pgo_build": "False", 18:31:20 INFO - "basedir": "/builds/slave/test", 18:31:20 INFO - "buildnumber": 8, 18:31:20 INFO - "slavename": "talos-linux64-ix-050", 18:31:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:31:20 INFO - "platform": "ubuntu64_hw", 18:31:20 INFO - "scheduler": "tests-larch-linux64-talos", 18:31:20 INFO - "branch": "larch", 18:31:20 INFO - "repo_path": "projects/larch", 18:31:20 INFO - "revision": "279597150b922a9b762de1dc88fbf083a31a4f6f", 18:31:20 INFO - "stage_platform": "linux64", 18:31:20 INFO - "builduid": "53752fd2ea174b3482276bb52bef4de6", 18:31:20 INFO - "slavebuilddir": "test" 18:31:20 INFO - } 18:31:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:31:20 INFO - Running post-action listener: _resource_record_post_action 18:31:20 INFO - ##### 18:31:20 INFO - ##### Finished read-buildbot-config step (success) 18:31:20 INFO - ##### 18:31:20 INFO - ##### 18:31:20 INFO - ##### Running download-and-extract step. 18:31:20 INFO - ##### 18:31:20 INFO - Running pre-action listener: _resource_record_pre_action 18:31:20 INFO - Running main action method: download_and_extract 18:31:20 INFO - mkdir: /builds/slave/test/build/tests 18:31:20 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')]} 18:31:20 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:31:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:30 INFO - Downloaded 1361 bytes. 18:31:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:31:30 INFO - Using the following test package requirements: 18:31:30 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:31:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:31:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:31:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'jsshell-linux-x86_64.zip'], 18:31:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:31:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:31:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:31:30 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:31:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:31:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:31:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:31:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:30 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:37 INFO - Downloaded 22850307 bytes. 18:31:37 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'] 18:31:37 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 18:31:39 INFO - Return code: 0 18:31:39 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 18:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:39 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:31:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:31:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:42 INFO - Downloaded 22850307 bytes. 18:31:42 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'] 18:31:42 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 18:31:43 INFO - Return code: 0 18:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:43 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:44 INFO - Downloaded 10834063 bytes. 18:31:44 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'] 18:31:44 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 18:31:44 INFO - Return code: 0 18:31: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')]} 18:31:44 INFO - https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:31:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N6ox9COwQlGfduF2pP1_dw/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 18:31:47 INFO - Downloaded 55177110 bytes. 18:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:47 INFO - mkdir: /builds/slave/test/properties 18:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:47 INFO - Contents: 18:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:31:47 INFO - Running post-action listener: _resource_record_post_action 18:31:47 INFO - Running post-action listener: set_extra_try_arguments 18:31:47 INFO - ##### 18:31:47 INFO - ##### Finished download-and-extract step (success) 18:31:47 INFO - ##### 18:31:47 INFO - ##### 18:31:47 INFO - ##### Running populate-webroot step. 18:31:47 INFO - ##### 18:31:47 INFO - Running pre-action listener: _resource_record_pre_action 18:31:47 INFO - Running main action method: populate_webroot 18:31:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:47 INFO - '--tppagecycles', 18:31:47 INFO - '7'], 18:31:47 INFO - 'tests': ['tsvgm']}}, 18:31:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['damp', 'tps']}, 18:31:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'tests': ['damp', 'tps']}, 18:31:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['dromaeo_dom']}, 18:31:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['a11yr', 18:31:47 INFO - 'ts_paint', 18:31:47 INFO - 'tpaint', 18:31:47 INFO - 'sessionrestore', 18:31:47 INFO - 'sessionrestore_no_auto_restore', 18:31:47 INFO - 'tabpaint']}, 18:31:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['tsvgx', 18:31:47 INFO - 'tsvgr_opacity', 18:31:47 INFO - 'tart', 18:31:47 INFO - 'tscrollx', 18:31:47 INFO - 'cart']}, 18:31:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:47 INFO - 'tsvgr_opacity', 18:31:47 INFO - 'tart', 18:31:47 INFO - 'tscrollx', 18:31:47 INFO - 'cart']}, 18:31:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'talos_options': ['--disable-e10s'], 18:31:47 INFO - 'tests': ['tp5o']}, 18:31:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'tests': ['tp5o']}, 18:31:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'talos_options': ['--disable-e10s', 18:31:47 INFO - '--xperf_path', 18:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:47 INFO - 'tests': ['tp5n']}, 18:31:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:47 INFO - 'talos_options': ['--xperf_path', 18:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:47 INFO - 'tests': ['tp5n']}}, 18:31:47 INFO - 'talos.zip': {'path': '', 18:31:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:47 INFO - Running post-action listener: _resource_record_post_action 18:31:47 INFO - ##### 18:31:47 INFO - ##### Finished populate-webroot step (success) 18:31:47 INFO - ##### 18:31:47 INFO - ##### 18:31:47 INFO - ##### Running create-virtualenv step. 18:31:47 INFO - ##### 18:31:47 INFO - Running pre-action listener: _resource_record_pre_action 18:31:47 INFO - Running main action method: create_virtualenv 18:31:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:47 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 18:31:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:47 INFO - Using real prefix '/usr' 18:31:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:50 INFO - Installing pip.................done. 18:31:50 INFO - Return code: 0 18:31:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:50 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 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:31:50 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 18:31:50 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 18:31:50 INFO - Using env: {'DISPLAY': ':0', 18:31:50 INFO - 'HOME': '/home/cltbld', 18:31:50 INFO - 'LANG': 'en_US.UTF-8', 18:31:50 INFO - 'LANGUAGE': 'en_US:en', 18:31:50 INFO - 'LOGNAME': 'cltbld', 18:31:50 INFO - 'MAIL': '/var/mail/cltbld', 18:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:50 INFO - 'MOZ_NO_REMOTE': '1', 18:31:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:50 INFO - 'NO_EM_RESTART': '1', 18:31:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:50 INFO - 'PWD': '/builds/slave/test', 18:31:50 INFO - 'SHELL': '/bin/bash', 18:31:50 INFO - 'SHLVL': '1', 18:31:50 INFO - 'TERM': 'linux', 18:31:50 INFO - 'TMOUT': '86400', 18:31:50 INFO - 'USER': 'cltbld', 18:31:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:50 INFO - '_': '/tools/buildbot/bin/python'} 18:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:50 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 18:31:50 INFO - Cleaning up... 18:31:50 INFO - Return code: 0 18:31:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:50 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:31:50 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>=3.1.1'] in /builds/slave/test/build 18:31:50 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>=3.1.1 18:31:50 INFO - Using env: {'DISPLAY': ':0', 18:31:50 INFO - 'HOME': '/home/cltbld', 18:31:50 INFO - 'LANG': 'en_US.UTF-8', 18:31:50 INFO - 'LANGUAGE': 'en_US:en', 18:31:50 INFO - 'LOGNAME': 'cltbld', 18:31:50 INFO - 'MAIL': '/var/mail/cltbld', 18:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:50 INFO - 'MOZ_NO_REMOTE': '1', 18:31:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:50 INFO - 'NO_EM_RESTART': '1', 18:31:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:50 INFO - 'PWD': '/builds/slave/test', 18:31:50 INFO - 'SHELL': '/bin/bash', 18:31:50 INFO - 'SHLVL': '1', 18:31:50 INFO - 'TERM': 'linux', 18:31:50 INFO - 'TMOUT': '86400', 18:31:50 INFO - 'USER': 'cltbld', 18:31:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:50 INFO - '_': '/tools/buildbot/bin/python'} 18:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:50 INFO - Downloading/unpacking psutil>=3.1.1 18:31:50 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 18:31:50 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 18:31:50 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 18:31:50 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 18:31:50 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 18:31:50 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 18:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:52 INFO - Installing collected packages: psutil 18:31:52 INFO - Running setup.py install for psutil 18:31:52 INFO - building 'psutil._psutil_linux' extension 18:31:52 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 18:31:53 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 18:31:53 INFO - building 'psutil._psutil_posix' extension 18:31:53 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 18:31:53 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 18:31:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:53 INFO - Successfully installed psutil 18:31:53 INFO - Cleaning up... 18:31:53 INFO - Return code: 0 18:31:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:31:53 INFO - Using env: {'DISPLAY': ':0', 18:31:53 INFO - 'HOME': '/home/cltbld', 18:31:53 INFO - 'LANG': 'en_US.UTF-8', 18:31:53 INFO - 'LANGUAGE': 'en_US:en', 18:31:53 INFO - 'LOGNAME': 'cltbld', 18:31:53 INFO - 'MAIL': '/var/mail/cltbld', 18:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:53 INFO - 'MOZ_NO_REMOTE': '1', 18:31:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:53 INFO - 'NO_EM_RESTART': '1', 18:31:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:53 INFO - 'PWD': '/builds/slave/test', 18:31:53 INFO - 'SHELL': '/bin/bash', 18:31:53 INFO - 'SHLVL': '1', 18:31:53 INFO - 'TERM': 'linux', 18:31:53 INFO - 'TMOUT': '86400', 18:31:53 INFO - 'USER': 'cltbld', 18:31:53 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:53 INFO - '_': '/tools/buildbot/bin/python'} 18:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:53 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 18:31:53 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 18:31:53 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 18:31:53 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 18:31:53 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 18:31:53 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 18:31:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:31:55 INFO - Installing collected packages: mozsystemmonitor 18:31:55 INFO - Running setup.py install for mozsystemmonitor 18:31:55 INFO - Successfully installed mozsystemmonitor 18:31:55 INFO - Cleaning up... 18:31:55 INFO - Return code: 0 18:31:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:55 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 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:31:55 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 18:31:55 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 18:31:55 INFO - Using env: {'DISPLAY': ':0', 18:31:55 INFO - 'HOME': '/home/cltbld', 18:31:55 INFO - 'LANG': 'en_US.UTF-8', 18:31:55 INFO - 'LANGUAGE': 'en_US:en', 18:31:55 INFO - 'LOGNAME': 'cltbld', 18:31:55 INFO - 'MAIL': '/var/mail/cltbld', 18:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:55 INFO - 'MOZ_NO_REMOTE': '1', 18:31:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:55 INFO - 'NO_EM_RESTART': '1', 18:31:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:55 INFO - 'PWD': '/builds/slave/test', 18:31:55 INFO - 'SHELL': '/bin/bash', 18:31:55 INFO - 'SHLVL': '1', 18:31:55 INFO - 'TERM': 'linux', 18:31:55 INFO - 'TMOUT': '86400', 18:31:55 INFO - 'USER': 'cltbld', 18:31:55 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:55 INFO - '_': '/tools/buildbot/bin/python'} 18:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:55 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 18:31:55 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 18:31:55 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 18:31:55 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 18:31:55 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 18:31:55 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 18:31:57 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:57 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 18:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:58 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 18:31:58 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 18:31:58 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 18:31:58 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 18:31:58 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 18:31:58 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 18:31:58 INFO - Downloading docopt-0.6.1.tar.gz 18:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:58 INFO - Running setup.py install for blobuploader 18:31:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:58 INFO - Running setup.py install for requests 18:31:59 INFO - Running setup.py install for docopt 18:31:59 INFO - Successfully installed blobuploader requests docopt 18:31:59 INFO - Cleaning up... 18:31:59 INFO - Return code: 0 18:31:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:59 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 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:31:59 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 18:31:59 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 18:31:59 INFO - Using env: {'DISPLAY': ':0', 18:31:59 INFO - 'HOME': '/home/cltbld', 18:31:59 INFO - 'LANG': 'en_US.UTF-8', 18:31:59 INFO - 'LANGUAGE': 'en_US:en', 18:31:59 INFO - 'LOGNAME': 'cltbld', 18:31:59 INFO - 'MAIL': '/var/mail/cltbld', 18:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:59 INFO - 'MOZ_NO_REMOTE': '1', 18:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:59 INFO - 'NO_EM_RESTART': '1', 18:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:59 INFO - 'PWD': '/builds/slave/test', 18:31:59 INFO - 'SHELL': '/bin/bash', 18:31:59 INFO - 'SHLVL': '1', 18:31:59 INFO - 'TERM': 'linux', 18:31:59 INFO - 'TMOUT': '86400', 18:31:59 INFO - 'USER': 'cltbld', 18:31:59 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:59 INFO - '_': '/tools/buildbot/bin/python'} 18:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:59 INFO - Running setup.py (path:/tmp/pip-jzBs1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:59 INFO - Running setup.py (path:/tmp/pip-VPjWp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:59 INFO - Running setup.py (path:/tmp/pip-QQPoOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:59 INFO - Running setup.py (path:/tmp/pip-fBb6Yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:59 INFO - Running setup.py (path:/tmp/pip-1feQgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:59 INFO - Running setup.py (path:/tmp/pip-YIu2j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:59 INFO - Running setup.py (path:/tmp/pip-ztQknw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:59 INFO - Running setup.py (path:/tmp/pip-DBz8F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:00 INFO - Running setup.py (path:/tmp/pip-Q7UjOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:00 INFO - Running setup.py (path:/tmp/pip-6LXWus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:00 INFO - Running setup.py (path:/tmp/pip-IVTjJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:00 INFO - Running setup.py (path:/tmp/pip-OjZbTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:00 INFO - Running setup.py (path:/tmp/pip-sqydln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:00 INFO - Running setup.py (path:/tmp/pip-9IrUZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:00 INFO - Running setup.py (path:/tmp/pip-QwFzua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:00 INFO - Running setup.py (path:/tmp/pip-BBgiwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:00 INFO - Running setup.py (path:/tmp/pip-dJFqhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:32:00 INFO - Running setup.py install for manifestparser 18:32:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:32:00 INFO - Running setup.py install for mozcrash 18:32:00 INFO - Running setup.py install for mozdebug 18:32:00 INFO - Running setup.py install for mozdevice 18:32:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for mozfile 18:32:01 INFO - Running setup.py install for mozhttpd 18:32:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for mozinfo 18:32:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for mozInstall 18:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for mozleak 18:32:01 INFO - Running setup.py install for mozlog 18:32:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for moznetwork 18:32:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:32:01 INFO - Running setup.py install for mozprocess 18:32:01 INFO - Running setup.py install for mozprofile 18:32:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:32:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:32:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:32:02 INFO - Running setup.py install for mozrunner 18:32:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:32:02 INFO - Running setup.py install for mozscreenshot 18:32:02 INFO - Running setup.py install for moztest 18:32:02 INFO - Running setup.py install for mozversion 18:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:32:02 INFO - Cleaning up... 18:32:02 INFO - Return code: 0 18:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:02 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 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:32:02 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 18:32:02 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 18:32:02 INFO - Using env: {'DISPLAY': ':0', 18:32:02 INFO - 'HOME': '/home/cltbld', 18:32:02 INFO - 'LANG': 'en_US.UTF-8', 18:32:02 INFO - 'LANGUAGE': 'en_US:en', 18:32:02 INFO - 'LOGNAME': 'cltbld', 18:32:02 INFO - 'MAIL': '/var/mail/cltbld', 18:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:02 INFO - 'MOZ_NO_REMOTE': '1', 18:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:02 INFO - 'NO_EM_RESTART': '1', 18:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:02 INFO - 'PWD': '/builds/slave/test', 18:32:02 INFO - 'SHELL': '/bin/bash', 18:32:02 INFO - 'SHLVL': '1', 18:32:02 INFO - 'TERM': 'linux', 18:32:02 INFO - 'TMOUT': '86400', 18:32:02 INFO - 'USER': 'cltbld', 18:32:02 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:02 INFO - '_': '/tools/buildbot/bin/python'} 18:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:32:02 INFO - Running setup.py (path:/tmp/pip-zkrS32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:32:02 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)) 18:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:32:02 INFO - Running setup.py (path:/tmp/pip-at0ZtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:32:02 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)) 18:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:32:02 INFO - Running setup.py (path:/tmp/pip-xjBhgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:32:02 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)) 18:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:32:02 INFO - Running setup.py (path:/tmp/pip-7W4CAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:32:03 INFO - Running setup.py (path:/tmp/pip-2DL39i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:32:03 INFO - Running setup.py (path:/tmp/pip-meuOOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:32:03 INFO - Running setup.py (path:/tmp/pip-RfYd4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:32:03 INFO - Running setup.py (path:/tmp/pip-aNRhzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:03 INFO - Running setup.py (path:/tmp/pip-abJQxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:03 INFO - Running setup.py (path:/tmp/pip-S1cmaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:03 INFO - Running setup.py (path:/tmp/pip-t16Hb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:03 INFO - Running setup.py (path:/tmp/pip-sPYJTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:03 INFO - Running setup.py (path:/tmp/pip-LPEm6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:03 INFO - Running setup.py (path:/tmp/pip-24J3mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:03 INFO - Running setup.py (path:/tmp/pip-Tf8V7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:03 INFO - Running setup.py (path:/tmp/pip-ObhE7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:03 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)) 18:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:03 INFO - Running setup.py (path:/tmp/pip-nz04th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:03 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)) 18:32:03 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)) 18:32:03 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)) 18:32:03 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)) 18:32:03 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)) 18:32:03 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)) 18:32:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:32:03 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 18:32:03 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 18:32:03 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 18:32:03 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 18:32:03 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 18:32:03 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 18:32:05 INFO - Downloading blessings-1.6.tar.gz 18:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:32:05 INFO - Installing collected packages: blessings 18:32:05 INFO - Running setup.py install for blessings 18:32:06 INFO - Successfully installed blessings 18:32:06 INFO - Cleaning up... 18:32:06 INFO - Return code: 0 18:32:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:06 INFO - Reading from file tmpfile_stdout 18:32:06 INFO - Current package versions: 18:32:06 INFO - argparse == 1.2.1 18:32:06 INFO - blessings == 1.6 18:32:06 INFO - blobuploader == 1.2.4 18:32:06 INFO - docopt == 0.6.1 18:32:06 INFO - manifestparser == 1.1 18:32:06 INFO - mozInstall == 1.12 18:32:06 INFO - mozcrash == 0.17 18:32:06 INFO - mozdebug == 0.1 18:32:06 INFO - mozdevice == 0.48 18:32:06 INFO - mozfile == 1.2 18:32:06 INFO - mozhttpd == 0.7 18:32:06 INFO - mozinfo == 0.9 18:32:06 INFO - mozleak == 0.1 18:32:06 INFO - mozlog == 3.2 18:32:06 INFO - moznetwork == 0.27 18:32:06 INFO - mozprocess == 0.22 18:32:06 INFO - mozprofile == 0.28 18:32:06 INFO - mozrunner == 6.11 18:32:06 INFO - mozscreenshot == 0.1 18:32:06 INFO - mozsystemmonitor == 0.3 18:32:06 INFO - moztest == 0.7 18:32:06 INFO - mozversion == 1.4 18:32:06 INFO - psutil == 3.1.1 18:32:06 INFO - requests == 1.2.3 18:32:06 INFO - wsgiref == 0.1.2 18:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32: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')]} 18:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32: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')]} 18:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:06 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 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:32:06 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 18:32:06 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 18:32:06 INFO - Using env: {'DISPLAY': ':0', 18:32:06 INFO - 'HOME': '/home/cltbld', 18:32:06 INFO - 'LANG': 'en_US.UTF-8', 18:32:06 INFO - 'LANGUAGE': 'en_US:en', 18:32:06 INFO - 'LOGNAME': 'cltbld', 18:32:06 INFO - 'MAIL': '/var/mail/cltbld', 18:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:06 INFO - 'MOZ_NO_REMOTE': '1', 18:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:06 INFO - 'NO_EM_RESTART': '1', 18:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:06 INFO - 'PWD': '/builds/slave/test', 18:32:06 INFO - 'SHELL': '/bin/bash', 18:32:06 INFO - 'SHLVL': '1', 18:32:06 INFO - 'TERM': 'linux', 18:32:06 INFO - 'TMOUT': '86400', 18:32:06 INFO - 'USER': 'cltbld', 18:32:06 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:06 INFO - '_': '/tools/buildbot/bin/python'} 18:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 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)) 18:32:06 INFO - Cleaning up... 18:32:06 INFO - Return code: 0 18:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:32: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')]} 18:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32: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')]} 18:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:32: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', 'jsonschema'] in /builds/slave/test/build 18:32: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 jsonschema 18:32:06 INFO - Using env: {'DISPLAY': ':0', 18:32:06 INFO - 'HOME': '/home/cltbld', 18:32:06 INFO - 'LANG': 'en_US.UTF-8', 18:32:06 INFO - 'LANGUAGE': 'en_US:en', 18:32:06 INFO - 'LOGNAME': 'cltbld', 18:32:06 INFO - 'MAIL': '/var/mail/cltbld', 18:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:06 INFO - 'MOZ_NO_REMOTE': '1', 18:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:06 INFO - 'NO_EM_RESTART': '1', 18:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:06 INFO - 'PWD': '/builds/slave/test', 18:32:06 INFO - 'SHELL': '/bin/bash', 18:32:06 INFO - 'SHLVL': '1', 18:32:06 INFO - 'TERM': 'linux', 18:32:06 INFO - 'TMOUT': '86400', 18:32:06 INFO - 'USER': 'cltbld', 18:32:06 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:06 INFO - '_': '/tools/buildbot/bin/python'} 18:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:06 INFO - Downloading/unpacking jsonschema 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:32:08 INFO - Downloading/unpacking functools32 (from jsonschema) 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:32:08 INFO - warning: no files found matching '*.txt' 18:32:08 INFO - no previously-included directories found matching 'build' 18:32:08 INFO - no previously-included directories found matching 'dist' 18:32:08 INFO - no previously-included directories found matching '.git*' 18:32:08 INFO - Installing collected packages: jsonschema, functools32 18:32:08 INFO - Running setup.py install for functools32 18:32:09 INFO - warning: no files found matching '*.txt' 18:32:09 INFO - no previously-included directories found matching 'build' 18:32:09 INFO - no previously-included directories found matching 'dist' 18:32:09 INFO - no previously-included directories found matching '.git*' 18:32:09 INFO - Successfully installed jsonschema functools32 18:32:09 INFO - Cleaning up... 18:32:09 INFO - Return code: 0 18:32:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:32: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')]} 18:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32: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')]} 18:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8589fbdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe08770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', '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 18:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:32:09 INFO - Using env: {'DISPLAY': ':0', 18:32:09 INFO - 'HOME': '/home/cltbld', 18:32:09 INFO - 'LANG': 'en_US.UTF-8', 18:32:09 INFO - 'LANGUAGE': 'en_US:en', 18:32:09 INFO - 'LOGNAME': 'cltbld', 18:32:09 INFO - 'MAIL': '/var/mail/cltbld', 18:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:09 INFO - 'MOZ_NO_REMOTE': '1', 18:32:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:09 INFO - 'NO_EM_RESTART': '1', 18:32:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:09 INFO - 'PWD': '/builds/slave/test', 18:32:09 INFO - 'SHELL': '/bin/bash', 18:32:09 INFO - 'SHLVL': '1', 18:32:09 INFO - 'TERM': 'linux', 18:32:09 INFO - 'TMOUT': '86400', 18:32:09 INFO - 'USER': 'cltbld', 18:32:09 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:09 INFO - '_': '/tools/buildbot/bin/python'} 18:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:09 INFO - Downloading/unpacking flake8 18:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:32:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:32:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:11 INFO - no previously-included directories found matching 'docs/_build' 18:32:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:32:12 INFO - Running setup.py install for pep8 18:32:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:12 INFO - no previously-included directories found matching 'docs/_build' 18:32:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:32:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:32:12 INFO - Cleaning up... 18:32:12 INFO - Return code: 0 18:32:12 INFO - Running post-action listener: _resource_record_post_action 18:32:12 INFO - Running post-action listener: _start_resource_monitoring 18:32:12 INFO - Starting resource monitoring. 18:32:12 INFO - ##### 18:32:12 INFO - ##### Finished create-virtualenv step (success) 18:32:12 INFO - ##### 18:32:12 INFO - ##### 18:32:12 INFO - ##### Running install step. 18:32:12 INFO - ##### 18:32:12 INFO - Running pre-action listener: _resource_record_pre_action 18:32:12 INFO - Running main action method: install 18:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:12 INFO - Reading from file tmpfile_stdout 18:32:12 INFO - Detecting whether we're running mozinstall >=1.0... 18:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:32:12 INFO - Reading from file tmpfile_stdout 18:32:12 INFO - Output received: 18:32:12 INFO - Usage: mozinstall [options] installer 18:32:12 INFO - Options: 18:32:12 INFO - -h, --help show this help message and exit 18:32:12 INFO - -d DEST, --destination=DEST 18:32:12 INFO - Directory to install application into. [default: 18:32:12 INFO - "/builds/slave/test"] 18:32:12 INFO - --app=APP Application being installed. [default: firefox] 18:32:12 INFO - mkdir: /builds/slave/test/build/application 18:32:12 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'] 18:32:12 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 18:32:28 INFO - Reading from file tmpfile_stdout 18:32:28 INFO - Output received: 18:32:28 INFO - /builds/slave/test/build/application/firefox/firefox 18:32:28 INFO - Running post-action listener: _resource_record_post_action 18:32:28 INFO - ##### 18:32:28 INFO - ##### Finished install step (success) 18:32:28 INFO - ##### 18:32:28 INFO - ##### 18:32:28 INFO - ##### Running run-tests step. 18:32:28 INFO - ##### 18:32:28 INFO - Running pre-action listener: _resource_record_pre_action 18:32:28 INFO - Running main action method: run_tests 18:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:28 INFO - Python 2.7.3 18:32:28 INFO - Return code: 0 18:32:28 INFO - grabbing minidump binary from tooltool 18:32:28 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')]} 18:32:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe979c0>, '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 0xe988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe98d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:28 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 18:32:28 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 18:32:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:32:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:28 INFO - Return code: 0 18:32:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:32:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:32:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:32:29 INFO - Return code: 0 18:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 18:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 18:32:29 INFO - Using env: {'DISPLAY': ':0', 18:32:29 INFO - 'HOME': '/home/cltbld', 18:32:29 INFO - 'LANG': 'en_US.UTF-8', 18:32:29 INFO - 'LANGUAGE': 'en_US:en', 18:32:29 INFO - 'LOGNAME': 'cltbld', 18:32:29 INFO - 'MAIL': '/var/mail/cltbld', 18:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:29 INFO - 'MOZ_NO_REMOTE': '1', 18:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:32:29 INFO - 'NO_EM_RESTART': '1', 18:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:29 INFO - 'PWD': '/builds/slave/test', 18:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:29 INFO - 'SHELL': '/bin/bash', 18:32:29 INFO - 'SHLVL': '1', 18:32:29 INFO - 'TERM': 'linux', 18:32:29 INFO - 'TMOUT': '86400', 18:32:29 INFO - 'USER': 'cltbld', 18:32:29 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066', 18:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:29 INFO - '_': '/tools/buildbot/bin/python'} 18:32:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 18:32:29 INFO - mozversion application_buildid: 20160601171646 18:32:29 INFO - mozversion application_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 18:32:29 INFO - mozversion application_display_name: Nightly 18:32:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:29 INFO - mozversion application_name: Firefox 18:32:29 INFO - mozversion application_remotingname: firefox 18:32:29 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 18:32:29 INFO - mozversion application_vendor: Mozilla 18:32:29 INFO - mozversion application_version: 49.0a1 18:32:29 INFO - mozversion platform_buildid: 20160601171646 18:32:29 INFO - mozversion platform_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 18:32:29 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 18:32:29 INFO - mozversion platform_version: 49.0a1 18:32:29 INFO - using testdate: 1464831149 18:32:29 INFO - actual date: 1464831149 18:32:29 INFO - starting webserver on 'localhost:49577' 18:32:29 INFO - SUITE-START | Running 2 tests 18:32:29 INFO - TEST-START | dromaeo_css 18:32:29 INFO - operating with platform_type : linux_ 18:32:29 INFO - Initialising browser for dromaeo_css test... 18:32:29 INFO - TEST-INFO | started process 10355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDPXk3V/profile http://localhost:49577/getInfo.html) 18:32:34 INFO - PROCESS | 10355 | __metrics Screen width/height:1600/1200 18:32:34 INFO - PROCESS | 10355 | colorDepth:24 18:32:34 INFO - PROCESS | 10355 | Browser inner width/height: 1024/697 18:32:34 INFO - PROCESS | 10355 | __metrics 18:32:35 ERROR - PROCESS | 10355 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 18:32:36 INFO - TEST-INFO | 10355: exit 0 18:32:36 INFO - Browser initialized. 18:32:36 INFO - Running cycle 1/1 for dromaeo_css test... 18:32:36 INFO - TEST-INFO | started process 10472 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDPXk3V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:48 INFO - PROCESS | 10472 | RSS: Main: 168435712 18:32:48 INFO - PROCESS | 10472 | 18:33:37 INFO - PROCESS | 10472 | 1464831217706 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] 18:35:48 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49577/tests/dromaeo/cssquery-ext.html) 18:35:49 INFO - PROCESS | 10472 | RSS: Main: 170872832 18:35:49 INFO - PROCESS | 10472 | 18:38:51 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-ext.html (next: http://localhost:49577/tests/dromaeo/cssquery-jquery.html) 18:38:52 INFO - PROCESS | 10472 | RSS: Main: 170156032 18:38:52 INFO - PROCESS | 10472 | 18:41:54 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49577/tests/dromaeo/cssquery-mootools.html) 18:41:55 INFO - PROCESS | 10472 | RSS: Main: 170774528 18:41:55 INFO - PROCESS | 10472 | 18:44:56 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49577/tests/dromaeo/cssquery-prototype.html) 18:44:57 INFO - PROCESS | 10472 | RSS: Main: 168255488 18:44:57 INFO - PROCESS | 10472 | 18:48:00 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49577/tests/dromaeo/cssquery-yui.html) 18:48:00 INFO - PROCESS | 10472 | RSS: Main: 170676224 18:48:00 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | Cycle 1(1): loaded http://localhost:49577/tests/dromaeo/cssquery-yui.html (next: http://localhost:49577/tests/dromaeo/cssquery-dojo.html) 18:51:03 INFO - PROCESS | 10472 | RSS: Main: 171896832 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | __start_tp_report 18:51:03 INFO - PROCESS | 10472 | _x_x_mozilla_page_load 18:51:03 INFO - PROCESS | 10472 | _x_x_mozilla_page_load_details 18:51:03 INFO - PROCESS | 10472 | |i|pagename|runs| 18:51:03 INFO - PROCESS | 10472 | |0;dojo.html;2584.1730646193214;2588.0424243030084;2596.298999270197;2599.0123752973877;2640.6963199232086;4612.077584483104;4614.676972611918;4653.092646933849;4696.248600223965;4742.193827049401;4589.495155532889;4673.299005149221;4684.203685373486;4692.835750748724;4724.196886529289;4299.419578356921;4375.868723938271;4421.535738747433;4435.201663700533;4441.800118994637;578.9307728215384;4290.077633308838;4326.43756311679;4332.220200363933;4353.804078816461;4367.45406824147;4439.533848945846;4441.689081764278;4444.466664000308;4472.038511719979;4390.912181756379;4432.778361081965;4435.0908063847155;4443.955560444387;4480.215962206601;4270.0605866709275;4277.251480990814;4281.593248641374;4315.762633055181;4349.608535231844;4514.909701805978;4519.299508576176;4554;4566.566176213288;4605.930911036337;5013.824516141917;5027.396712394499;5035.521625445577;5040.873978150539;5067.113255180328;4890.266460030988;4916.975415122937;4935.925961110586;4947;4960.107180707471;4613.884652883705;4625.953740462586;4635.907281854344;4640.303954406866;4683.320918466838;4983;5067.290579318898;5071.644984851099;5072.416672082684;5122.487751224922;4981.601471882241;5023.472535383732;5047.217681259411;5054.039732450822;5080.441151473335;5116.488351164928;5145.716985565755;5165.974170129125;5178.792848286026;5187.948120518822;4878.585320247818;5032.597392208614;5074.949250507521;5100.69395836251;5103.87240318995;5048.3941926969;5061.898762024813;5088.745562721923;5094.949050509522;5150.021495915764;4287.764172970454;4298.742075475481;4343.2182207203;4356.629686476685;4386.385905973102;3720.255948810249;3736.3461394255896;3746.063484128968;3754.680852127545;3805.2009078093356;4235.1953128906025;4357.7385356878685;4397.318415645516;4406.955930440655;4441.045175287329;5124.897502049938;5177.948220517746;5185.740712964337;5195.9088591395475;5227.973860130675;4286.678499112579;4297.871063868089;4319.740815551077;4438.378626992224;4440.267355886307;4508.887277818081;4516.16450956574;4516.299973504112;4555.2256116460285;4565.109803588335;4728.858134255978;4729.763511824463;4736.786844592001;4765.142274390504;4797.520247975174;4846.539578740014;5021.372328458942;5038.622103342191;5072.873178170576;5080.847574572768;4759.119562880878;4765.8570242892765;4784.6889952153;4785.282207668877;4847.515248475124;4982.2775697524385;4984.40187177541;4992.176290911959;5012.924806127838;5067.341245638044;4812.783424745825;5059.266406371129;5095.312132862165;5133.79464821403;5137.434882162885;4943.060818444484;4953.72754498506;4984.626153038609;5009.77456014473;5014.0974624567925;4779.95220047809;4975.4278258000795;4975.875603109951;4994.275830004702;5046.571041461517;4815.3258543803195;4835.105505481497;4875.366202393666;4896.90206195867;4908.042931628298;5005.649604527648;5006.874828129326;5028;5035.672681275705;5036.722980236123;4772.880677982939;4857.975710121426;4882.267659851051;4906.141425250638;4951.752412379438 18:51:03 INFO - PROCESS | 10472 | |1;ext.html;539.6117355705557;589.8230530840747;595.8927393069248;606.6754286456746;609.8749756299959;35636.64363356365;40604;40637;40643;40667.186656266895;40663;40743.18513629726;40750.59249407505;40771.16529756161;41154.176916461656;7721.189275126115;7838.09861865887;7876.645550950221;7880.921190788076;7896.921030789705;7722.498037627564;7764.689412423524;7799.649015794274;7830.412719046066;7834.843303133934;7763.767086987399;7764.572948487831;7803.414743894231;7803.60981950903;7843.294103530681;7828.256315650005;7828.804279892991;7829.725959591416;7831;7868.173841746591;7775.494592851446;7880.2513761192595;7884.093329267151;7902.881456778153;7906.130325664172;38171.80914095439;38213.235735285416;38228.42657360142;38503.422448663296;38767.03082422934;2548.6686730724887;2907.3749143933946;2912.199145235056;2996.3857409231055;3007.593974813394;25010.749892501208;25628.359291017874;25648.87175564129;26014;26038.609420858702;3086.7221950024605;3096.8606412711256;3111.7199452049426;3117.703818137273;3148.905532833996;235815;235872;237033;237179.81410093012;237272;32776.180595485304;33621.327573448885;33842;33875;33998;33315.16712082169;34959.97960234555;34960.30079398449;35076;35143.64856351455;13474.86525134736;13659.726805463835;13665.453381864812;13718.519851805138;13755.31223438844;10531.210159238088;10593.364398136137;10612.787744245072;10654.360738355723;10667.733306667395;8044.517328960281;8075.3539716822515;8114.8782768258525;8173.67305307793;8180.590970451453;3085.2132706159787;3150.3384289299047;3159.6524382317925;3200.0719791260276;3207.5028370602595;865.5282870835409;910.2126660438673;920.8112336970905;1011.8026984738052;1014.5890914179769;28138.71861281402;28366.432671346458;28947.276318092216;28997;29096.563551546744;790.968361265543;894.2532984957634;927.0034712683891;1038.2783965144304;1069.0805906920043;13764.462173560922;14000.439982400794;14164.575062748134;14164.716705665829;14213.928930355489;408273;408520;410611;413366;414223;51272.74363628233;51657;51857.48142518602;52007.73996129995;52212.21681674702;1195.9222650527688;1234.1854376111723;1258.0941721960173;1259.395490164726;1266.7973124300067;26745.197644070708;28300.575491367235;28338.716612834432;28436.715632844236;28798.7120128796;44094.559054409045;44192.55807442013;44985.32522012107;45035;45082.54917450915;340687;347779;347780;348244;348770;25701.485970280864;25893.870530647226;26208.34479138037;26715.33211669724;26719.599206012314;43634.56365436432;43724.78137609291;43890.78054609834;44094.11811763811;44528.77735611301;37706.8114659425;37770.81114594409;37844.810775945945;37942.81028594839;38051.80974095222;37278.44082338821;37865;38025.619743802206;38076.61923380731;38555.42166867443;30007;36247.63752362443;36460.270794583426;37379.81310093541;37465.81267093647;28399;32042;33235.02359433767;34966.30067398689;35103.122421938635;25289.62065569055;25591.488170236862;25811.870940645178;25946.74053259443;26196 18:51:03 INFO - PROCESS | 10472 | |2;jquery.html;377.1119014720334;379.6715840797709;381.75758393420176;391.42656008946886;396.3539430727913;15581.610459738498;15935;16773.24520396582;16778.244978975945;16824.747628785564;9655.951720241388;10356.275060745751;10358.637447689332;10358.792824143513;10367.118794902442;3474.9131271718156;4643.442786865581;4828;4830.806767729304;5048.823291184809;5297.735113244342;5558.110702287635;5559.249501317311;5560.332760068797;5569.10894256919;5445.455454454563;5576.972115139439;5607.691576963265;5608.214849921014;6176;6106.694665266763;6141.754329826802;6180.999999999977;6183.258009038899;6201.131841542165;5331.893362132774;5343.545798607123;5416.322959630046;5417.133258678593;5457.154141108134;12059.698507537381;12133.21134126279;12207.877921220763;12224.938875305656;18470.815291847044;1769.6991511443086;1796.380248814157;1834.5413646588354;1870.6726322893576;2008.608321377332;8595.871061934076;8840;9128.543572821332;9163.862542061808;9203.263738900872;1705.0792572011098;1724.5860993361628;1738.4350086222028;1774.7692799936056;1784.161444121274;59551;62008.689956549926;65403.35030300606;66451;66904.66547667279;9567.091126342986;9902.950485247527;9947.850782238273;9981.401115933068;9991.400515969066;13589.184648920898;13597.864021359857;13650.726985460435;13655.931720341334;13694.246816425193;7494.362979146725;7607.847843043108;7710.151883292833;7807.609619519115;7819.100803407568;7454.664540095709;7492.288232617892;7553.5845528495365;7584.734534291384;7600.50596711212;3912.0415498203124;4104.4048612950955;4139.855105071308;4183.728057676241;4413.16149931512;1728.6888360095052;1821.8087100854484;1836.4704710686347;1844.4190080124717;1845.713914343279;810.7324582887633;825.9050209225895;869.3740506835069;869.8956125264882;965.7247684409909;16046.197690115447;16052.598685032734;16111.758323625156;16169.272382742569;16571.17144142788;863.1109956744563;867.1718508824058;870.7648934787572;873.4191762477881;917.060013486182;8679.826403472021;9135.54322283883;9291.907080929104;9362.721849180054;9388.202002829836;166828.16585916994;169654;169993.15003424903;170165.14917425334;356920;60660;80889;82936.17063829284;86544;86913;806.4757907360263;854.7734850264685;889.7227639667193;910.8588168833842;915.3089417489886;15326.693466130391;15349;15389.615259618598;15571.454999074978;16051.357945682043;34594.65405345914;35676;35692;36958.445623315136;37237.441438378984;48704.75647621739;50902.490975091256;51054.23418648649;51135.232971504716;51362;7372.520786148883;7540.509866858856;7595.240475952361;7628.80927976805;7779.4554381192775;8113.310368618733;9352.719418417459;10449.791004179722;10886.564537418415;10893.183011274345;9174.541272936192;9219.76950576241;9303.813923721624;9351.48566828831;9402.43585384879;9603.951980240054;9752.9024709752;9858.802823943624;9922.851157232792;10188.439635820101;8482.533460659723;9094.545272736204;9371;9592.184664303333;10012.349197302152;5722.856928576819;5903.291605007426;6314.715837787402;6402.103705481143;6408.455281300955;8377.581120943807;8426.873596895928;8839.116088391109;8993.595288212176;8996.775080623036 18:51:03 INFO - PROCESS | 10472 | |3;mootools.html;1302.1080559816523;1385.321192615618;1483.3473271760424;1669.1320513333076;1684.7304431290995;6510;6976.5116441849095;7030.64846757662;7541.886871696922;7550.358219551338;6825.761098361559;6947.8957815632675;6971.302869713026;6990.196127445345;6994.3355381238825;2104.6001259760674;2130.0947097483586;2131.0836340373635;2142.8500004999655;2151.0427859602482;2070.2960993262286;2086.7704552499213;2186.0709198590534;2186.7485239197536;2195.648696208607;2127.5744851029776;2143.635581951064;2149.5593403352277;2160.5246845693923;2191.6931629571873;2041.3059559749681;2047.1299697628522;2048.2523878784286;2068.679354700024;2071;2038.8165065144208;2117.9788202117934;2122.734658167729;2144.603248399051;2146.270268108835;7129.714811407537;7149.106361704787;7158.534695244793;7180.748673796392;7200.747973820943;4134.545200027995;4277.679174061957;4281.100968796525;4289.742615443083;4296.205202037633;2770.986145069262;2931.736143747073;2933.8239705617516;2936.060460652592;2938.926526836825;896.3501461440469;919.3472634429496;919.5723988345452;936.3081141717562;950.8668786369914;9824.361416507976;9848.06443387877;9892.95053524735;9896.356736812084;9897.109260166548;4051.3922911562913;4097.528784189797;4146.564610715832;4165.97917010419;4182.351735481006;8537.829243415097;8578.313734901192;8611.913880861237;8614.698485552975;8622.223999840044;4263.126059157865;4300.075483770942;4320.027993701455;4346.282863327516;4351.804168812348;3452.5166476693284;3831.942520862189;3943.5464921533817;4000.8599699010397;4022.8591999279884;3513.8594456221463;3550;3623.7463377563317;3691.9077023074638;3744.4196149596855;1148.4028305281206;1198.5205917632843;1204.403820109034;1205.5358686905604;1211.4608998995539;724.5218156017004;774.3727580659571;822.8560001999746;861.763015170833;880.4189235104928;4953;5020.648554601143;5048.873778155502;5112.872178195575;5135.12702840518;392.21556886227546;404.4681244163915;410.35983865169834;410.9766680964437;412.0317254452014;3585.94621080684;3622.5834029086463;3629.491871138043;3651.9634803651625;3676.834542445543;8455.408121431441;8839.60221790021;8899.644014239488;8915.28677705782;8917.331200160013;8645.265152461985;8752.687096935384;8769.736907892773;8785.780355491035;8795.384323097449;6700.061991321219;6742.561733487308;6746.089277947414;6748.257691653914;6748.763793267212;4942.901141977212;4993.1012417763695;5014.423341315795;5022.924656130234;5072.163093089599;8034.598270086589;8158.836823263383;8228.465149765247;8240.21717936795;8344.582770861554;8241.876371854307;8323.583820808813;8364;8413.705520307005;8416.915830841857;5659.54723622101;6015.849603759766;6172.660503672341;6218.06728990655;6225.346338634378;6923.757668481579;6951.7914462566205;6962.408195303456;7000.439964802702;7020.368166865007;6332.936670633235;6492.870142597216;6531.444827189552;6584.934150658432;6694.397504224643;6045.093236014633;6141.201643786279;6283.842903927439;6325.4939604832425;6425.10048593188;6048.4858787003595;6079.361667024898;6166.81499555014;6190.938090619036;6206.06908963659;4403.317172240082;4483.529229430863;4485.394471746273;4493.168763778712;4517;4465.687401881967;4482.663800214932;4518.892871246566;4523.050159466549;4548.363229147988 18:51:03 INFO - PROCESS | 10472 | |4;prototype.html;289.54685916540615;292.3451468710089;294.91152654203734;299.2608257603718;301.08920515440786;5078.64449488536;5078.873028174297;5095.515925987034;5109.770060347284;5135.897282054357;5029.7988080476825;5034.345535080443;5035.924461133086;5041.890784027517;5055.620828437864;3689.9815500922523;3838.8080595970227;3903.7072219583647;3910.7262491625597;3930.390789427629;3919.9216015679813;3930.3711406175016;3935.4490371348033;3942.6648734857426;3952.130531283113;3888.3389823730035;3942.6254505821903;3956;3958.8416463341578;3976.125252444472;3832.674222691047;3879.8254078566533;3892.5912779158066;3909.3549564321993;3935.8425662973445;3400.996705971744;3407.267437500949;3426.3318652862704;3430.3310854383403;3432.622411534729;4824.855254342376;4829.589484893753;4832.637552183601;4834.564889159958;4834.975825120887;4345.73925564464;4439.489458712224;4458.242098843204;4490;4490.236659767847;5172.663776854492;5173.689578625294;5192.532672059495;5196.662216955886;5244.396894357122;3702.1670124221832;3713.424419215026;3714.4985426967555;3724.404095344732;3730.5336832895887;5456.454354564551;5460.426655201226;5473.141435288112;5492.231087647732;5532.28080349556;3735.6824669902817;3787.715921305858;3791.7156213284115;3798.2023775007005;3822.7132965027176;3841.8079096045085;3873.4770805941403;3878.398848178537;3879.2241551689776;3879.8836034919;4423.60187583119;4437.600615944581;4446.866594002185;4470.150671372428;4485.641148708096;5102.744862756922;5198.948010519846;5275.182346736262;5329.973350133225;5356.098585002346;1304.8238487804215;1327.674719693662;1327.6946302350514;1333.3266750228595;1334.6062911441147;837.9036410812713;841.381584535366;842.6671464771381;864.7276108025952;875.1598465473216;4466.39703640011;4504.189245935698;4562.429696287964;4575.016371480081;4583.679142459996;5486.91769623448;5511.0080185566185;5523.7238138092935;5546.750396232179;5573.108302671553;4409.14021765759;4641.257398816173;4645.326427667969;4648.953510464852;4659.930101048487;2905.738483536492;2936.985315073411;2940.6765255821842;2942.7498662613916;2947.911562653081;5142.6914385137015;5158.819441319536;5163.40620828602;5171.379434467813;5172.482751724872;5466.753996070186;5545.72271386429;5584.692841893653;5587.497125258747;5588.926090650208;5079.949200508096;5107.463716309809;5110.3356563646485;5133.204353325241;5200.271961925334;5425.240466334795;5434.402215756343;5457.754401051883;5463.207834864002;5471.537807986501;5139.406783897004;5386.192071189353;5387.218853266176;5398.784048638167;5400.702961337164;5111.565516931102;5117.769700363418;5128.4615115413835;5144.717040562656;5188.58491320701;3832.693384529175;3844.961550384572;3848.13416981177;3855.730098893163;3867.16855875988;5230.293910322058;5427.3215848019;5436.646617969819;5446.754896029767;5478.808241711521;5232.869178270422;5302.231176479312;5320.308359913187;5328.614560214294;5340.626156169031;5231.869203269949;5258.737063146751;5283.973580132075;5286.841394758163;5349.277847490539;4920.778564964657;5253.947460525499;5256.526912577886;5296.364436267672;5309.840704778709;5080.187170052774;5186.792528298825;5324.056719297506;5330.987112448623;5351.545118664957;5160.251763494197;5181.35233095863;5247.842564723064;5256.421793602624;5265.710385928811 18:51:03 INFO - PROCESS | 10472 | |5;yui.html;606.7967230977623;654.9345065493451;714.1679942866557;724.2757242757243;747.7794050755028;11507.59723409681;11522.135839812006;12048.819267710991;12261.570845020426;12291.078169137329;11753.236039657437;11945.044396448286;11983.101267404958;12021.27872327661;12033.879661203386;605.1104875832541;769.4383100336757;770.3644493293027;788.3811208201563;788.9684412623488;789.6762327445748;794.6940427935228;797.5135167547792;799.8120441696226;800.8638531449668;835.1147783349638;838.4382463749272;838.8322335532887;838.9161083891623;840.3361344537808;821.9301359384459;830.0910502999207;831.7962099285684;832.5878690048435;833.6790335720731;613.9386061393825;614.2444792904707;615.3630991923383;615.7290792051527;615.7506209984919;11791.29252244868;11867.643970680894;11926.701832454171;11973.700657483545;12025.218360806519;13706.52027179044;13919.164850108924;14002.369893354824;14050.789238161437;14059.297035148202;9762.707118786448;9788.168005719523;9953.502324883726;10508.894911050867;10646.361218326847;4195.811188496525;4199.160167966419;4200.159968006411;4227.936580951288;4375.803088861009;56118;56880;57390.42609573893;57561;58389.41610583882;9732.07545283197;9790.5594248259;9814.116729494379;9889.80220395588;10036.548355324028;16452.588685282724;16557;16674;16779.91610041942;17087.316507339816;6451.1290975717675;6465.450436712932;6498.935010649928;6549.050387693853;6607.867842643121;3553.1650062235353;3600.9099772505374;3610.133567943727;3630.8003059831435;3711.16498787776;2736.945261094767;2823.3365159187574;2823.435312937421;2828.78784091194;2830.872610732522;492.8053418899515;501.9974900125549;506.23305691876834;506.8732816795801;511.2510172597163;504.2360823352624;527.6122050293031;535.6384440502645;539.3258426966293;550.6971165858841;6920.7231710731;6952.2352541220425;6988.580685158907;7154.141503019671;7201.567905925661;495.247224219192;516.4731973387125;532.7043490862609;545.9808906688247;554.5369616370376;3545.5213546170935;3596.748227624041;3681.9079523012138;3684.9631503685155;3688.5286857805336;84390;88918.11081889099;90799.54600226956;91402.25793226213;91411.54294228619;75525;77758;77762;78870.60564697254;80237.19762802414;54590;69174;69258.3074169262;69795.81225126509;70055.29944700487;6610.603363798187;6652.600843949379;6701.932980670131;6806.693698783468;7100.396466300423;40616.79691601523;42639.57360426356;43827.342589861815;44283.11433771371;44947.32579011383;43487.347689784045;55368.44631553633;57681.423185767606;68633;68865.31134688789;11353.262037967506;11360.602378916697;11365.204435689422;11863.822042669195;12951.352432378531;12793.42429590652;12872.292023938773;13120.343982801012;13234.205947643171;13353.66615834612;11585.131115166228;12247.265164090182;12264;12315.445804938978;12375.62873113808;11146.498407571517;11639.185257032264;11977.161598688357;12333.753324933272;12346.444410001392;11112.277701949672;11305.26515776472;11458.312501249951;11485.82771258415;11733.471993760131;9752.707418777449;10123.696289111338;10492.527836247235;10556.524956376828;10607.151427885596;10059.748506287402;10277;10301.587936482756;10378.325408848401;10421.218408619232 18:51:03 INFO - PROCESS | 10472 | __end_tp_report 18:51:03 INFO - PROCESS | 10472 | __start_cc_report 18:51:03 INFO - PROCESS | 10472 | _x_x_mozilla_cycle_collect,397 18:51:03 INFO - PROCESS | 10472 | __end_cc_report 18:51:03 INFO - PROCESS | 10472 | __startTimestamp1464832263644__endTimestamp 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | ------- Summary: start ------- 18:51:03 INFO - PROCESS | 10472 | Number of tests: 6 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#0] dojo.html Cycles:165 Average:4650.17 Median:4784.69 stddev:588.73 (12.3%) stddev-sans-first:567.79 18:51:03 INFO - PROCESS | 10472 | Values: 2584.2 2588.0 2596.3 2599.0 2640.7 4612.1 4614.7 4653.1 4696.2 4742.2 4589.5 4673.3 4684.2 4692.8 4724.2 4299.4 4375.9 4421.5 4435.2 4441.8 578.9 4290.1 4326.4 4332.2 4353.8 4367.5 4439.5 4441.7 4444.5 4472.0 4390.9 4432.8 4435.1 4444.0 4480.2 4270.1 4277.3 4281.6 4315.8 4349.6 4514.9 4519.3 4554.0 4566.6 4605.9 5013.8 5027.4 5035.5 5040.9 5067.1 4890.3 4917.0 4935.9 4947.0 4960.1 4613.9 4626.0 4635.9 4640.3 4683.3 4983.0 5067.3 5071.6 5072.4 5122.5 4981.6 5023.5 5047.2 5054.0 5080.4 5116.5 5145.7 5166.0 5178.8 5187.9 4878.6 5032.6 5074.9 5100.7 5103.9 5048.4 5061.9 5088.7 5094.9 5150.0 4287.8 4298.7 4343.2 4356.6 4386.4 3720.3 3736.3 3746.1 3754.7 3805.2 4235.2 4357.7 4397.3 4407.0 4441.0 5124.9 5177.9 5185.7 5195.9 5228.0 4286.7 4297.9 4319.7 4438.4 4440.3 4508.9 4516.2 4516.3 4555.2 4565.1 4728.9 4729.8 4736.8 4765.1 4797.5 4846.5 5021.4 5038.6 5072.9 5080.8 4759.1 4765.9 4784.7 4785.3 4847.5 4982.3 4984.4 4992.2 5012.9 5067.3 4812.8 5059.3 5095.3 5133.8 5137.4 4943.1 4953.7 4984.6 5009.8 5014.1 4780.0 4975.4 4975.9 4994.3 5046.6 4815.3 4835.1 4875.4 4896.9 4908.0 5005.6 5006.9 5028.0 5035.7 5036.7 4772.9 4858.0 4882.3 4906.1 4951.8 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#1] ext.html Cycles:180 Average:47192.59 Median:35942.14 stddev:89744.33 (249.7%) stddev-sans-first:89927.73 18:51:03 INFO - PROCESS | 10472 | Values: 539.6 589.8 595.9 606.7 609.9 35636.6 40604.0 40637.0 40643.0 40667.2 40663.0 40743.2 40750.6 40771.2 41154.2 7721.2 7838.1 7876.6 7880.9 7896.9 7722.5 7764.7 7799.6 7830.4 7834.8 7763.8 7764.6 7803.4 7803.6 7843.3 7828.3 7828.8 7829.7 7831.0 7868.2 7775.5 7880.3 7884.1 7902.9 7906.1 38171.8 38213.2 38228.4 38503.4 38767.0 2548.7 2907.4 2912.2 2996.4 3007.6 25010.7 25628.4 25648.9 26014.0 26038.6 3086.7 3096.9 3111.7 3117.7 3148.9 235815.0 235872.0 237033.0 237179.8 237272.0 32776.2 33621.3 33842.0 33875.0 33998.0 33315.2 34960.0 34960.3 35076.0 35143.6 13474.9 13659.7 13665.5 13718.5 13755.3 10531.2 10593.4 10612.8 10654.4 10667.7 8044.5 8075.4 8114.9 8173.7 8180.6 3085.2 3150.3 3159.7 3200.1 3207.5 865.5 910.2 920.8 1011.8 1014.6 28138.7 28366.4 28947.3 28997.0 29096.6 791.0 894.3 927.0 1038.3 1069.1 13764.5 14000.4 14164.6 14164.7 14213.9 408273.0 408520.0 410611.0 413366.0 414223.0 51272.7 51657.0 51857.5 52007.7 52212.2 1195.9 1234.2 1258.1 1259.4 1266.8 26745.2 28300.6 28338.7 28436.7 28798.7 44094.6 44192.6 44985.3 45035.0 45082.5 340687.0 347779.0 347780.0 348244.0 348770.0 25701.5 25893.9 26208.3 26715.3 26719.6 43634.6 43724.8 43890.8 44094.1 44528.8 37706.8 37770.8 37844.8 37942.8 38051.8 37278.4 37865.0 38025.6 38076.6 38555.4 30007.0 36247.6 36460.3 37379.8 37465.8 28399.0 32042.0 33235.0 34966.3 35103.1 25289.6 25591.5 25811.9 25946.7 26196.0 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#2] jquery.html Cycles:180 Average:18511.80 Median:5592.33 stddev:38400.44 (686.7%) stddev-sans-first:38484.03 18:51:03 INFO - PROCESS | 10472 | Values: 377.1 379.7 381.8 391.4 396.4 15581.6 15935.0 16773.2 16778.2 16824.7 9656.0 10356.3 10358.6 10358.8 10367.1 3474.9 4643.4 4828.0 4830.8 5048.8 5297.7 5558.1 5559.2 5560.3 5569.1 5445.5 5577.0 5607.7 5608.2 6176.0 6106.7 6141.8 6181.0 6183.3 6201.1 5331.9 5343.5 5416.3 5417.1 5457.2 12059.7 12133.2 12207.9 12224.9 18470.8 1769.7 1796.4 1834.5 1870.7 2008.6 8595.9 8840.0 9128.5 9163.9 9203.3 1705.1 1724.6 1738.4 1774.8 1784.2 59551.0 62008.7 65403.4 66451.0 66904.7 9567.1 9903.0 9947.9 9981.4 9991.4 13589.2 13597.9 13650.7 13655.9 13694.2 7494.4 7607.8 7710.2 7807.6 7819.1 7454.7 7492.3 7553.6 7584.7 7600.5 3912.0 4104.4 4139.9 4183.7 4413.2 1728.7 1821.8 1836.5 1844.4 1845.7 810.7 825.9 869.4 869.9 965.7 16046.2 16052.6 16111.8 16169.3 16571.2 863.1 867.2 870.8 873.4 917.1 8679.8 9135.5 9291.9 9362.7 9388.2 166828.2 169654.0 169993.2 170165.1 356920.0 60660.0 80889.0 82936.2 86544.0 86913.0 806.5 854.8 889.7 910.9 915.3 15326.7 15349.0 15389.6 15571.5 16051.4 34594.7 35676.0 35692.0 36958.4 37237.4 48704.8 50902.5 51054.2 51135.2 51362.0 7372.5 7540.5 7595.2 7628.8 7779.5 8113.3 9352.7 10449.8 10886.6 10893.2 9174.5 9219.8 9303.8 9351.5 9402.4 9604.0 9752.9 9858.8 9922.9 10188.4 8482.5 9094.5 9371.0 9592.2 10012.3 5722.9 5903.3 6314.7 6402.1 6408.5 8377.6 8426.9 8839.1 8993.6 8996.8 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#3] mootools.html Cycles:180 Average:4824.55 Median:5035.90 stddev:2628.46 (52.2%) stddev-sans-first:2622.50 18:51:03 INFO - PROCESS | 10472 | Values: 1302.1 1385.3 1483.3 1669.1 1684.7 6510.0 6976.5 7030.6 7541.9 7550.4 6825.8 6947.9 6971.3 6990.2 6994.3 2104.6 2130.1 2131.1 2142.9 2151.0 2070.3 2086.8 2186.1 2186.7 2195.6 2127.6 2143.6 2149.6 2160.5 2191.7 2041.3 2047.1 2048.3 2068.7 2071.0 2038.8 2118.0 2122.7 2144.6 2146.3 7129.7 7149.1 7158.5 7180.7 7200.7 4134.5 4277.7 4281.1 4289.7 4296.2 2771.0 2931.7 2933.8 2936.1 2938.9 896.4 919.3 919.6 936.3 950.9 9824.4 9848.1 9893.0 9896.4 9897.1 4051.4 4097.5 4146.6 4166.0 4182.4 8537.8 8578.3 8611.9 8614.7 8622.2 4263.1 4300.1 4320.0 4346.3 4351.8 3452.5 3831.9 3943.5 4000.9 4022.9 3513.9 3550.0 3623.7 3691.9 3744.4 1148.4 1198.5 1204.4 1205.5 1211.5 724.5 774.4 822.9 861.8 880.4 4953.0 5020.6 5048.9 5112.9 5135.1 392.2 404.5 410.4 411.0 412.0 3585.9 3622.6 3629.5 3652.0 3676.8 8455.4 8839.6 8899.6 8915.3 8917.3 8645.3 8752.7 8769.7 8785.8 8795.4 6700.1 6742.6 6746.1 6748.3 6748.8 4942.9 4993.1 5014.4 5022.9 5072.2 8034.6 8158.8 8228.5 8240.2 8344.6 8241.9 8323.6 8364.0 8413.7 8416.9 5659.5 6015.8 6172.7 6218.1 6225.3 6923.8 6951.8 6962.4 7000.4 7020.4 6332.9 6492.9 6531.4 6584.9 6694.4 6045.1 6141.2 6283.8 6325.5 6425.1 6048.5 6079.4 6166.8 6190.9 6206.1 4403.3 4483.5 4485.4 4493.2 4517.0 4465.7 4482.7 4518.9 4523.1 4548.4 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#4] prototype.html Cycles:180 Average:4377.61 Median:5067.13 stddev:1284.58 (25.4%) stddev-sans-first:1251.00 18:51:03 INFO - PROCESS | 10472 | Values: 289.5 292.3 294.9 299.3 301.1 5078.6 5078.9 5095.5 5109.8 5135.9 5029.8 5034.3 5035.9 5041.9 5055.6 3690.0 3838.8 3903.7 3910.7 3930.4 3919.9 3930.4 3935.4 3942.7 3952.1 3888.3 3942.6 3956.0 3958.8 3976.1 3832.7 3879.8 3892.6 3909.4 3935.8 3401.0 3407.3 3426.3 3430.3 3432.6 4824.9 4829.6 4832.6 4834.6 4835.0 4345.7 4439.5 4458.2 4490.0 4490.2 5172.7 5173.7 5192.5 5196.7 5244.4 3702.2 3713.4 3714.5 3724.4 3730.5 5456.5 5460.4 5473.1 5492.2 5532.3 3735.7 3787.7 3791.7 3798.2 3822.7 3841.8 3873.5 3878.4 3879.2 3879.9 4423.6 4437.6 4446.9 4470.2 4485.6 5102.7 5198.9 5275.2 5330.0 5356.1 1304.8 1327.7 1327.7 1333.3 1334.6 837.9 841.4 842.7 864.7 875.2 4466.4 4504.2 4562.4 4575.0 4583.7 5486.9 5511.0 5523.7 5546.8 5573.1 4409.1 4641.3 4645.3 4649.0 4659.9 2905.7 2937.0 2940.7 2942.7 2947.9 5142.7 5158.8 5163.4 5171.4 5172.5 5466.8 5545.7 5584.7 5587.5 5588.9 5079.9 5107.5 5110.3 5133.2 5200.3 5425.2 5434.4 5457.8 5463.2 5471.5 5139.4 5386.2 5387.2 5398.8 5400.7 5111.6 5117.8 5128.5 5144.7 5188.6 3832.7 3845.0 3848.1 3855.7 3867.2 5230.3 5427.3 5436.6 5446.8 5478.8 5232.9 5302.2 5320.3 5328.6 5340.6 5231.9 5258.7 5284.0 5286.8 5349.3 4920.8 5253.9 5256.5 5296.4 5309.8 5080.2 5186.8 5324.1 5331.0 5351.5 5160.3 5181.4 5247.8 5256.4 5265.7 18:51:03 INFO - PROCESS | 10472 | 18:51:03 INFO - PROCESS | 10472 | [#5] yui.html Cycles:180 Average:16705.35 Median:503.12 stddev:23307.83 (4632.7%) stddev-sans-first:23341.87 18:51:03 INFO - PROCESS | 10472 | Values: 606.8 654.9 714.2 724.3 747.8 11507.6 11522.1 12048.8 12261.6 12291.1 11753.2 11945.0 11983.1 12021.3 12033.9 605.1 769.4 770.4 788.4 789.0 789.7 794.7 797.5 799.8 800.9 835.1 838.4 838.8 838.9 840.3 821.9 830.1 831.8 832.6 833.7 613.9 614.2 615.4 615.7 615.8 11791.3 11867.6 11926.7 11973.7 12025.2 13706.5 13919.2 14002.4 14050.8 14059.3 9762.7 9788.2 9953.5 10508.9 10646.4 4195.8 4199.2 4200.2 4227.9 4375.8 56118.0 56880.0 57390.4 57561.0 58389.4 9732.1 9790.6 9814.1 9889.8 10036.5 16452.6 16557.0 16674.0 16779.9 17087.3 6451.1 6465.5 6498.9 6549.1 6607.9 3553.2 3600.9 3610.1 3630.8 3711.2 2736.9 2823.3 2823.4 2828.8 2830.9 492.8 502.0 506.2 506.9 511.3 504.2 527.6 535.6 539.3 550.7 6920.7 6952.2 6988.6 7154.1 7201.6 495.2 516.5 532.7 546.0 554.5 3545.5 3596.7 3681.9 3685.0 3688.5 84390.0 88918.1 90799.5 91402.3 91411.5 75525.0 77758.0 77762.0 78870.6 80237.2 54590.0 69174.0 69258.3 69795.8 70055.3 6610.6 6652.6 6701.9 6806.7 7100.4 40616.8 42639.6 43827.3 44283.1 44947.3 43487.3 55368.4 57681.4 68633.0 68865.3 11353.3 11360.6 11365.2 11863.8 12951.4 12793.4 12872.3 13120.3 13234.2 13353.7 11585.1 12247.3 12264.0 12315.4 12375.6 11146.5 11639.2 11977.2 12333.8 12346.4 11112.3 11305.3 11458.3 11485.8 11733.5 9752.7 10123.7 10492.5 10556.5 10607.2 10059.7 10277.0 10301.6 10378.3 10421.2 18:51:03 INFO - PROCESS | 10472 | -------- Summary: end -------- 18:51:03 INFO - PROCESS | 10472 | 18:51:04 INFO - TEST-INFO | 10472: exit 0 18:51:04 INFO - TEST-OK | dromaeo_css | took 1114805ms 18:51:04 INFO - TEST-START | kraken 18:51:04 INFO - operating with platform_type : linux_ 18:51:04 INFO - Initialising browser for kraken test... 18:51:04 INFO - TEST-INFO | started process 10704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl9RI3h/profile http://localhost:49577/getInfo.html) 18:51:09 INFO - PROCESS | 10704 | __metrics Screen width/height:1600/1200 18:51:09 INFO - PROCESS | 10704 | colorDepth:24 18:51:09 INFO - PROCESS | 10704 | Browser inner width/height: 1024/697 18:51:09 INFO - PROCESS | 10704 | __metrics 18:51:09 ERROR - PROCESS | 10704 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 18:51:10 INFO - TEST-INFO | 10704: exit 0 18:51:10 INFO - Browser initialized. 18:51:10 INFO - Running cycle 1/1 for kraken test... 18:51:10 INFO - TEST-INFO | started process 10825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl9RI3h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:22 INFO - PROCESS | 10825 | RSS: Main: 167940096 18:51:22 INFO - PROCESS | 10825 | 18:51:25 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49577/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:26 INFO - PROCESS | 10825 | RSS: Main: 172138496 18:51:26 INFO - PROCESS | 10825 | 18:51:29 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49577/tests/kraken/driver.html?testName=audio-dft) 18:51:29 INFO - PROCESS | 10825 | RSS: Main: 172032000 18:51:29 INFO - PROCESS | 10825 | 18:51:32 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49577/tests/kraken/driver.html?testName=audio-fft) 18:51:33 INFO - PROCESS | 10825 | RSS: Main: 167399424 18:51:33 INFO - PROCESS | 10825 | 18:51:35 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49577/tests/kraken/driver.html?testName=audio-oscillator) 18:51:36 INFO - PROCESS | 10825 | RSS: Main: 167870464 18:51:36 INFO - PROCESS | 10825 | 18:51:38 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49577/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:39 INFO - PROCESS | 10825 | RSS: Main: 168210432 18:51:39 INFO - PROCESS | 10825 | 18:51:42 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49577/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:42 INFO - PROCESS | 10825 | RSS: Main: 168468480 18:51:42 INFO - PROCESS | 10825 | 18:51:46 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49577/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:47 INFO - PROCESS | 10825 | RSS: Main: 166285312 18:51:47 INFO - PROCESS | 10825 | 18:51:50 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49577/tests/kraken/driver.html?testName=json-parse-financial) 18:51:50 INFO - PROCESS | 10825 | RSS: Main: 167690240 18:51:50 INFO - PROCESS | 10825 | 18:51:52 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49577/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:53 INFO - PROCESS | 10825 | RSS: Main: 168198144 18:51:53 INFO - PROCESS | 10825 | 18:51:54 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:55 INFO - PROCESS | 10825 | RSS: Main: 168259584 18:51:55 INFO - PROCESS | 10825 | 18:51:57 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:58 INFO - PROCESS | 10825 | RSS: Main: 168849408 18:51:58 INFO - PROCESS | 10825 | 18:52:01 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:52:02 INFO - PROCESS | 10825 | RSS: Main: 169025536 18:52:02 INFO - PROCESS | 10825 | 18:52:04 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:52:05 INFO - PROCESS | 10825 | RSS: Main: 168415232 18:52:05 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | Cycle 1(1): loaded http://localhost:49577/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49577/tests/kraken/driver.html?testName=ai-astar) 18:52:07 INFO - PROCESS | 10825 | RSS: Main: 169631744 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | __start_tp_report 18:52:07 INFO - PROCESS | 10825 | _x_x_mozilla_page_load 18:52:07 INFO - PROCESS | 10825 | _x_x_mozilla_page_load_details 18:52:07 INFO - PROCESS | 10825 | |i|pagename|runs| 18:52:07 INFO - PROCESS | 10825 | |0;ai-astar;116;112;115;116;115;115;116;115;115;115 18:52:07 INFO - PROCESS | 10825 | |1;audio-beat-detection;159;137;156;188;133;157;166;133;190;132 18:52:07 INFO - PROCESS | 10825 | |2;audio-dft;164;163;165;172;163;163;162;163;163;166 18:52:07 INFO - PROCESS | 10825 | |3;audio-fft;76;76;76;159;78;78;76;75;165;76 18:52:07 INFO - PROCESS | 10825 | |4;audio-oscillator;85;88;144;85;85;85;144;85;86;147 18:52:07 INFO - PROCESS | 10825 | |5;imaging-gaussian-blur;119;118;117;119;118;119;118;119;119;117 18:52:07 INFO - PROCESS | 10825 | |6;imaging-darkroom;147;150;148;147;148;151;147;153;149;148 18:52:07 INFO - PROCESS | 10825 | |7;imaging-desaturate;95;96;96;101;95;95;95;96;98;96 18:52:07 INFO - PROCESS | 10825 | |8;json-parse-financial;64;64;64;64;64;67;64;64;66;64 18:52:07 INFO - PROCESS | 10825 | |9;json-stringify-tinderbox;65;67;67;66;65;69;65;65;66;66 18:52:07 INFO - PROCESS | 10825 | |10;stanford-crypto-aes;88;87;85;84;88;88;84;85;115;91 18:52:07 INFO - PROCESS | 10825 | |11;stanford-crypto-ccm;139;136;133;134;133;130;156;139;159;138 18:52:07 INFO - PROCESS | 10825 | |12;stanford-crypto-pbkdf2;168;176;171;171;170;173;177;176;175;165 18:52:07 INFO - PROCESS | 10825 | |13;stanford-crypto-sha256-iterative;67;67;68;69;69;68;69;69;68;67 18:52:07 INFO - PROCESS | 10825 | __end_tp_report 18:52:07 INFO - PROCESS | 10825 | __start_cc_report 18:52:07 INFO - PROCESS | 10825 | _x_x_mozilla_cycle_collect,980 18:52:07 INFO - PROCESS | 10825 | __end_cc_report 18:52:07 INFO - PROCESS | 10825 | __startTimestamp1464832327629__endTimestamp 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | ------- Summary: start ------- 18:52:07 INFO - PROCESS | 10825 | Number of tests: 14 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:1.15 (1.0%) stddev-sans-first:1.17 18:52:07 INFO - PROCESS | 10825 | Values: 116.0 112.0 115.0 116.0 115.0 115.0 116.0 115.0 115.0 115.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#1] audio-beat-detection Cycles:10 Average:155.10 Median:158.00 stddev:21.80 (13.8%) stddev-sans-first:23.08 18:52:07 INFO - PROCESS | 10825 | Values: 159.0 137.0 156.0 188.0 133.0 157.0 166.0 133.0 190.0 132.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#2] audio-dft Cycles:10 Average:164.40 Median:163.50 stddev:2.91 (1.8%) stddev-sans-first:3.09 18:52:07 INFO - PROCESS | 10825 | Values: 164.0 163.0 165.0 172.0 163.0 163.0 162.0 163.0 163.0 166.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#3] audio-fft Cycles:10 Average:93.50 Median:76.00 stddev:36.14 (47.6%) stddev-sans-first:37.78 18:52:07 INFO - PROCESS | 10825 | Values: 76.0 76.0 76.0 159.0 78.0 78.0 76.0 75.0 165.0 76.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:85.00 stddev:28.73 (33.8%) stddev-sans-first:29.69 18:52:07 INFO - PROCESS | 10825 | Values: 85.0 88.0 144.0 85.0 85.0 85.0 144.0 85.0 86.0 147.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:119.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 18:52:07 INFO - PROCESS | 10825 | Values: 119.0 118.0 117.0 119.0 118.0 119.0 118.0 119.0 119.0 117.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.50 stddev:1.99 (1.3%) stddev-sans-first:2.00 18:52:07 INFO - PROCESS | 10825 | Values: 147.0 150.0 148.0 147.0 148.0 151.0 147.0 153.0 149.0 148.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:1.89 (2.0%) stddev-sans-first:1.94 18:52:07 INFO - PROCESS | 10825 | Values: 95.0 96.0 96.0 101.0 95.0 95.0 95.0 96.0 98.0 96.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:1.08 (1.7%) stddev-sans-first:1.13 18:52:07 INFO - PROCESS | 10825 | Values: 64.0 64.0 64.0 64.0 64.0 67.0 64.0 64.0 66.0 64.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:1.29 (1.9%) stddev-sans-first:1.30 18:52:07 INFO - PROCESS | 10825 | Values: 65.0 67.0 67.0 66.0 65.0 69.0 65.0 65.0 66.0 66.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:87.50 stddev:9.23 (10.5%) stddev-sans-first:9.77 18:52:07 INFO - PROCESS | 10825 | Values: 88.0 87.0 85.0 84.0 88.0 88.0 84.0 85.0 115.0 91.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:138.50 stddev:9.84 (7.1%) stddev-sans-first:10.44 18:52:07 INFO - PROCESS | 10825 | Values: 139.0 136.0 133.0 134.0 133.0 130.0 156.0 139.0 159.0 138.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:174.00 stddev:3.91 (2.2%) stddev-sans-first:3.84 18:52:07 INFO - PROCESS | 10825 | Values: 168.0 176.0 171.0 171.0 170.0 173.0 177.0 176.0 175.0 165.0 18:52:07 INFO - PROCESS | 10825 | 18:52:07 INFO - PROCESS | 10825 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 18:52:07 INFO - PROCESS | 10825 | Values: 67.0 67.0 68.0 69.0 69.0 68.0 69.0 69.0 68.0 67.0 18:52:07 INFO - PROCESS | 10825 | -------- Summary: end -------- 18:52:07 INFO - PROCESS | 10825 | 18:52:08 INFO - TEST-INFO | 10825: exit 0 18:52:08 INFO - TEST-OK | kraken | took 64249ms 18:52:08 INFO - SUITE-END | took 1179s 18:52:08 INFO - Completed test suite (00:19:39) 18:52:08 INFO - javascript benchmark 18:52:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6198.517654148561, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2584.1730646193214, 2588.0424243030084, 2596.298999270197, 2599.0123752973877, 2640.6963199232086, 4612.077584483104, 4614.676972611918, 4653.092646933849, 4696.248600223965, 4742.193827049401, 4589.495155532889, 4673.299005149221, 4684.203685373486, 4692.835750748724, 4724.196886529289, 4299.419578356921, 4375.868723938271, 4421.535738747433, 4435.201663700533, 4441.800118994637, 578.9307728215384, 4290.077633308838, 4326.43756311679, 4332.220200363933, 4353.804078816461, 4367.45406824147, 4439.533848945846, 4441.689081764278, 4444.466664000308, 4472.038511719979, 4390.912181756379, 4432.778361081965, 4435.0908063847155, 4443.955560444387, 4480.215962206601, 4270.0605866709275, 4277.251480990814, 4281.593248641374, 4315.762633055181, 4349.608535231844, 4514.909701805978, 4519.299508576176, 4554.0, 4566.566176213288, 4605.930911036337, 5013.824516141917, 5027.396712394499, 5035.521625445577, 5040.873978150539, 5067.113255180328, 4890.266460030988, 4916.975415122937, 4935.925961110586, 4947.0, 4960.107180707471, 4613.884652883705, 4625.953740462586, 4635.907281854344, 4640.303954406866, 4683.320918466838, 4983.0, 5067.290579318898, 5071.644984851099, 5072.416672082684, 5122.487751224922, 4981.601471882241, 5023.472535383732, 5047.217681259411, 5054.039732450822, 5080.441151473335, 5116.488351164928, 5145.716985565755, 5165.974170129125, 5178.792848286026, 5187.948120518822, 4878.585320247818, 5032.597392208614, 5074.949250507521, 5100.69395836251, 5103.87240318995, 5048.3941926969, 5061.898762024813, 5088.745562721923, 5094.949050509522, 5150.021495915764, 4287.764172970454, 4298.742075475481, 4343.2182207203, 4356.629686476685, 4386.385905973102, 3720.255948810249, 3736.3461394255896, 3746.063484128968, 3754.680852127545, 3805.2009078093356, 4235.1953128906025, 4357.7385356878685, 4397.318415645516, 4406.955930440655, 4441.045175287329, 5124.897502049938, 5177.948220517746, 5185.740712964337, 5195.9088591395475, 5227.973860130675, 4286.678499112579, 4297.871063868089, 4319.740815551077, 4438.378626992224, 4440.267355886307, 4508.887277818081, 4516.16450956574, 4516.299973504112, 4555.2256116460285, 4565.109803588335, 4728.858134255978, 4729.763511824463, 4736.786844592001, 4765.142274390504, 4797.520247975174, 4846.539578740014, 5021.372328458942, 5038.622103342191, 5072.873178170576, 5080.847574572768, 4759.119562880878, 4765.8570242892765, 4784.6889952153, 4785.282207668877, 4847.515248475124, 4982.2775697524385, 4984.40187177541, 4992.176290911959, 5012.924806127838, 5067.341245638044, 4812.783424745825, 5059.266406371129, 5095.312132862165, 5133.79464821403, 5137.434882162885, 4943.060818444484, 4953.72754498506, 4984.626153038609, 5009.77456014473, 5014.0974624567925, 4779.95220047809, 4975.4278258000795, 4975.875603109951, 4994.275830004702, 5046.571041461517, 4815.3258543803195, 4835.105505481497, 4875.366202393666, 4896.90206195867, 4908.042931628298, 5005.649604527648, 5006.874828129326, 5028.0, 5035.672681275705, 5036.722980236123, 4772.880677982939, 4857.975710121426, 4882.267659851051, 4906.141425250638, 4951.752412379438], "value": 4613.993354922544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.6117355705557, 589.8230530840747, 595.8927393069248, 606.6754286456746, 609.8749756299959, 35636.64363356365, 40604.0, 40637.0, 40643.0, 40667.186656266895, 40663.0, 40743.18513629726, 40750.59249407505, 40771.16529756161, 41154.176916461656, 7721.189275126115, 7838.09861865887, 7876.645550950221, 7880.921190788076, 7896.921030789705, 7722.498037627564, 7764.689412423524, 7799.649015794274, 7830.412719046066, 7834.843303133934, 7763.767086987399, 7764.572948487831, 7803.414743894231, 7803.60981950903, 7843.294103530681, 7828.256315650005, 7828.804279892991, 7829.725959591416, 7831.0, 7868.173841746591, 7775.494592851446, 7880.2513761192595, 7884.093329267151, 7902.881456778153, 7906.130325664172, 38171.80914095439, 38213.235735285416, 38228.42657360142, 38503.422448663296, 38767.03082422934, 2548.6686730724887, 2907.3749143933946, 2912.199145235056, 2996.3857409231055, 3007.593974813394, 25010.749892501208, 25628.359291017874, 25648.87175564129, 26014.0, 26038.609420858702, 3086.7221950024605, 3096.8606412711256, 3111.7199452049426, 3117.703818137273, 3148.905532833996, 235815.0, 235872.0, 237033.0, 237179.81410093012, 237272.0, 32776.180595485304, 33621.327573448885, 33842.0, 33875.0, 33998.0, 33315.16712082169, 34959.97960234555, 34960.30079398449, 35076.0, 35143.64856351455, 13474.86525134736, 13659.726805463835, 13665.453381864812, 13718.519851805138, 13755.31223438844, 10531.210159238088, 10593.364398136137, 10612.787744245072, 10654.360738355723, 10667.733306667395, 8044.517328960281, 8075.3539716822515, 8114.8782768258525, 8173.67305307793, 8180.590970451453, 3085.2132706159787, 3150.3384289299047, 3159.6524382317925, 3200.0719791260276, 3207.5028370602595, 865.5282870835409, 910.2126660438673, 920.8112336970905, 1011.8026984738052, 1014.5890914179769, 28138.71861281402, 28366.432671346458, 28947.276318092216, 28997.0, 29096.563551546744, 790.968361265543, 894.2532984957634, 927.0034712683891, 1038.2783965144304, 1069.0805906920043, 13764.462173560922, 14000.439982400794, 14164.575062748134, 14164.716705665829, 14213.928930355489, 408273.0, 408520.0, 410611.0, 413366.0, 414223.0, 51272.74363628233, 51657.0, 51857.48142518602, 52007.73996129995, 52212.21681674702, 1195.9222650527688, 1234.1854376111723, 1258.0941721960173, 1259.395490164726, 1266.7973124300067, 26745.197644070708, 28300.575491367235, 28338.716612834432, 28436.715632844236, 28798.7120128796, 44094.559054409045, 44192.55807442013, 44985.32522012107, 45035.0, 45082.54917450915, 340687.0, 347779.0, 347780.0, 348244.0, 348770.0, 25701.485970280864, 25893.870530647226, 26208.34479138037, 26715.33211669724, 26719.599206012314, 43634.56365436432, 43724.78137609291, 43890.78054609834, 44094.11811763811, 44528.77735611301, 37706.8114659425, 37770.81114594409, 37844.810775945945, 37942.81028594839, 38051.80974095222, 37278.44082338821, 37865.0, 38025.619743802206, 38076.61923380731, 38555.42166867443, 30007.0, 36247.63752362443, 36460.270794583426, 37379.81310093541, 37465.81267093647, 28399.0, 32042.0, 33235.02359433767, 34966.30067398689, 35103.122421938635, 25289.62065569055, 25591.488170236862, 25811.870940645178, 25946.74053259443, 26196.0], "value": 16384.34360134527, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.1119014720334, 379.6715840797709, 381.75758393420176, 391.42656008946886, 396.3539430727913, 15581.610459738498, 15935.0, 16773.24520396582, 16778.244978975945, 16824.747628785564, 9655.951720241388, 10356.275060745751, 10358.637447689332, 10358.792824143513, 10367.118794902442, 3474.9131271718156, 4643.442786865581, 4828.0, 4830.806767729304, 5048.823291184809, 5297.735113244342, 5558.110702287635, 5559.249501317311, 5560.332760068797, 5569.10894256919, 5445.455454454563, 5576.972115139439, 5607.691576963265, 5608.214849921014, 6176.0, 6106.694665266763, 6141.754329826802, 6180.999999999977, 6183.258009038899, 6201.131841542165, 5331.893362132774, 5343.545798607123, 5416.322959630046, 5417.133258678593, 5457.154141108134, 12059.698507537381, 12133.21134126279, 12207.877921220763, 12224.938875305656, 18470.815291847044, 1769.6991511443086, 1796.380248814157, 1834.5413646588354, 1870.6726322893576, 2008.608321377332, 8595.871061934076, 8840.0, 9128.543572821332, 9163.862542061808, 9203.263738900872, 1705.0792572011098, 1724.5860993361628, 1738.4350086222028, 1774.7692799936056, 1784.161444121274, 59551.0, 62008.689956549926, 65403.35030300606, 66451.0, 66904.66547667279, 9567.091126342986, 9902.950485247527, 9947.850782238273, 9981.401115933068, 9991.400515969066, 13589.184648920898, 13597.864021359857, 13650.726985460435, 13655.931720341334, 13694.246816425193, 7494.362979146725, 7607.847843043108, 7710.151883292833, 7807.609619519115, 7819.100803407568, 7454.664540095709, 7492.288232617892, 7553.5845528495365, 7584.734534291384, 7600.50596711212, 3912.0415498203124, 4104.4048612950955, 4139.855105071308, 4183.728057676241, 4413.16149931512, 1728.6888360095052, 1821.8087100854484, 1836.4704710686347, 1844.4190080124717, 1845.713914343279, 810.7324582887633, 825.9050209225895, 869.3740506835069, 869.8956125264882, 965.7247684409909, 16046.197690115447, 16052.598685032734, 16111.758323625156, 16169.272382742569, 16571.17144142788, 863.1109956744563, 867.1718508824058, 870.7648934787572, 873.4191762477881, 917.060013486182, 8679.826403472021, 9135.54322283883, 9291.907080929104, 9362.721849180054, 9388.202002829836, 166828.16585916994, 169654.0, 169993.15003424903, 170165.14917425334, 356920.0, 60660.0, 80889.0, 82936.17063829284, 86544.0, 86913.0, 806.4757907360263, 854.7734850264685, 889.7227639667193, 910.8588168833842, 915.3089417489886, 15326.693466130391, 15349.0, 15389.615259618598, 15571.454999074978, 16051.357945682043, 34594.65405345914, 35676.0, 35692.0, 36958.445623315136, 37237.441438378984, 48704.75647621739, 50902.490975091256, 51054.23418648649, 51135.232971504716, 51362.0, 7372.520786148883, 7540.509866858856, 7595.240475952361, 7628.80927976805, 7779.4554381192775, 8113.310368618733, 9352.719418417459, 10449.791004179722, 10886.564537418415, 10893.183011274345, 9174.541272936192, 9219.76950576241, 9303.813923721624, 9351.48566828831, 9402.43585384879, 9603.951980240054, 9752.9024709752, 9858.802823943624, 9922.851157232792, 10188.439635820101, 8482.533460659723, 9094.545272736204, 9371.0, 9592.184664303333, 10012.349197302152, 5722.856928576819, 5903.291605007426, 6314.715837787402, 6402.103705481143, 6408.455281300955, 8377.581120943807, 8426.873596895928, 8839.116088391109, 8993.595288212176, 8996.775080623036], "value": 7673.496965422369, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.1080559816523, 1385.321192615618, 1483.3473271760424, 1669.1320513333076, 1684.7304431290995, 6510.0, 6976.5116441849095, 7030.64846757662, 7541.886871696922, 7550.358219551338, 6825.761098361559, 6947.8957815632675, 6971.302869713026, 6990.196127445345, 6994.3355381238825, 2104.6001259760674, 2130.0947097483586, 2131.0836340373635, 2142.8500004999655, 2151.0427859602482, 2070.2960993262286, 2086.7704552499213, 2186.0709198590534, 2186.7485239197536, 2195.648696208607, 2127.5744851029776, 2143.635581951064, 2149.5593403352277, 2160.5246845693923, 2191.6931629571873, 2041.3059559749681, 2047.1299697628522, 2048.2523878784286, 2068.679354700024, 2071.0, 2038.8165065144208, 2117.9788202117934, 2122.734658167729, 2144.603248399051, 2146.270268108835, 7129.714811407537, 7149.106361704787, 7158.534695244793, 7180.748673796392, 7200.747973820943, 4134.545200027995, 4277.679174061957, 4281.100968796525, 4289.742615443083, 4296.205202037633, 2770.986145069262, 2931.736143747073, 2933.8239705617516, 2936.060460652592, 2938.926526836825, 896.3501461440469, 919.3472634429496, 919.5723988345452, 936.3081141717562, 950.8668786369914, 9824.361416507976, 9848.06443387877, 9892.95053524735, 9896.356736812084, 9897.109260166548, 4051.3922911562913, 4097.528784189797, 4146.564610715832, 4165.97917010419, 4182.351735481006, 8537.829243415097, 8578.313734901192, 8611.913880861237, 8614.698485552975, 8622.223999840044, 4263.126059157865, 4300.075483770942, 4320.027993701455, 4346.282863327516, 4351.804168812348, 3452.5166476693284, 3831.942520862189, 3943.5464921533817, 4000.8599699010397, 4022.8591999279884, 3513.8594456221463, 3550.0, 3623.7463377563317, 3691.9077023074638, 3744.4196149596855, 1148.4028305281206, 1198.5205917632843, 1204.403820109034, 1205.5358686905604, 1211.4608998995539, 724.5218156017004, 774.3727580659571, 822.8560001999746, 861.763015170833, 880.4189235104928, 4953.0, 5020.648554601143, 5048.873778155502, 5112.872178195575, 5135.12702840518, 392.21556886227546, 404.4681244163915, 410.35983865169834, 410.9766680964437, 412.0317254452014, 3585.94621080684, 3622.5834029086463, 3629.491871138043, 3651.9634803651625, 3676.834542445543, 8455.408121431441, 8839.60221790021, 8899.644014239488, 8915.28677705782, 8917.331200160013, 8645.265152461985, 8752.687096935384, 8769.736907892773, 8785.780355491035, 8795.384323097449, 6700.061991321219, 6742.561733487308, 6746.089277947414, 6748.257691653914, 6748.763793267212, 4942.901141977212, 4993.1012417763695, 5014.423341315795, 5022.924656130234, 5072.163093089599, 8034.598270086589, 8158.836823263383, 8228.465149765247, 8240.21717936795, 8344.582770861554, 8241.876371854307, 8323.583820808813, 8364.0, 8413.705520307005, 8416.915830841857, 5659.54723622101, 6015.849603759766, 6172.660503672341, 6218.06728990655, 6225.346338634378, 6923.757668481579, 6951.7914462566205, 6962.408195303456, 7000.439964802702, 7020.368166865007, 6332.936670633235, 6492.870142597216, 6531.444827189552, 6584.934150658432, 6694.397504224643, 6045.093236014633, 6141.201643786279, 6283.842903927439, 6325.4939604832425, 6425.10048593188, 6048.4858787003595, 6079.361667024898, 6166.81499555014, 6190.938090619036, 6206.06908963659, 4403.317172240082, 4483.529229430863, 4485.394471746273, 4493.168763778712, 4517.0, 4465.687401881967, 4482.663800214932, 4518.892871246566, 4523.050159466549, 4548.363229147988], "value": 3875.8164498684514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.54685916540615, 292.3451468710089, 294.91152654203734, 299.2608257603718, 301.08920515440786, 5078.64449488536, 5078.873028174297, 5095.515925987034, 5109.770060347284, 5135.897282054357, 5029.7988080476825, 5034.345535080443, 5035.924461133086, 5041.890784027517, 5055.620828437864, 3689.9815500922523, 3838.8080595970227, 3903.7072219583647, 3910.7262491625597, 3930.390789427629, 3919.9216015679813, 3930.3711406175016, 3935.4490371348033, 3942.6648734857426, 3952.130531283113, 3888.3389823730035, 3942.6254505821903, 3956.0, 3958.8416463341578, 3976.125252444472, 3832.674222691047, 3879.8254078566533, 3892.5912779158066, 3909.3549564321993, 3935.8425662973445, 3400.996705971744, 3407.267437500949, 3426.3318652862704, 3430.3310854383403, 3432.622411534729, 4824.855254342376, 4829.589484893753, 4832.637552183601, 4834.564889159958, 4834.975825120887, 4345.73925564464, 4439.489458712224, 4458.242098843204, 4490.0, 4490.236659767847, 5172.663776854492, 5173.689578625294, 5192.532672059495, 5196.662216955886, 5244.396894357122, 3702.1670124221832, 3713.424419215026, 3714.4985426967555, 3724.404095344732, 3730.5336832895887, 5456.454354564551, 5460.426655201226, 5473.141435288112, 5492.231087647732, 5532.28080349556, 3735.6824669902817, 3787.715921305858, 3791.7156213284115, 3798.2023775007005, 3822.7132965027176, 3841.8079096045085, 3873.4770805941403, 3878.398848178537, 3879.2241551689776, 3879.8836034919, 4423.60187583119, 4437.600615944581, 4446.866594002185, 4470.150671372428, 4485.641148708096, 5102.744862756922, 5198.948010519846, 5275.182346736262, 5329.973350133225, 5356.098585002346, 1304.8238487804215, 1327.674719693662, 1327.6946302350514, 1333.3266750228595, 1334.6062911441147, 837.9036410812713, 841.381584535366, 842.6671464771381, 864.7276108025952, 875.1598465473216, 4466.39703640011, 4504.189245935698, 4562.429696287964, 4575.016371480081, 4583.679142459996, 5486.91769623448, 5511.0080185566185, 5523.7238138092935, 5546.750396232179, 5573.108302671553, 4409.14021765759, 4641.257398816173, 4645.326427667969, 4648.953510464852, 4659.930101048487, 2905.738483536492, 2936.985315073411, 2940.6765255821842, 2942.7498662613916, 2947.911562653081, 5142.6914385137015, 5158.819441319536, 5163.40620828602, 5171.379434467813, 5172.482751724872, 5466.753996070186, 5545.72271386429, 5584.692841893653, 5587.497125258747, 5588.926090650208, 5079.949200508096, 5107.463716309809, 5110.3356563646485, 5133.204353325241, 5200.271961925334, 5425.240466334795, 5434.402215756343, 5457.754401051883, 5463.207834864002, 5471.537807986501, 5139.406783897004, 5386.192071189353, 5387.218853266176, 5398.784048638167, 5400.702961337164, 5111.565516931102, 5117.769700363418, 5128.4615115413835, 5144.717040562656, 5188.58491320701, 3832.693384529175, 3844.961550384572, 3848.13416981177, 3855.730098893163, 3867.16855875988, 5230.293910322058, 5427.3215848019, 5436.646617969819, 5446.754896029767, 5478.808241711521, 5232.869178270422, 5302.231176479312, 5320.308359913187, 5328.614560214294, 5340.626156169031, 5231.869203269949, 5258.737063146751, 5283.973580132075, 5286.841394758163, 5349.277847490539, 4920.778564964657, 5253.947460525499, 5256.526912577886, 5296.364436267672, 5309.840704778709, 5080.187170052774, 5186.792528298825, 5324.056719297506, 5330.987112448623, 5351.545118664957, 5160.251763494197, 5181.35233095863, 5247.842564723064, 5256.421793602624, 5265.710385928811], "value": 3960.905986592609, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.7967230977623, 654.9345065493451, 714.1679942866557, 724.2757242757243, 747.7794050755028, 11507.59723409681, 11522.135839812006, 12048.819267710991, 12261.570845020426, 12291.078169137329, 11753.236039657437, 11945.044396448286, 11983.101267404958, 12021.27872327661, 12033.879661203386, 605.1104875832541, 769.4383100336757, 770.3644493293027, 788.3811208201563, 788.9684412623488, 789.6762327445748, 794.6940427935228, 797.5135167547792, 799.8120441696226, 800.8638531449668, 835.1147783349638, 838.4382463749272, 838.8322335532887, 838.9161083891623, 840.3361344537808, 821.9301359384459, 830.0910502999207, 831.7962099285684, 832.5878690048435, 833.6790335720731, 613.9386061393825, 614.2444792904707, 615.3630991923383, 615.7290792051527, 615.7506209984919, 11791.29252244868, 11867.643970680894, 11926.701832454171, 11973.700657483545, 12025.218360806519, 13706.52027179044, 13919.164850108924, 14002.369893354824, 14050.789238161437, 14059.297035148202, 9762.707118786448, 9788.168005719523, 9953.502324883726, 10508.894911050867, 10646.361218326847, 4195.811188496525, 4199.160167966419, 4200.159968006411, 4227.936580951288, 4375.803088861009, 56118.0, 56880.0, 57390.42609573893, 57561.0, 58389.41610583882, 9732.07545283197, 9790.5594248259, 9814.116729494379, 9889.80220395588, 10036.548355324028, 16452.588685282724, 16557.0, 16674.0, 16779.91610041942, 17087.316507339816, 6451.1290975717675, 6465.450436712932, 6498.935010649928, 6549.050387693853, 6607.867842643121, 3553.1650062235353, 3600.9099772505374, 3610.133567943727, 3630.8003059831435, 3711.16498787776, 2736.945261094767, 2823.3365159187574, 2823.435312937421, 2828.78784091194, 2830.872610732522, 492.8053418899515, 501.9974900125549, 506.23305691876834, 506.8732816795801, 511.2510172597163, 504.2360823352624, 527.6122050293031, 535.6384440502645, 539.3258426966293, 550.6971165858841, 6920.7231710731, 6952.2352541220425, 6988.580685158907, 7154.141503019671, 7201.567905925661, 495.247224219192, 516.4731973387125, 532.7043490862609, 545.9808906688247, 554.5369616370376, 3545.5213546170935, 3596.748227624041, 3681.9079523012138, 3684.9631503685155, 3688.5286857805336, 84390.0, 88918.11081889099, 90799.54600226956, 91402.25793226213, 91411.54294228619, 75525.0, 77758.0, 77762.0, 78870.60564697254, 80237.19762802414, 54590.0, 69174.0, 69258.3074169262, 69795.81225126509, 70055.29944700487, 6610.603363798187, 6652.600843949379, 6701.932980670131, 6806.693698783468, 7100.396466300423, 40616.79691601523, 42639.57360426356, 43827.342589861815, 44283.11433771371, 44947.32579011383, 43487.347689784045, 55368.44631553633, 57681.423185767606, 68633.0, 68865.31134688789, 11353.262037967506, 11360.602378916697, 11365.204435689422, 11863.822042669195, 12951.352432378531, 12793.42429590652, 12872.292023938773, 13120.343982801012, 13234.205947643171, 13353.66615834612, 11585.131115166228, 12247.265164090182, 12264.0, 12315.445804938978, 12375.62873113808, 11146.498407571517, 11639.185257032264, 11977.161598688357, 12333.753324933272, 12346.444410001392, 11112.277701949672, 11305.26515776472, 11458.312501249951, 11485.82771258415, 11733.471993760131, 9752.707418777449, 10123.696289111338, 10492.527836247235, 10556.524956376828, 10607.151427885596, 10059.748506287402, 10277.0, 10301.587936482756, 10378.325408848401, 10421.218408619232], "value": 6368.296249226065, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 115.0, 116.0, 115.0, 115.0, 116.0, 115.0, 115.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 137.0, 156.0, 188.0, 133.0, 157.0, 166.0, 133.0, 190.0, 132.0], "value": 155.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 165.0, 172.0, 163.0, 163.0, 162.0, 163.0, 163.0, 166.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 159.0, 78.0, 78.0, 76.0, 75.0, 165.0, 76.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 144.0, 85.0, 85.0, 85.0, 144.0, 85.0, 86.0, 147.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 119.0, 118.0, 119.0, 118.0, 119.0, 119.0, 117.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 148.0, 147.0, 148.0, 151.0, 147.0, 153.0, 149.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 101.0, 95.0, 95.0, 95.0, 96.0, 98.0, 96.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 67.0, 64.0, 64.0, 66.0, 64.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 66.0, 65.0, 69.0, 65.0, 65.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 85.0, 84.0, 88.0, 88.0, 84.0, 85.0, 115.0, 91.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 133.0, 134.0, 133.0, 130.0, 156.0, 139.0, 159.0, 138.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 176.0, 171.0, 171.0, 170.0, 173.0, 177.0, 176.0, 175.0, 165.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 69.0, 69.0, 68.0, 69.0, 69.0, 68.0, 67.0], "value": 68.1, "unit": "score"}]}]} 18:52:08 INFO - Return code: 0 18:52:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:52:08 ERROR - # TBPL SUCCESS # 18:52:08 INFO - Running post-action listener: _resource_record_post_action 18:52:08 INFO - ##### 18:52:08 INFO - ##### Finished run-tests step (success) 18:52:08 INFO - ##### 18:52:08 INFO - Running post-run listener: _resource_record_post_run 18:52:09 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 523440128; Read time: 8; Write time: 456408 18:52:09 INFO - TinderboxPrint: CPU usage
13.4% 18:52:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:52:09 INFO - TinderboxPrint: I/O write bytes / time
523,440,128 / 456,408 18:52:09 INFO - TinderboxPrint: CPU idle
8,259.4 (86.5%) 18:52:09 INFO - TinderboxPrint: CPU user
1,229.6 (12.9%) 18:52:09 INFO - TinderboxPrint: Swap in / out
0 / 0 18:52:09 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237297664; Read time: 0; Write time: 215416 18:52:09 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285843456; Read time: 8; Write time: 240940 18:52:09 INFO - Running post-run listener: _upload_blobber_files 18:52:09 INFO - Blob upload gear active. 18:52:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:52:09 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 18:52:09 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:52:09 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:52:10 INFO - (blobuploader) - INFO - Open directory for files ... 18:52:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:52:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:52:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:11 INFO - (blobuploader) - INFO - Done attempting. 18:52:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:52:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:52:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:15 INFO - (blobuploader) - INFO - Done attempting. 18:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:52:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:17 INFO - (blobuploader) - INFO - Done attempting. 18:52:17 INFO - (blobuploader) - INFO - Iteration through files over. 18:52:17 INFO - Return code: 0 18:52:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:52:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:52:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4c2a501f9327f5baa50e123f58b85acca29ca098504b7e6a7c697331273cf03dcfe9582705a08f9e3fc7fbbcb55cb60c0c23cfefd69214c011728de54fff2460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/447e6239be9920dbe1e667642e69115821338a50a2f759ca3559ce81ec66d4918d081dc67e82d2e6efd8c81c6c1a57004cba23dc5d4cca7e6f065b3b6c021fe9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2fabdda941474c997d319da615a8f541408a5a1762d1abe32ba2b86f40068e5b853553c73ebc8d7aeeb2d91bc4c01814222972249b05c3819b3094237a89a02d"} 18:52:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:52:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:52:17 INFO - Contents: 18:52:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/4c2a501f9327f5baa50e123f58b85acca29ca098504b7e6a7c697331273cf03dcfe9582705a08f9e3fc7fbbcb55cb60c0c23cfefd69214c011728de54fff2460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/447e6239be9920dbe1e667642e69115821338a50a2f759ca3559ce81ec66d4918d081dc67e82d2e6efd8c81c6c1a57004cba23dc5d4cca7e6f065b3b6c021fe9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2fabdda941474c997d319da615a8f541408a5a1762d1abe32ba2b86f40068e5b853553c73ebc8d7aeeb2d91bc4c01814222972249b05c3819b3094237a89a02d"} 18:52:17 INFO - Running post-run listener: copy_logs_to_upload_dir 18:52:17 INFO - Copying logs to upload dir... 18:52:17 INFO - mkdir: /builds/slave/test/build/upload/logs 18:52:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.752532 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-06-01 18:52:16.630412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:16.633932) ========= 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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 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/Larch-Non-PGO/sha512/4c2a501f9327f5baa50e123f58b85acca29ca098504b7e6a7c697331273cf03dcfe9582705a08f9e3fc7fbbcb55cb60c0c23cfefd69214c011728de54fff2460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/447e6239be9920dbe1e667642e69115821338a50a2f759ca3559ce81ec66d4918d081dc67e82d2e6efd8c81c6c1a57004cba23dc5d4cca7e6f065b3b6c021fe9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2fabdda941474c997d319da615a8f541408a5a1762d1abe32ba2b86f40068e5b853553c73ebc8d7aeeb2d91bc4c01814222972249b05c3819b3094237a89a02d"} build_url:https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/N6ox9COwQlGfduF2pP1_dw/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/Larch-Non-PGO/sha512/4c2a501f9327f5baa50e123f58b85acca29ca098504b7e6a7c697331273cf03dcfe9582705a08f9e3fc7fbbcb55cb60c0c23cfefd69214c011728de54fff2460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/447e6239be9920dbe1e667642e69115821338a50a2f759ca3559ce81ec66d4918d081dc67e82d2e6efd8c81c6c1a57004cba23dc5d4cca7e6f065b3b6c021fe9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2fabdda941474c997d319da615a8f541408a5a1762d1abe32ba2b86f40068e5b853553c73ebc8d7aeeb2d91bc4c01814222972249b05c3819b3094237a89a02d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:16.663760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:16.664094) ========= 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=6dbc10da6d83fee962c624be000001a8-1464830250.403266-767535066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004549 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:52:16.749041) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:52:16.749817) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:52:16.750482) ========= ========= Total master_lag: 0.18 =========