builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-020 starttime: 1464727248.9 results: success (0) buildid: 20160531122540 builduid: 110a2d9dc13a4e7e9e2cd5af6d2ae613 revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:48.903068) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:48.903499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:48.903836) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:48.946394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:48.946744) ========= 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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-31 13:40:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 90.0M=0s 2016-05-31 13:40:49 (90.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124066 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:49.087723) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:49.088061) ========= 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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015439 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:49.145134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 13:40:49.145533) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --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 23a96762bc11fdbd26f032d3fcbcbf7417797acb --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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-31 13:40:49,288 truncating revision to first 12 chars 2016-05-31 13:40:49,288 Setting DEBUG logging. 2016-05-31 13:40:49,288 attempt 1/10 2016-05-31 13:40:49,288 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/23a96762bc11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 13:40:50,585 unpacking tar archive at: larch-23a96762bc11/testing/mozharness/ program finished with exit code 0 elapsedTime=1.608745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 13:40:50.773251) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:50.773718) ========= script_repo_revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:50.774799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:50.775195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 13:40:50.796082) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-31 13:40:50.796389) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:40:51 INFO - MultiFileLogger online at 20160531 13:40:51 in /builds/slave/test 13:40:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 13:40:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:40:51 INFO - {'append_to_log': False, 13:40:51 INFO - 'base_work_dir': '/builds/slave/test', 13:40:51 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 13:40:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:40:51 INFO - 'branch': 'Larch-Non-PGO', 13:40:51 INFO - 'buildbot_json_path': 'buildprops.json', 13:40:51 INFO - 'config_files': ('talos/linux_config.py',), 13:40:51 INFO - 'default_actions': ('clobber', 13:40:51 INFO - 'read-buildbot-config', 13:40:51 INFO - 'download-and-extract', 13:40:51 INFO - 'populate-webroot', 13:40:51 INFO - 'create-virtualenv', 13:40:51 INFO - 'install', 13:40:51 INFO - 'run-tests'), 13:40:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:40:51 INFO - 'download_minidump_stackwalk': True, 13:40:51 INFO - 'download_symbols': 'ondemand', 13:40:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:40:51 INFO - 'tooltool.py': '/tools/tooltool.py', 13:40:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:40:51 INFO - '/tools/misc-python/virtualenv.py')}, 13:40:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:40:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:40:51 INFO - 'installer_path': 'installer.exe', 13:40:51 INFO - 'log_level': 'info', 13:40:51 INFO - 'log_name': 'talos', 13:40:51 INFO - 'log_to_console': True, 13:40:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:40:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:40:51 INFO - 'opt_config_files': (), 13:40:51 INFO - 'pip_index': False, 13:40:51 INFO - 'sps_profile': False, 13:40:51 INFO - 'sps_profile_interval': 0, 13:40:51 INFO - 'suite': 'dromaeojs', 13:40:51 INFO - 'system_bits': '32', 13:40:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:40:51 INFO - 'title': 'talos-linux64-ix-020', 13:40:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:40:51 INFO - 'use_talos_json': True, 13:40:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:40:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:40:51 INFO - 'work_dir': 'build'} 13:40:51 INFO - ##### 13:40:51 INFO - ##### Running clobber step. 13:40:51 INFO - ##### 13:40:51 INFO - Running pre-action listener: _resource_record_pre_action 13:40:51 INFO - Running main action method: clobber 13:40:51 INFO - rmtree: /builds/slave/test/build 13:40:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:40:51 INFO - Running post-action listener: _resource_record_post_action 13:40:51 INFO - ##### 13:40:51 INFO - ##### Finished clobber step (success) 13:40:51 INFO - ##### 13:40:51 INFO - ##### 13:40:51 INFO - ##### Running read-buildbot-config step. 13:40:51 INFO - ##### 13:40:51 INFO - Running pre-action listener: _resource_record_pre_action 13:40:51 INFO - Running main action method: read_buildbot_config 13:40:51 INFO - Using buildbot properties: 13:40:51 INFO - { 13:40:51 INFO - "project": "", 13:40:51 INFO - "product": "firefox", 13:40:51 INFO - "script_repo_revision": "production", 13:40:51 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 13:40:51 INFO - "repository": "", 13:40:51 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 13:40:51 INFO - "buildid": "20160531122540", 13:40:51 INFO - "pgo_build": "False", 13:40:51 INFO - "basedir": "/builds/slave/test", 13:40:51 INFO - "buildnumber": 3, 13:40:51 INFO - "slavename": "talos-linux64-ix-020", 13:40:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:40:51 INFO - "platform": "ubuntu64_hw", 13:40:51 INFO - "scheduler": "tests-larch-linux64-talos", 13:40:51 INFO - "branch": "larch", 13:40:51 INFO - "repo_path": "projects/larch", 13:40:51 INFO - "revision": "23a96762bc11fdbd26f032d3fcbcbf7417797acb", 13:40:51 INFO - "stage_platform": "linux64", 13:40:51 INFO - "builduid": "110a2d9dc13a4e7e9e2cd5af6d2ae613", 13:40:51 INFO - "slavebuilddir": "test" 13:40:51 INFO - } 13:40:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 13:40:51 INFO - Running post-action listener: _resource_record_post_action 13:40:51 INFO - ##### 13:40:51 INFO - ##### Finished read-buildbot-config step (success) 13:40:51 INFO - ##### 13:40:51 INFO - ##### 13:40:51 INFO - ##### Running download-and-extract step. 13:40:51 INFO - ##### 13:40:51 INFO - Running pre-action listener: _resource_record_pre_action 13:40:51 INFO - Running main action method: download_and_extract 13:40:51 INFO - mkdir: /builds/slave/test/build/tests 13:40:51 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')]} 13:40:51 INFO - https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:40:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:40:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:40:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:52 INFO - Downloaded 1361 bytes. 13:40:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:40:52 INFO - Using the following test package requirements: 13:40:52 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 13:40:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:40:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:40:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'jsshell-linux-x86_64.zip'], 13:40:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:40:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 13:40:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:40:52 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:40:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:40:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:40:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:40:52 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')]} 13:40:52 INFO - https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:40:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:40:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:40:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:54 INFO - Downloaded 22840344 bytes. 13:40:54 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'] 13:40:54 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 13:40:55 INFO - Return code: 0 13:40:55 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 13:40: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')]} 13:40:55 INFO - https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:40:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:40:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:40:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:57 INFO - Downloaded 22840344 bytes. 13:40:57 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'] 13:40:57 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 13:40:58 INFO - Return code: 0 13:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:40:58 INFO - https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:40:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:40:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:40:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:59 INFO - Downloaded 10834063 bytes. 13:40:59 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'] 13:40:59 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 13:40:59 INFO - Return code: 0 13:40: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')]} 13:40:59 INFO - https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:40:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:40:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:40:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:40:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/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 13:41:01 INFO - Downloaded 55126964 bytes. 13:41:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:41:01 INFO - mkdir: /builds/slave/test/properties 13:41:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:41:01 INFO - Writing to file /builds/slave/test/properties/build_url 13:41:01 INFO - Contents: 13:41:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:41:01 INFO - Running post-action listener: _resource_record_post_action 13:41:01 INFO - Running post-action listener: set_extra_try_arguments 13:41:01 INFO - ##### 13:41:01 INFO - ##### Finished download-and-extract step (success) 13:41:01 INFO - ##### 13:41:01 INFO - ##### 13:41:01 INFO - ##### Running populate-webroot step. 13:41:01 INFO - ##### 13:41:01 INFO - Running pre-action listener: _resource_record_pre_action 13:41:01 INFO - Running main action method: populate_webroot 13:41:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:41:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:41:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:41:01 INFO - '--tppagecycles', 13:41:01 INFO - '7'], 13:41:01 INFO - 'tests': ['tsvgm']}}, 13:41:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:41:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:41:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:41:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:41:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:41:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:41:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['damp', 'tps']}, 13:41:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'tests': ['damp', 'tps']}, 13:41:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['dromaeo_dom']}, 13:41:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:41:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'other-e10s': {'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['a11yr', 13:41:01 INFO - 'ts_paint', 13:41:01 INFO - 'tpaint', 13:41:01 INFO - 'sessionrestore', 13:41:01 INFO - 'sessionrestore_no_auto_restore', 13:41:01 INFO - 'tabpaint']}, 13:41:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['tsvgx', 13:41:01 INFO - 'tsvgr_opacity', 13:41:01 INFO - 'tart', 13:41:01 INFO - 'tscrollx', 13:41:01 INFO - 'cart']}, 13:41:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:41:01 INFO - 'tsvgr_opacity', 13:41:01 INFO - 'tart', 13:41:01 INFO - 'tscrollx', 13:41:01 INFO - 'cart']}, 13:41:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'talos_options': ['--disable-e10s'], 13:41:01 INFO - 'tests': ['tp5o']}, 13:41:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'tests': ['tp5o']}, 13:41:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'talos_options': ['--disable-e10s', 13:41:01 INFO - '--xperf_path', 13:41:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:41:01 INFO - 'tests': ['tp5n']}, 13:41:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:01 INFO - 'talos_options': ['--xperf_path', 13:41:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:41:01 INFO - 'tests': ['tp5n']}}, 13:41:01 INFO - 'talos.zip': {'path': '', 13:41:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:41:01 INFO - Running post-action listener: _resource_record_post_action 13:41:01 INFO - ##### 13:41:01 INFO - ##### Finished populate-webroot step (success) 13:41:01 INFO - ##### 13:41:01 INFO - ##### 13:41:01 INFO - ##### Running create-virtualenv step. 13:41:01 INFO - ##### 13:41:01 INFO - Running pre-action listener: _resource_record_pre_action 13:41:01 INFO - Running main action method: create_virtualenv 13:41:01 INFO - Creating virtualenv /builds/slave/test/build/venv 13:41:01 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 13:41:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:41:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:41:02 INFO - Using real prefix '/usr' 13:41:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:41:02 INFO - Installing distribute.............................................................................................................................................................................................done. 13:41:05 INFO - Installing pip.................done. 13:41:05 INFO - Return code: 0 13:41:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:05 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:05 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 13:41:05 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 13:41:05 INFO - Using env: {'DISPLAY': ':0', 13:41:05 INFO - 'HOME': '/home/cltbld', 13:41:05 INFO - 'LANG': 'en_US.UTF-8', 13:41:05 INFO - 'LANGUAGE': 'en_US:en', 13:41:05 INFO - 'LOGNAME': 'cltbld', 13:41:05 INFO - 'MAIL': '/var/mail/cltbld', 13:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:05 INFO - 'MOZ_NO_REMOTE': '1', 13:41:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:05 INFO - 'NO_EM_RESTART': '1', 13:41:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:05 INFO - 'PWD': '/builds/slave/test', 13:41:05 INFO - 'SHELL': '/bin/bash', 13:41:05 INFO - 'SHLVL': '1', 13:41:05 INFO - 'TERM': 'linux', 13:41:05 INFO - 'TMOUT': '86400', 13:41:05 INFO - 'USER': 'cltbld', 13:41:05 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:05 INFO - '_': '/tools/buildbot/bin/python'} 13:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:05 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 13:41:05 INFO - Cleaning up... 13:41:05 INFO - Return code: 0 13:41:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:05 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:05 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 13:41:05 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 13:41:05 INFO - Using env: {'DISPLAY': ':0', 13:41:05 INFO - 'HOME': '/home/cltbld', 13:41:05 INFO - 'LANG': 'en_US.UTF-8', 13:41:05 INFO - 'LANGUAGE': 'en_US:en', 13:41:05 INFO - 'LOGNAME': 'cltbld', 13:41:05 INFO - 'MAIL': '/var/mail/cltbld', 13:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:05 INFO - 'MOZ_NO_REMOTE': '1', 13:41:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:05 INFO - 'NO_EM_RESTART': '1', 13:41:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:05 INFO - 'PWD': '/builds/slave/test', 13:41:05 INFO - 'SHELL': '/bin/bash', 13:41:05 INFO - 'SHLVL': '1', 13:41:05 INFO - 'TERM': 'linux', 13:41:05 INFO - 'TMOUT': '86400', 13:41:05 INFO - 'USER': 'cltbld', 13:41:05 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:05 INFO - '_': '/tools/buildbot/bin/python'} 13:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:06 INFO - Downloading/unpacking psutil>=3.1.1 13:41: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 13:41: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 13:41: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 13:41: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 13:41: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 13:41: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 13:41:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:41:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:41:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:41:08 INFO - Installing collected packages: psutil 13:41:08 INFO - Running setup.py install for psutil 13:41:08 INFO - building 'psutil._psutil_linux' extension 13:41:08 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 13:41:08 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 13:41:08 INFO - building 'psutil._psutil_posix' extension 13:41:08 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 13:41:08 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 13:41:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:41:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:41:08 INFO - Successfully installed psutil 13:41:08 INFO - Cleaning up... 13:41:08 INFO - Return code: 0 13:41:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:41:08 INFO - Using env: {'DISPLAY': ':0', 13:41:08 INFO - 'HOME': '/home/cltbld', 13:41:08 INFO - 'LANG': 'en_US.UTF-8', 13:41:08 INFO - 'LANGUAGE': 'en_US:en', 13:41:08 INFO - 'LOGNAME': 'cltbld', 13:41:08 INFO - 'MAIL': '/var/mail/cltbld', 13:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:08 INFO - 'MOZ_NO_REMOTE': '1', 13:41:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:08 INFO - 'NO_EM_RESTART': '1', 13:41:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:08 INFO - 'PWD': '/builds/slave/test', 13:41:08 INFO - 'SHELL': '/bin/bash', 13:41:08 INFO - 'SHLVL': '1', 13:41:08 INFO - 'TERM': 'linux', 13:41:08 INFO - 'TMOUT': '86400', 13:41:08 INFO - 'USER': 'cltbld', 13:41:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:08 INFO - '_': '/tools/buildbot/bin/python'} 13:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:41: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 13:41: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 13:41: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 13:41: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 13:41: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 13:41: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 13:41:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:41:11 INFO - Installing collected packages: mozsystemmonitor 13:41:11 INFO - Running setup.py install for mozsystemmonitor 13:41:11 INFO - Successfully installed mozsystemmonitor 13:41:11 INFO - Cleaning up... 13:41:11 INFO - Return code: 0 13:41:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:41:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:41:12 INFO - Using env: {'DISPLAY': ':0', 13:41:12 INFO - 'HOME': '/home/cltbld', 13:41:12 INFO - 'LANG': 'en_US.UTF-8', 13:41:12 INFO - 'LANGUAGE': 'en_US:en', 13:41:12 INFO - 'LOGNAME': 'cltbld', 13:41:12 INFO - 'MAIL': '/var/mail/cltbld', 13:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:12 INFO - 'MOZ_NO_REMOTE': '1', 13:41:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:12 INFO - 'NO_EM_RESTART': '1', 13:41:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:12 INFO - 'PWD': '/builds/slave/test', 13:41:12 INFO - 'SHELL': '/bin/bash', 13:41:12 INFO - 'SHLVL': '1', 13:41:12 INFO - 'TERM': 'linux', 13:41:12 INFO - 'TMOUT': '86400', 13:41:12 INFO - 'USER': 'cltbld', 13:41:12 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:12 INFO - '_': '/tools/buildbot/bin/python'} 13:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:12 INFO - Downloading/unpacking blobuploader==1.2.4 13:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:14 INFO - Downloading blobuploader-1.2.4.tar.gz 13:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:41:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:41:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:14 INFO - Downloading docopt-0.6.1.tar.gz 13:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:41:14 INFO - Installing collected packages: blobuploader, requests, docopt 13:41:14 INFO - Running setup.py install for blobuploader 13:41:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:41:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:41:14 INFO - Running setup.py install for requests 13:41:15 INFO - Running setup.py install for docopt 13:41:15 INFO - Successfully installed blobuploader requests docopt 13:41:15 INFO - Cleaning up... 13:41:15 INFO - Return code: 0 13:41:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:41:15 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')]} 13:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:15 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')]} 13:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:15 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:15 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 13:41:15 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 13:41:15 INFO - Using env: {'DISPLAY': ':0', 13:41:15 INFO - 'HOME': '/home/cltbld', 13:41:15 INFO - 'LANG': 'en_US.UTF-8', 13:41:15 INFO - 'LANGUAGE': 'en_US:en', 13:41:15 INFO - 'LOGNAME': 'cltbld', 13:41:15 INFO - 'MAIL': '/var/mail/cltbld', 13:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:15 INFO - 'MOZ_NO_REMOTE': '1', 13:41:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:15 INFO - 'NO_EM_RESTART': '1', 13:41:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:15 INFO - 'PWD': '/builds/slave/test', 13:41:15 INFO - 'SHELL': '/bin/bash', 13:41:15 INFO - 'SHLVL': '1', 13:41:15 INFO - 'TERM': 'linux', 13:41:15 INFO - 'TMOUT': '86400', 13:41:15 INFO - 'USER': 'cltbld', 13:41:15 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:15 INFO - '_': '/tools/buildbot/bin/python'} 13:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:41:15 INFO - Running setup.py (path:/tmp/pip-TS7HCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:41:15 INFO - Running setup.py (path:/tmp/pip-2oIixI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:41:15 INFO - Running setup.py (path:/tmp/pip-DXHPIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:41:15 INFO - Running setup.py (path:/tmp/pip-xaY9FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:41:15 INFO - Running setup.py (path:/tmp/pip-jnHEvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:41:15 INFO - Running setup.py (path:/tmp/pip-JnO9hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:41:16 INFO - Running setup.py (path:/tmp/pip-tNpQ6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:41:16 INFO - Running setup.py (path:/tmp/pip-vq5cw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:41:16 INFO - Running setup.py (path:/tmp/pip-lkQI26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:41:16 INFO - Running setup.py (path:/tmp/pip-3RCXBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:41:16 INFO - Running setup.py (path:/tmp/pip-o2nlNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:41:16 INFO - Running setup.py (path:/tmp/pip-mYiM0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:41:16 INFO - Running setup.py (path:/tmp/pip-PS_dXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:41:16 INFO - Running setup.py (path:/tmp/pip-kUyegh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:41:16 INFO - Running setup.py (path:/tmp/pip-q06xQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:41:16 INFO - Running setup.py (path:/tmp/pip-AeA2sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:41:16 INFO - Running setup.py (path:/tmp/pip-ZAJ3Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:41:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:41:16 INFO - Running setup.py install for manifestparser 13:41:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:41:16 INFO - Running setup.py install for mozcrash 13:41:17 INFO - Running setup.py install for mozdebug 13:41:17 INFO - Running setup.py install for mozdevice 13:41:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for mozfile 13:41:17 INFO - Running setup.py install for mozhttpd 13:41:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for mozinfo 13:41:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for mozInstall 13:41:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for mozleak 13:41:17 INFO - Running setup.py install for mozlog 13:41:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for moznetwork 13:41:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:41:17 INFO - Running setup.py install for mozprocess 13:41:18 INFO - Running setup.py install for mozprofile 13:41:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:41:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:41:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:41:18 INFO - Running setup.py install for mozrunner 13:41:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:41:18 INFO - Running setup.py install for mozscreenshot 13:41:18 INFO - Running setup.py install for moztest 13:41:18 INFO - Running setup.py install for mozversion 13:41:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:41:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:41:18 INFO - Cleaning up... 13:41:18 INFO - Return code: 0 13:41:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:41:18 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')]} 13:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:18 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')]} 13:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:18 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:18 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 13:41:18 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 13:41:18 INFO - Using env: {'DISPLAY': ':0', 13:41:18 INFO - 'HOME': '/home/cltbld', 13:41:18 INFO - 'LANG': 'en_US.UTF-8', 13:41:18 INFO - 'LANGUAGE': 'en_US:en', 13:41:18 INFO - 'LOGNAME': 'cltbld', 13:41:18 INFO - 'MAIL': '/var/mail/cltbld', 13:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:18 INFO - 'MOZ_NO_REMOTE': '1', 13:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:18 INFO - 'NO_EM_RESTART': '1', 13:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:18 INFO - 'PWD': '/builds/slave/test', 13:41:18 INFO - 'SHELL': '/bin/bash', 13:41:18 INFO - 'SHLVL': '1', 13:41:18 INFO - 'TERM': 'linux', 13:41:18 INFO - 'TMOUT': '86400', 13:41:18 INFO - 'USER': 'cltbld', 13:41:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:18 INFO - '_': '/tools/buildbot/bin/python'} 13:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:41:18 INFO - Running setup.py (path:/tmp/pip-iovrbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:41:18 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)) 13:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:41:18 INFO - Running setup.py (path:/tmp/pip-2gIsql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:41:18 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)) 13:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:41:18 INFO - Running setup.py (path:/tmp/pip-kqnppO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:41:19 INFO - Running setup.py (path:/tmp/pip-LS626b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:41:19 INFO - Running setup.py (path:/tmp/pip-V5LC4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:41:19 INFO - Running setup.py (path:/tmp/pip-tRcvtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:41:19 INFO - Running setup.py (path:/tmp/pip-jowfLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:41:19 INFO - Running setup.py (path:/tmp/pip-nfTRWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:41:19 INFO - Running setup.py (path:/tmp/pip-cswAIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:41:19 INFO - Running setup.py (path:/tmp/pip-0Ilqo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:41:19 INFO - Running setup.py (path:/tmp/pip-PVUR8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:41:19 INFO - Running setup.py (path:/tmp/pip-t6HXgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:41:19 INFO - Running setup.py (path:/tmp/pip-8KJgOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:41:19 INFO - Running setup.py (path:/tmp/pip-I_k4cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:41:19 INFO - Running setup.py (path:/tmp/pip-qahuqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:41:19 INFO - Running setup.py (path:/tmp/pip-PukeUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:41:19 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)) 13:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:41:19 INFO - Running setup.py (path:/tmp/pip-OaBUSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:41:20 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)) 13:41:20 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)) 13:41:20 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)) 13:41:20 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)) 13:41:20 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)) 13:41:20 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)) 13:41:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:41:20 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 13:41:20 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 13:41:20 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 13:41:20 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 13:41:20 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 13:41:20 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 13:41:21 INFO - Downloading blessings-1.6.tar.gz 13:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:41:22 INFO - Installing collected packages: blessings 13:41:22 INFO - Running setup.py install for blessings 13:41:22 INFO - Successfully installed blessings 13:41:22 INFO - Cleaning up... 13:41:22 INFO - Return code: 0 13:41:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:41:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:41:22 INFO - Reading from file tmpfile_stdout 13:41:22 INFO - Current package versions: 13:41:22 INFO - argparse == 1.2.1 13:41:22 INFO - blessings == 1.6 13:41:22 INFO - blobuploader == 1.2.4 13:41:22 INFO - docopt == 0.6.1 13:41:22 INFO - manifestparser == 1.1 13:41:22 INFO - mozInstall == 1.12 13:41:22 INFO - mozcrash == 0.17 13:41:22 INFO - mozdebug == 0.1 13:41:22 INFO - mozdevice == 0.48 13:41:22 INFO - mozfile == 1.2 13:41:22 INFO - mozhttpd == 0.7 13:41:22 INFO - mozinfo == 0.9 13:41:22 INFO - mozleak == 0.1 13:41:22 INFO - mozlog == 3.2 13:41:22 INFO - moznetwork == 0.27 13:41:22 INFO - mozprocess == 0.22 13:41:22 INFO - mozprofile == 0.28 13:41:22 INFO - mozrunner == 6.11 13:41:22 INFO - mozscreenshot == 0.1 13:41:22 INFO - mozsystemmonitor == 0.3 13:41:22 INFO - moztest == 0.7 13:41:22 INFO - mozversion == 1.4 13:41:22 INFO - psutil == 3.1.1 13:41:22 INFO - requests == 1.2.3 13:41:22 INFO - wsgiref == 0.1.2 13:41:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:22 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:22 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 13:41:22 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 13:41:22 INFO - Using env: {'DISPLAY': ':0', 13:41:22 INFO - 'HOME': '/home/cltbld', 13:41:22 INFO - 'LANG': 'en_US.UTF-8', 13:41:22 INFO - 'LANGUAGE': 'en_US:en', 13:41:22 INFO - 'LOGNAME': 'cltbld', 13:41:22 INFO - 'MAIL': '/var/mail/cltbld', 13:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:22 INFO - 'MOZ_NO_REMOTE': '1', 13:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:22 INFO - 'NO_EM_RESTART': '1', 13:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:22 INFO - 'PWD': '/builds/slave/test', 13:41:22 INFO - 'SHELL': '/bin/bash', 13:41:22 INFO - 'SHLVL': '1', 13:41:22 INFO - 'TERM': 'linux', 13:41:22 INFO - 'TMOUT': '86400', 13:41:22 INFO - 'USER': 'cltbld', 13:41:22 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:22 INFO - '_': '/tools/buildbot/bin/python'} 13:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 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)) 13:41:22 INFO - Cleaning up... 13:41:22 INFO - Return code: 0 13:41:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:22 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:22 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 13:41:22 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 13:41:22 INFO - Using env: {'DISPLAY': ':0', 13:41:22 INFO - 'HOME': '/home/cltbld', 13:41:22 INFO - 'LANG': 'en_US.UTF-8', 13:41:22 INFO - 'LANGUAGE': 'en_US:en', 13:41:22 INFO - 'LOGNAME': 'cltbld', 13:41:22 INFO - 'MAIL': '/var/mail/cltbld', 13:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:22 INFO - 'MOZ_NO_REMOTE': '1', 13:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:22 INFO - 'NO_EM_RESTART': '1', 13:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:22 INFO - 'PWD': '/builds/slave/test', 13:41:22 INFO - 'SHELL': '/bin/bash', 13:41:22 INFO - 'SHLVL': '1', 13:41:22 INFO - 'TERM': 'linux', 13:41:22 INFO - 'TMOUT': '86400', 13:41:22 INFO - 'USER': 'cltbld', 13:41:22 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:22 INFO - '_': '/tools/buildbot/bin/python'} 13:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:22 INFO - Downloading/unpacking jsonschema 13:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:41:24 INFO - Downloading/unpacking functools32 (from jsonschema) 13:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:24 INFO - Downloading functools32-3.2.3-2.tar.gz 13:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:41:24 INFO - warning: no files found matching '*.txt' 13:41:24 INFO - no previously-included directories found matching 'build' 13:41:24 INFO - no previously-included directories found matching 'dist' 13:41:24 INFO - no previously-included directories found matching '.git*' 13:41:24 INFO - Installing collected packages: jsonschema, functools32 13:41:25 INFO - Running setup.py install for functools32 13:41:25 INFO - warning: no files found matching '*.txt' 13:41:25 INFO - no previously-included directories found matching 'build' 13:41:25 INFO - no previously-included directories found matching 'dist' 13:41:25 INFO - no previously-included directories found matching '.git*' 13:41:25 INFO - Successfully installed jsonschema functools32 13:41:25 INFO - Cleaning up... 13:41:25 INFO - Return code: 0 13:41:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:25 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 0x7f6076f11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, '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': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', '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 13:41:25 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 13:41:25 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 13:41:25 INFO - Using env: {'DISPLAY': ':0', 13:41:25 INFO - 'HOME': '/home/cltbld', 13:41:25 INFO - 'LANG': 'en_US.UTF-8', 13:41:25 INFO - 'LANGUAGE': 'en_US:en', 13:41:25 INFO - 'LOGNAME': 'cltbld', 13:41:25 INFO - 'MAIL': '/var/mail/cltbld', 13:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:25 INFO - 'MOZ_NO_REMOTE': '1', 13:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:25 INFO - 'NO_EM_RESTART': '1', 13:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:25 INFO - 'PWD': '/builds/slave/test', 13:41:25 INFO - 'SHELL': '/bin/bash', 13:41:25 INFO - 'SHLVL': '1', 13:41:25 INFO - 'TERM': 'linux', 13:41:25 INFO - 'TMOUT': '86400', 13:41:25 INFO - 'USER': 'cltbld', 13:41:25 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:25 INFO - '_': '/tools/buildbot/bin/python'} 13:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:25 INFO - Downloading/unpacking flake8 13:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:41:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:41:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:41:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 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 13:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:41:27 INFO - no previously-included directories found matching 'docs/_build' 13:41:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:41:28 INFO - Running setup.py install for pep8 13:41:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:41:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:41:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:41:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:41:28 INFO - no previously-included directories found matching 'docs/_build' 13:41:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:41:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:41:28 INFO - Cleaning up... 13:41:28 INFO - Return code: 0 13:41:28 INFO - Running post-action listener: _resource_record_post_action 13:41:28 INFO - Running post-action listener: _start_resource_monitoring 13:41:28 INFO - Starting resource monitoring. 13:41:28 INFO - ##### 13:41:28 INFO - ##### Finished create-virtualenv step (success) 13:41:28 INFO - ##### 13:41:28 INFO - ##### 13:41:28 INFO - ##### Running install step. 13:41:28 INFO - ##### 13:41:28 INFO - Running pre-action listener: _resource_record_pre_action 13:41:28 INFO - Running main action method: install 13:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:41:28 INFO - Reading from file tmpfile_stdout 13:41:28 INFO - Detecting whether we're running mozinstall >=1.0... 13:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:41:28 INFO - Reading from file tmpfile_stdout 13:41:28 INFO - Output received: 13:41:28 INFO - Usage: mozinstall [options] installer 13:41:28 INFO - Options: 13:41:28 INFO - -h, --help show this help message and exit 13:41:28 INFO - -d DEST, --destination=DEST 13:41:28 INFO - Directory to install application into. [default: 13:41:28 INFO - "/builds/slave/test"] 13:41:28 INFO - --app=APP Application being installed. [default: firefox] 13:41:28 INFO - mkdir: /builds/slave/test/build/application 13:41:28 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'] 13:41:28 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 13:41:44 INFO - Reading from file tmpfile_stdout 13:41:44 INFO - Output received: 13:41:44 INFO - /builds/slave/test/build/application/firefox/firefox 13:41:44 INFO - Running post-action listener: _resource_record_post_action 13:41:44 INFO - ##### 13:41:44 INFO - ##### Finished install step (success) 13:41:44 INFO - ##### 13:41:44 INFO - ##### 13:41:44 INFO - ##### Running run-tests step. 13:41:44 INFO - ##### 13:41:44 INFO - Running pre-action listener: _resource_record_pre_action 13:41:44 INFO - Running main action method: run_tests 13:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:41:44 INFO - Python 2.7.3 13:41:44 INFO - Return code: 0 13:41:44 INFO - grabbing minidump binary from tooltool 13:41: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')]} 13:41:44 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 0x258b9c0>, '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 0x258c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x258cd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:41:44 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 13:41:44 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 13:41:44 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 13:41:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:41:44 INFO - Return code: 0 13:41:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:41:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:41:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:41:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:41:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:41:45 INFO - Return code: 0 13:41:45 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:41:45 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:41:45 INFO - Using env: {'DISPLAY': ':0', 13:41:45 INFO - 'HOME': '/home/cltbld', 13:41:45 INFO - 'LANG': 'en_US.UTF-8', 13:41:45 INFO - 'LANGUAGE': 'en_US:en', 13:41:45 INFO - 'LOGNAME': 'cltbld', 13:41:45 INFO - 'MAIL': '/var/mail/cltbld', 13:41:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:41:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:45 INFO - 'MOZ_NO_REMOTE': '1', 13:41:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:45 INFO - 'NO_EM_RESTART': '1', 13:41:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:45 INFO - 'PWD': '/builds/slave/test', 13:41:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:41:45 INFO - 'SHELL': '/bin/bash', 13:41:45 INFO - 'SHLVL': '1', 13:41:45 INFO - 'TERM': 'linux', 13:41:45 INFO - 'TMOUT': '86400', 13:41:45 INFO - 'USER': 'cltbld', 13:41:45 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536', 13:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:45 INFO - '_': '/tools/buildbot/bin/python'} 13:41:45 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:41:45 INFO - mozversion application_buildid: 20160531122540 13:41:45 INFO - mozversion application_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 13:41:45 INFO - mozversion application_display_name: Nightly 13:41:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:41:45 INFO - mozversion application_name: Firefox 13:41:45 INFO - mozversion application_remotingname: firefox 13:41:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:41:45 INFO - mozversion application_vendor: Mozilla 13:41:45 INFO - mozversion application_version: 49.0a1 13:41:45 INFO - mozversion platform_buildid: 20160531122540 13:41:45 INFO - mozversion platform_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 13:41:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:41:45 INFO - mozversion platform_version: 49.0a1 13:41:45 INFO - using testdate: 1464727305 13:41:45 INFO - actual date: 1464727305 13:41:45 INFO - starting webserver on 'localhost:48090' 13:41:45 INFO - SUITE-START | Running 2 tests 13:41:45 INFO - TEST-START | dromaeo_css 13:41:45 INFO - operating with platform_type : linux_ 13:41:45 INFO - Initialising browser for dromaeo_css test... 13:41:45 INFO - TEST-INFO | started process 3907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpceXtlq/profile http://localhost:48090/getInfo.html) 13:41:51 INFO - PROCESS | 3907 | __metrics Screen width/height:1600/1200 13:41:51 INFO - PROCESS | 3907 | colorDepth:24 13:41:51 INFO - PROCESS | 3907 | Browser inner width/height: 1024/697 13:41:51 INFO - PROCESS | 3907 | __metrics 13:41:52 ERROR - PROCESS | 3907 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:41:53 INFO - TEST-INFO | 3907: exit 0 13:41:53 INFO - Browser initialized. 13:41:53 INFO - Running cycle 1/1 for dromaeo_css test... 13:41:53 INFO - TEST-INFO | started process 3990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpceXtlq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:42:05 INFO - PROCESS | 3990 | RSS: Main: 157356032 13:42:05 INFO - PROCESS | 3990 | 13:42:57 INFO - PROCESS | 3990 | 1464727377146 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] 13:45:09 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48090/tests/dromaeo/cssquery-ext.html) 13:45:10 INFO - PROCESS | 3990 | RSS: Main: 236285952 13:45:10 INFO - PROCESS | 3990 | 13:48:12 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-ext.html (next: http://localhost:48090/tests/dromaeo/cssquery-jquery.html) 13:48:13 INFO - PROCESS | 3990 | RSS: Main: 238211072 13:48:13 INFO - PROCESS | 3990 | 13:51:15 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48090/tests/dromaeo/cssquery-mootools.html) 13:51:16 INFO - PROCESS | 3990 | RSS: Main: 247083008 13:51:16 INFO - PROCESS | 3990 | 13:54:18 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48090/tests/dromaeo/cssquery-prototype.html) 13:54:19 INFO - PROCESS | 3990 | RSS: Main: 240099328 13:54:19 INFO - PROCESS | 3990 | 13:57:21 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48090/tests/dromaeo/cssquery-yui.html) 13:57:22 INFO - PROCESS | 3990 | RSS: Main: 214347776 13:57:22 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:48090/tests/dromaeo/cssquery-yui.html (next: http://localhost:48090/tests/dromaeo/cssquery-dojo.html) 14:00:25 INFO - PROCESS | 3990 | RSS: Main: 283447296 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | __start_tp_report 14:00:25 INFO - PROCESS | 3990 | _x_x_mozilla_page_load 14:00:25 INFO - PROCESS | 3990 | _x_x_mozilla_page_load_details 14:00:25 INFO - PROCESS | 3990 | |i|pagename|runs| 14:00:25 INFO - PROCESS | 3990 | |0;dojo.html;2666.7333266673327;2675.47828173506;2696.555068413712;2703.8918443262264;2707.878145483453;4549.636029117671;4613.077384523101;4639.536046395358;4711.481737008926;4763.761811909404;4654.8138074477065;4660.231061874787;4663.207254766689;4693.671442998992;4706;4075.714699971003;4221.345691417835;4300.935485967713;4329.220740266751;4438.001449673814;4341.196878577473;4375.540568240337;4392.209402307583;4403.889902752425;4429.7785110744335;4166.500019997605;4205.831766729327;4259.893502662428;4283.0363168287195;4285.228658841407;4239.130978149472;4265.914681706348;4278.101598664284;4317.676174286941;4333.133373325316;4289.6139347458875;4305.655547556219;4315.956840431619;4338.132373525307;4356.019895523514;4492.213862574036;4538.818447262105;4579;4602.470715867685;4606.5393460654;4993.176125939216;5022.020705962339;5057.241413787924;5100.795968161269;5106.157484015133;5116.539511443988;5119.360079990001;5144.228365745131;5148.279240906276;5153.742312884341;4647.093816705744;4683.7892294846815;4719.032598317337;4720.197566413718;4781.354517140211;5216.869578260498;5223.4776522347465;5229.6339256251695;5231.503007214308;5232.947670523322;4854.5873600744335;4978.626603004716;5021.372328458942;5026.095302845523;5026.874328141753;5005.27423523587;5058.671186372948;5063.3417655704525;5079.466656001141;5089.592832573459;5040.218766091261;5045.2432135180015;5050.469700681376;5052.191649336162;5105.081085404663;4895.339129217581;4942.481039490874;5027.773750181251;5040.899182016339;5041.949580504148;4317.784110794448;4337.067530480962;4340.848070317524;4366.716163449366;4384.276594361894;3765.1151979285396;3785.5835858055593;3793.6206379361843;3832.693384529231;3838.1939792643857;4243.618074373321;4278.358246263086;4298.978505107517;4335.3713711511655;4343.761093139845;4993.500649934977;5022.648414610942;5031.7987280509105;5043.9747801260755;5086.491350864958;4330.718503297276;4332.978335108304;4335.566443355639;4371.912561748811;4392.165488557164;4459.152760975404;4497.033137875373;4527.1172121436;4565.360849481075;4580.862574122782;4656.9301460477445;4682.2508398656055;4688.531146885421;4738.3840100787365;4738.691985020962;4985.825496107619;4986.12742770021;4995.275685025724;4999.275105109811;5037.269595908646;4591.4949355571525;4635.884102897455;4651.86044418668;4671.976640116778;4677.883052923704;4938.407391113089;4963.950360496348;4979.103761322996;4979.477154898684;5019;4993.500649934977;5008.549230569266;5011.824586139468;5013.774380152975;5080.1363768160245;4811.446683631287;4857.441394239708;4896.975515122402;4907.77914993819;4955.702657840541;4886.193778026586;4902.534259245366;4910.467048181559;4916.237983112624;4960.578350840075;4815.422149342101;4832.154372984784;4834.830780922651;4862.854114376574;4883.780229889592;4820.638452116035;4896.338994235733;4945.035718035093;4962.602991760578;4984.925226121683;4845.49122342149;4877.587673693143;4882.974575339219;4887.3890763654545;4910.877228069184 14:00:25 INFO - PROCESS | 3990 | |1;ext.html;561.393764165026;582.7872826418358;591.0926727473332;594.1978329255503;596.6449962272449;27802.30494237645;40519;41586.37620435696;41599.37600935988;41626.791866040665;41976;42430.15139697204;42517;42582.78708606461;42602.360964585554;7396.852062958737;7694.576798276106;7885.1326354101;7887.526748395086;9961.800763984716;9696.612135514571;10042.146417554517;10045.447500387476;10048.547815348327;10049.547770350315;10036;10112.59549618018;10122;10126.886042535316;10128.746781330452;7608.35328997036;9895.802083958353;9896.455694936776;10184.236182286359;10190.89809101907;10012.499375031291;10105.39367637944;10114.494275286279;10128.54421551032;10130.392176469435;38326.61673383258;38389.61610383888;38805.80597097025;38850.222995540214;38854.805725971186;3020.879164833404;3063.1270088024808;3078.6459557150993;3126.8436578171227;3214.1321843102464;29416.11751647454;29755.851220743756;29855.552166717516;30127.548086778716;30247;3253.723433508155;3294.374068926896;3298.323843612078;3314.66853314669;3384.5092461593176;262821.9999999981;263461;263753;263808;264699;52658;52938.47061529412;53075.73462132664;53080.734596327544;53084.469155307954;33103.83448082744;34340.31319373648;56549;56596.43403565994;56737;13339.66650833737;13414.530491432948;13432.261225632488;13522.850557702706;13523.39144738489;9653.17947974416;9899.80200395988;9940.502974851373;9960;10161.390316581028;18424.263029478938;18437.53906152357;18493.260269589067;18514.351997680016;18528.258869645062;6877.65611719422;6908.9309106909295;6953.374196322356;7065.92934070663;7149.499535032498;784.8351846112266;880.9735707928772;921.5530467723149;929.3958926697566;994.5077186792583;27569.310767230978;28601.85699071533;28637.71362286392;28670.426591468055;29190.416191676046;947.5641205045619;951.543259235557;1012.888582255966;1017.9389236645825;1085.9348439093521;14486.203258820866;14608.780868286984;14979.70040599182;15073;15190.696186076217;401424;401599;402888;403927;406307;50809.49190508122;51222.23166652428;51242.23136652953;51245;51288.23067653988;1087.206339372247;1125.431657013203;1156.4102307822902;1205.8492688413949;1244.3467179730715;26297.868510657325;29039.56440653434;29642.258943526584;29777.85111074431;29986.25034374158;42593.36109958415;42907.570924290354;43038.56961430346;43065;43127.35308970431;292832;294057;294368;294512;295024;22323.553528929657;24832;25693.229203123938;26603.33491662724;26859.597106043817;42211.15577688368;42424;42462;43011.784941076345;43130.56869431266;36120.638793611724;36335.81832090928;36514;36876.631233687316;37140.628593713715;35707;36555.81722091373;36843.447348290334;36877.26245475022;37136.442953356265;35349.469757953135;35539.289214216085;35637.287254254254;36086.63913360938;36384;31966.52050219295;32268.555466879676;32496;32498.350032999682;32749.67250327466;24623.138190163976;24735.50528989446;24784.628230576196;25353.492930141663;25401.87299063493 14:00:25 INFO - PROCESS | 3990 | |2;jquery.html;366.3369301564169;367.88227767114523;387.83904679557986;390.78897395406483;395.1780296982277;15734.921325393372;16176.757348639765;16992.915035424838;17002.744958825595;17027.659446811056;11894.52421903125;15571.922140389283;16244.75632865508;16289.511314660549;17041.82958170418;4623.398958135438;4715.339852420664;4765.046990601876;4794.592459640935;4803.543663351993;3515.5605549306338;5160.096983027974;5422.945770542303;5436.891262174754;5452.263944367499;5424;5425.050616142179;5431.565474762009;5438.83683489494;5450.345958484987;5092.618053645972;5415.566754659618;5423.918641220384;5486.396496385394;5489.3412790465;3397.3545026444895;4893.632977526664;5249.527542521191;5249.658772179834;5300;11306.717332066683;11704.590339338083;12310.199837010565;12471.93764031174;12530.248185108923;1852.2128095559333;1904.209752952523;1939.8933058681766;1968.3602829080462;2433.7322894481595;8845.734627961172;8880.600372983232;9187.81624367509;9360.204382627484;9426.481543515103;1661.551381127089;1735.8350956659096;1738.75657407963;1768.4252617899235;1787.436957358428;66423.66788166076;66974;67194.3280567193;67717.96846094626;68098.31901680969;9867.753306167404;10264.230182736175;10265.435401053013;10265.897341026493;10391.740206494747;14216.502422415166;14247.715045699235;14312.355943982546;14323.57029289123;14335.354909029118;7412.1476030256135;7504.174540800616;7669.233076692398;7760.883586746203;7785.688572457151;7198.100237470317;7315.122185337687;7318.853622927618;7402.7409040683315;7481.401487880957;4339.696221264481;4421.5578442156175;4475.686701930898;4510.436195475566;4547.295169248771;1635.2069246415479;1783.2153852304943;2913.927151821179;2990.7158819912074;3051.755859531232;782.3350152370554;823.4524041512447;939.1829108675496;969.0890562870878;990.6829814459304;16175.757363639555;16707.164641767864;17021.574460638334;17190.968541887527;17333.47999560015;844.0799528513827;858.4891989266376;879.9428037177563;912.82656295305;916.2898753465984;9426.62293508252;9497.952510237543;9881.789615442196;9982;10091.142252908585;368982;381695;382985;383880;384186;68038.65980670066;78525.21474785294;78940.21059789443;79072.20927790763;79194;897.4301318662697;902.891653001644;921.953902304882;922.9261659067258;926.5274709897866;14970.47603333878;15141.167235802137;15181.16503592313;15390.461333853262;15430.305636246172;33501.497477538345;34137.31725365528;34460.31079378448;35235;35980.64019359773;44027.77986110049;46062.539374607164;48078.27882581834;48214;48272.275915861996;7773.3004029637605;7839.451238413257;7844.960775196316;7942.205779422243;8052.033755949089;10956.616518421817;11788.941055294668;11850.348230847041;11859.288442693465;12039.63881083569;10111.292209545261;10144.087032167141;10187.337823041478;10405.58377664885;10494.842577361193;10079.748006299902;10128.797424051625;10168.745781355525;10382.3251488653;10442.373457592264;10316.27786054969;10397.740056498647;10464.529096190841;10490.265681402228;10652.520636571528;7724.227577242231;7763.844723105393;7801.843963120819;7818.804529886798;7976.122626511195;7834.4907581007055;10217.438040907822;10320.12278956297;10492.422916739653;10589.735256618338 14:00:25 INFO - PROCESS | 3990 | |3;mootools.html;1244.5955064604007;1377.3595278195637;1422.815034045574;1506.8041154649893;1747.6592064547403;6837.521373503851;7678.347340476066;7885.487443316195;7892.921070789291;8022.518648881071;7773.416993725465;7925.167857374979;7926.9207307927045;7940.801479963004;7941.7617471475805;2169.4467910682793;2192.967105493419;2195.736511618608;2195.9890200548975;2200.2409168836725;2182.8035476807086;2236.284388995522;2238.6865838782583;2240.6863039174527;2241.932742017742;2183.465051062492;2189.5292512109895;2191.2659259148204;2195.297504798465;2238.3620668109593;2164.2050823014633;2181.0839447432118;2186.431527802767;2189.1681161158817;2190.0144934779332;2162.8269738421045;2166.6100101981638;2169.739631244245;2173.0873033325884;2173.445771328317;7710.57591832449;7765.1070126935565;7871.055473343178;7872.370210383156;7989;4642.210824159901;4779.187538118528;4779.545943135397;4780.282957556359;4782.163121453732;2770.321271288547;2906.157214407819;2945.4109178164454;2947.688278715972;2967.7329040386467;913.6665117232229;921.4977837078741;929.168394287116;982.921366290695;1018.2159737002466;10600.098991585724;10667.253292269545;10674.466276686135;10703.839442408447;10708.1433485321;3970.861019864291;4058.066644671683;4116.526599441103;4118;4139.19285739278;8424.873626895602;8478.109798471196;8485.0666426693;8523.275521580736;8527;4573.702709323883;4633.4208223972;4634.21218392874;4636.374089497942;4636.675432719745;3779.3952967525065;3892.902677433087;4002.4996875390575;4256.446661933929;4300.569943005675;3310.519974603717;3405.7105146062368;3423.281110966725;3448.6551344865816;3456.948145777815;1008.4151192308443;1030.7835354575473;1031.0668844695563;1034.829253173218;1035.1563475767225;669.2003056347618;716.6560051175361;751.7143485475484;765.6669348833275;792.5918152151646;5322.494363035506;5348.304720386325;5457.672539647634;5463.863403414867;5534.972325138348;391.5810083210937;396.6985091330552;400.0658462490118;404.6095517825313;405.3635791806894;3672.485851980725;3885.68914486846;3890.6498415142205;3921.1961547882615;3942.6254505821903;9514.33399662031;9842.11420972116;9853.06395892389;9918;9923.057309555581;9853.113219810108;9855.753606159904;9863.506824658738;9869.161121304625;9880.802383952281;7368.415800581731;7471;7640.579768112696;7644.655990480442;7659.387249020065;5436.9456305438025;5443.510084092451;5482.451754824486;5566.666000040011;5584.218209450762;8993.820123597623;9086.318526110701;9097.909020909707;9146.222571081533;9183.265338773015;9186.954065229631;9389.014153513783;9406.200472959876;9411.905880941104;9427.104425079606;5815.709214539341;6257.906131408124;6400.743970241138;6408.743650254124;6413.77551785704;7435.739749108755;7453.739119130804;7478.775636730907;7484.139323977594;7503.174650788518;6729.461643068445;6885.139357580302;6963.1644202696;6969.686364113526;7036.648167591702;6781.355771201728;6847.281035491202;6867.484938629523;6955.269696681776;6966;6033.064874944391;6195.101710252078;6201.658908759881;6241.96879015602;6326.2092238470195;4600.7469589172915;4727.220008698664;4747.382840230748;4752.857414277577;4765.166095933128;4642.37327960721;4643.094596553639;4674.392328997209;4757.595604373529;4780.378550788375 14:00:25 INFO - PROCESS | 3990 | |4;prototype.html;301.434809731753;303.5931851319232;307.1139761787244;308.0895952460478;310.97667674924384;4771.713697178167;4774.069056533973;4781.211100168468;4831.637627177963;4849.612031037517;4738.668293219476;4752.572268495835;4770.332153498513;4780.4741478437345;4784.5933095686905;3569.054200636827;3657.963420365789;3691.1913162550427;3756.9436458453147;3813.466114743938;3721.9441708374397;3744.64425879541;3749.1189570450924;3788.4317352397084;3804;3535.7171426285963;3707.536557930259;3719.0516418313427;3727;3729.403295472725;3597.4963505109176;3706.610805865372;3708.9258214835822;3710.7588006779606;3721.832517536717;3256.8208748518823;3264.183954011497;3267.0198940318046;3268.5750852389037;3275.1812046988252;4522.23122069249;4526.411566496335;4528.9547104528865;4570.611498022672;4589.151007063704;3962.8811135665687;4226.978865105685;4233.534311225762;4257.169851878885;4279.144171165748;4975;5000.524950129732;5003.249512573107;5011.6241281904;5017.473165317626;3464.272502774395;3501.212227248874;3503.684668379858;3517.788932664023;3518.8240587970754;5092.872678183038;5199.14214154664;5211.869703257411;5224.791008359661;5260.184671375943;3619.76471529355;3637.9449959511444;3641.253543023674;3661.7436779425716;3664.7434679572707;3684.8341824617955;3706.4440333950124;3709.221063576625;3721.2371463850386;3741.607131251234;4240.618344349024;4248.256555102845;4266.914661706748;4287.97856010718;4288.270993931039;4859.538343857328;4937.432195297587;4966.701997880139;5003.874903127451;5033.7734801934;1261.4323554400557;1276.8020956751718;1288.5361269942814;1289.7807372746656;1292.1407264169222;802.4102284820652;810.9984169550601;838.7209505504155;858.7505179963264;863.3438586674173;4396.988692600656;4409.294512877924;4420.734755914656;4463.397441345442;4471.1728330259;5072.097963263776;5082.466341034138;5100.285959965608;5121.7695203715175;5137.178051511815;4328.398492557711;4368.432103826546;4383.254846676073;4390.736555806662;4408.7575183365225;2721.71422000691;2722.4282900591097;2744.67063952327;2746.9725302747115;2747.4505098980285;4767.499412561631;4774.618030557547;4791.6645834791925;4807.134715751128;4808.831690890802;5079.5428411443145;5195.402528709171;5203.687778733288;5210.739463026834;5221.642372983052;4713.81144754206;4741.881452963635;4743.217369134054;4759.881427864519;4770.856874293777;5096.566791822736;5098.490150984871;5119.6416250862085;5119.769610367468;5129.358380434005;4789.784459699252;4989.8752531185555;5017.523335283142;5031.84904452867;5032;4751.453582838018;4754.786034628381;4773.307870358848;4776.593989510791;4786.928196076992;3602.8919132426067;3606.260716553048;3622.0039489139645;3623.3115708015393;3631.473436351767;4843.903121937471;5078.97460512695;5111.309973153577;5115.25828754821;5151.175811870083;4933.5066493351815;4954.696066329174;4959.975200123977;4969.975150124226;4974.427940786856;4922.72924989129;4936.9259461107395;4948.233023881304;4964.230544265644;4997.075541024922;4638.8608341749805;4909.1899836528;4912.778924948315;4928.2853986171085;4966.478519755518;4839.540243676845;4951.851444456672;5054.620903432184;5062.721550314768;5091.261767043684;4929.852104436873;4950.8762280943265;4956.157453232887;4962.082014827269;4969.6769709970185 14:00:25 INFO - PROCESS | 3990 | |5;yui.html;630.7319389259561;657.9374959378857;665.8967859981705;722.8482018776058;763.248200522485;11096.500657470413;11310.434478276084;11839;11933.522659093636;12007.3395963222;11911.880881191186;11954.521819127247;12032.33822139782;12040.638780836589;12055.095867809905;634.1154090044386;650.5803756577005;757.515190278221;774.5739843086308;776.1074764021392;762.5462849604477;763.2215140556629;774.8334108166744;795.3954994204418;795.645937557787;831.8128421105264;836.3016880904422;837.3175861672718;837.6105111123327;837.6984285657189;660.0330515794384;803.9758807235793;825.1294883897485;833.3041910005122;834.9206825351582;599.2060519811267;599.4544964082664;599.7810799058312;600.7747094839435;601.3685630088389;11919.463624136935;11938.343391113485;11970.940145299304;12006.939965300206;12012.519499220021;11908.702282443008;12106.455209515592;12381.319027453486;12386.442610082568;12542.435590398454;10983.61557345497;10988;11000.779984400267;11236.49435775384;11350.262232954832;4089.407035979796;4115.218108559364;4125.484314460692;4167.791610419466;4229.471316085489;45521.54478455206;45548.544514554764;45671.77164114192;45802.312965305544;46012;9474.431534107976;9870.950645246729;9898.80202395948;9948.403095814274;10041.548130334153;17332;17353.826461735473;17389.826101739072;17394.826051739572;17417.825821741873;6540.509461790385;6586.901196482057;6647;6694.297098804653;6743.763968261087;3550.5739311282296;3567.7859328440377;3574.7855128692363;3649.7810131392202;3663.798491083016;2651.7878569714376;2714.6335244742104;2720.782337413002;2729.344957210295;2730.2218867622632;518.7561845932406;524.2372348233388;535.8258565966077;539.2962184349461;541.1990254423475;525.6740820691119;630.1996464490072;651.491836367634;653.9280679125293;670.11210146556;8122.268995790408;8463.788405289793;8482.957585212034;8496.617652205665;8570.528620925785;555.9249501317352;568.7270110347057;573.8594044459135;576.9989068965342;604.6885853785304;3799.9413405183177;3930.331843586597;3976.264391087658;4013.337799263147;4096.446979657768;53073.46926530763;54230.728846355516;54490.18264726032;55443;55524.72237638786;47983.28025079626;48331.27503087457;49210.75394623004;49341.25988110181;49499;44628.55371446309;45003.77498112554;45313.320300194864;45539.31691024637;45958;7802.453828231969;7912.28789408956;7939.960300198462;8110.878336825071;8155.755327340189;30568.84715576408;30742.385152297276;30852.53721194139;31257.433182222954;32041.51937720983;38336.42495362516;38379.616203837606;39852.80073599729;40309.596904030586;40349.1930161401;10227.59089636436;10317.793644127225;10450.477476126316;10587.20595955291;10727.356358618508;10247.590096396061;10450.58197672115;10467.110295624652;10890;11121.332720036824;8062.3888411502;10597.25819192649;11197.496112674786;11250.718732031764;11414.657560273205;11576.421178940851;11687.707807304885;11898.940505297418;11960.880391195977;12185.756284874431;11260.324380537195;11554.537818487166;11724.941375293069;11776;11842.230255033395;9115.361924665474;9297.628094876129;9396.775430714497;9544.141027307576;9607.471589062668;9743.625387242877;10153.187744980532;10171.219017451804;10215.537600994407;10218.642347517802 14:00:25 INFO - PROCESS | 3990 | __end_tp_report 14:00:25 INFO - PROCESS | 3990 | __start_cc_report 14:00:25 INFO - PROCESS | 3990 | _x_x_mozilla_cycle_collect,1089 14:00:25 INFO - PROCESS | 3990 | __end_cc_report 14:00:25 INFO - PROCESS | 3990 | __startTimestamp1464728425507__endTimestamp 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | ------- Summary: start ------- 14:00:25 INFO - PROCESS | 3990 | Number of tests: 6 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#0] dojo.html Cycles:165 Average:4650.74 Median:4738.69 stddev:483.52 (10.2%) stddev-sans-first:459.27 14:00:25 INFO - PROCESS | 3990 | Values: 2666.7 2675.5 2696.6 2703.9 2707.9 4549.6 4613.1 4639.5 4711.5 4763.8 4654.8 4660.2 4663.2 4693.7 4706.0 4075.7 4221.3 4300.9 4329.2 4438.0 4341.2 4375.5 4392.2 4403.9 4429.8 4166.5 4205.8 4259.9 4283.0 4285.2 4239.1 4265.9 4278.1 4317.7 4333.1 4289.6 4305.7 4316.0 4338.1 4356.0 4492.2 4538.8 4579.0 4602.5 4606.5 4993.2 5022.0 5057.2 5100.8 5106.2 5116.5 5119.4 5144.2 5148.3 5153.7 4647.1 4683.8 4719.0 4720.2 4781.4 5216.9 5223.5 5229.6 5231.5 5232.9 4854.6 4978.6 5021.4 5026.1 5026.9 5005.3 5058.7 5063.3 5079.5 5089.6 5040.2 5045.2 5050.5 5052.2 5105.1 4895.3 4942.5 5027.8 5040.9 5041.9 4317.8 4337.1 4340.8 4366.7 4384.3 3765.1 3785.6 3793.6 3832.7 3838.2 4243.6 4278.4 4299.0 4335.4 4343.8 4993.5 5022.6 5031.8 5044.0 5086.5 4330.7 4333.0 4335.6 4371.9 4392.2 4459.2 4497.0 4527.1 4565.4 4580.9 4656.9 4682.3 4688.5 4738.4 4738.7 4985.8 4986.1 4995.3 4999.3 5037.3 4591.5 4635.9 4651.9 4672.0 4677.9 4938.4 4964.0 4979.1 4979.5 5019.0 4993.5 5008.5 5011.8 5013.8 5080.1 4811.4 4857.4 4897.0 4907.8 4955.7 4886.2 4902.5 4910.5 4916.2 4960.6 4815.4 4832.2 4834.8 4862.9 4883.8 4820.6 4896.3 4945.0 4962.6 4984.9 4845.5 4877.6 4883.0 4887.4 4910.9 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#1] ext.html Cycles:180 Average:47740.91 Median:3349.59 stddev:85727.47 (2559.3%) stddev-sans-first:85894.78 14:00:25 INFO - PROCESS | 3990 | Values: 561.4 582.8 591.1 594.2 596.6 27802.3 40519.0 41586.4 41599.4 41626.8 41976.0 42430.2 42517.0 42582.8 42602.4 7396.9 7694.6 7885.1 7887.5 9961.8 9696.6 10042.1 10045.4 10048.5 10049.5 10036.0 10112.6 10122.0 10126.9 10128.7 7608.4 9895.8 9896.5 10184.2 10190.9 10012.5 10105.4 10114.5 10128.5 10130.4 38326.6 38389.6 38805.8 38850.2 38854.8 3020.9 3063.1 3078.6 3126.8 3214.1 29416.1 29755.9 29855.6 30127.5 30247.0 3253.7 3294.4 3298.3 3314.7 3384.5 262822.0 263461.0 263753.0 263808.0 264699.0 52658.0 52938.5 53075.7 53080.7 53084.5 33103.8 34340.3 56549.0 56596.4 56737.0 13339.7 13414.5 13432.3 13522.9 13523.4 9653.2 9899.8 9940.5 9960.0 10161.4 18424.3 18437.5 18493.3 18514.4 18528.3 6877.7 6908.9 6953.4 7065.9 7149.5 784.8 881.0 921.6 929.4 994.5 27569.3 28601.9 28637.7 28670.4 29190.4 947.6 951.5 1012.9 1017.9 1085.9 14486.2 14608.8 14979.7 15073.0 15190.7 401424.0 401599.0 402888.0 403927.0 406307.0 50809.5 51222.2 51242.2 51245.0 51288.2 1087.2 1125.4 1156.4 1205.8 1244.3 26297.9 29039.6 29642.3 29777.9 29986.3 42593.4 42907.6 43038.6 43065.0 43127.4 292832.0 294057.0 294368.0 294512.0 295024.0 22323.6 24832.0 25693.2 26603.3 26859.6 42211.2 42424.0 42462.0 43011.8 43130.6 36120.6 36335.8 36514.0 36876.6 37140.6 35707.0 36555.8 36843.4 36877.3 37136.4 35349.5 35539.3 35637.3 36086.6 36384.0 31966.5 32268.6 32496.0 32498.4 32749.7 24623.1 24735.5 24784.6 25353.5 25401.9 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#2] jquery.html Cycles:180 Average:23553.92 Median:2367.44 stddev:62800.73 (2652.7%) stddev-sans-first:62952.77 14:00:25 INFO - PROCESS | 3990 | Values: 366.3 367.9 387.8 390.8 395.2 15734.9 16176.8 16992.9 17002.7 17027.7 11894.5 15571.9 16244.8 16289.5 17041.8 4623.4 4715.3 4765.0 4794.6 4803.5 3515.6 5160.1 5422.9 5436.9 5452.3 5424.0 5425.1 5431.6 5438.8 5450.3 5092.6 5415.6 5423.9 5486.4 5489.3 3397.4 4893.6 5249.5 5249.7 5300.0 11306.7 11704.6 12310.2 12471.9 12530.2 1852.2 1904.2 1939.9 1968.4 2433.7 8845.7 8880.6 9187.8 9360.2 9426.5 1661.6 1735.8 1738.8 1768.4 1787.4 66423.7 66974.0 67194.3 67718.0 68098.3 9867.8 10264.2 10265.4 10265.9 10391.7 14216.5 14247.7 14312.4 14323.6 14335.4 7412.1 7504.2 7669.2 7760.9 7785.7 7198.1 7315.1 7318.9 7402.7 7481.4 4339.7 4421.6 4475.7 4510.4 4547.3 1635.2 1783.2 2913.9 2990.7 3051.8 782.3 823.5 939.2 969.1 990.7 16175.8 16707.2 17021.6 17191.0 17333.5 844.1 858.5 879.9 912.8 916.3 9426.6 9498.0 9881.8 9982.0 10091.1 368982.0 381695.0 382985.0 383880.0 384186.0 68038.7 78525.2 78940.2 79072.2 79194.0 897.4 902.9 922.0 922.9 926.5 14970.5 15141.2 15181.2 15390.5 15430.3 33501.5 34137.3 34460.3 35235.0 35980.6 44027.8 46062.5 48078.3 48214.0 48272.3 7773.3 7839.5 7845.0 7942.2 8052.0 10956.6 11788.9 11850.3 11859.3 12039.6 10111.3 10144.1 10187.3 10405.6 10494.8 10079.7 10128.8 10168.7 10382.3 10442.4 10316.3 10397.7 10464.5 10490.3 10652.5 7724.2 7763.8 7801.8 7818.8 7976.1 7834.5 10217.4 10320.1 10492.4 10589.7 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#3] mootools.html Cycles:180 Average:5154.73 Median:5052.33 stddev:2929.28 (58.0%) stddev-sans-first:2922.76 14:00:25 INFO - PROCESS | 3990 | Values: 1244.6 1377.4 1422.8 1506.8 1747.7 6837.5 7678.3 7885.5 7892.9 8022.5 7773.4 7925.2 7926.9 7940.8 7941.8 2169.4 2193.0 2195.7 2196.0 2200.2 2182.8 2236.3 2238.7 2240.7 2241.9 2183.5 2189.5 2191.3 2195.3 2238.4 2164.2 2181.1 2186.4 2189.2 2190.0 2162.8 2166.6 2169.7 2173.1 2173.4 7710.6 7765.1 7871.1 7872.4 7989.0 4642.2 4779.2 4779.5 4780.3 4782.2 2770.3 2906.2 2945.4 2947.7 2967.7 913.7 921.5 929.2 982.9 1018.2 10600.1 10667.3 10674.5 10703.8 10708.1 3970.9 4058.1 4116.5 4118.0 4139.2 8424.9 8478.1 8485.1 8523.3 8527.0 4573.7 4633.4 4634.2 4636.4 4636.7 3779.4 3892.9 4002.5 4256.4 4300.6 3310.5 3405.7 3423.3 3448.7 3456.9 1008.4 1030.8 1031.1 1034.8 1035.2 669.2 716.7 751.7 765.7 792.6 5322.5 5348.3 5457.7 5463.9 5535.0 391.6 396.7 400.1 404.6 405.4 3672.5 3885.7 3890.6 3921.2 3942.6 9514.3 9842.1 9853.1 9918.0 9923.1 9853.1 9855.8 9863.5 9869.2 9880.8 7368.4 7471.0 7640.6 7644.7 7659.4 5436.9 5443.5 5482.5 5566.7 5584.2 8993.8 9086.3 9097.9 9146.2 9183.3 9187.0 9389.0 9406.2 9411.9 9427.1 5815.7 6257.9 6400.7 6408.7 6413.8 7435.7 7453.7 7478.8 7484.1 7503.2 6729.5 6885.1 6963.2 6969.7 7036.6 6781.4 6847.3 6867.5 6955.3 6966.0 6033.1 6195.1 6201.7 6242.0 6326.2 4600.7 4727.2 4747.4 4752.9 4765.2 4642.4 4643.1 4674.4 4757.6 4780.4 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#4] prototype.html Cycles:180 Average:4131.85 Median:4752.01 stddev:1194.87 (25.1%) stddev-sans-first:1163.12 14:00:25 INFO - PROCESS | 3990 | Values: 301.4 303.6 307.1 308.1 311.0 4771.7 4774.1 4781.2 4831.6 4849.6 4738.7 4752.6 4770.3 4780.5 4784.6 3569.1 3658.0 3691.2 3756.9 3813.5 3721.9 3744.6 3749.1 3788.4 3804.0 3535.7 3707.5 3719.1 3727.0 3729.4 3597.5 3706.6 3708.9 3710.8 3721.8 3256.8 3264.2 3267.0 3268.6 3275.2 4522.2 4526.4 4529.0 4570.6 4589.2 3962.9 4227.0 4233.5 4257.2 4279.1 4975.0 5000.5 5003.2 5011.6 5017.5 3464.3 3501.2 3503.7 3517.8 3518.8 5092.9 5199.1 5211.9 5224.8 5260.2 3619.8 3637.9 3641.3 3661.7 3664.7 3684.8 3706.4 3709.2 3721.2 3741.6 4240.6 4248.3 4266.9 4288.0 4288.3 4859.5 4937.4 4966.7 5003.9 5033.8 1261.4 1276.8 1288.5 1289.8 1292.1 802.4 811.0 838.7 858.8 863.3 4397.0 4409.3 4420.7 4463.4 4471.2 5072.1 5082.5 5100.3 5121.8 5137.2 4328.4 4368.4 4383.3 4390.7 4408.8 2721.7 2722.4 2744.7 2747.0 2747.5 4767.5 4774.6 4791.7 4807.1 4808.8 5079.5 5195.4 5203.7 5210.7 5221.6 4713.8 4741.9 4743.2 4759.9 4770.9 5096.6 5098.5 5119.6 5119.8 5129.4 4789.8 4989.9 5017.5 5031.8 5032.0 4751.5 4754.8 4773.3 4776.6 4786.9 3602.9 3606.3 3622.0 3623.3 3631.5 4843.9 5079.0 5111.3 5115.3 5151.2 4933.5 4954.7 4960.0 4970.0 4974.4 4922.7 4936.9 4948.2 4964.2 4997.1 4638.9 4909.2 4912.8 4928.3 4966.5 4839.5 4951.9 5054.6 5062.7 5091.3 4929.9 4950.9 4956.2 4962.1 4969.7 14:00:25 INFO - PROCESS | 3990 | 14:00:25 INFO - PROCESS | 3990 | [#5] yui.html Cycles:180 Average:13038.31 Median:45158.55 stddev:14989.41 (33.2%) stddev-sans-first:15002.50 14:00:25 INFO - PROCESS | 3990 | Values: 630.7 657.9 665.9 722.8 763.2 11096.5 11310.4 11839.0 11933.5 12007.3 11911.9 11954.5 12032.3 12040.6 12055.1 634.1 650.6 757.5 774.6 776.1 762.5 763.2 774.8 795.4 795.6 831.8 836.3 837.3 837.6 837.7 660.0 804.0 825.1 833.3 834.9 599.2 599.5 599.8 600.8 601.4 11919.5 11938.3 11970.9 12006.9 12012.5 11908.7 12106.5 12381.3 12386.4 12542.4 10983.6 10988.0 11000.8 11236.5 11350.3 4089.4 4115.2 4125.5 4167.8 4229.5 45521.5 45548.5 45671.8 45802.3 46012.0 9474.4 9871.0 9898.8 9948.4 10041.5 17332.0 17353.8 17389.8 17394.8 17417.8 6540.5 6586.9 6647.0 6694.3 6743.8 3550.6 3567.8 3574.8 3649.8 3663.8 2651.8 2714.6 2720.8 2729.3 2730.2 518.8 524.2 535.8 539.3 541.2 525.7 630.2 651.5 653.9 670.1 8122.3 8463.8 8483.0 8496.6 8570.5 555.9 568.7 573.9 577.0 604.7 3799.9 3930.3 3976.3 4013.3 4096.4 53073.5 54230.7 54490.2 55443.0 55524.7 47983.3 48331.3 49210.8 49341.3 49499.0 44628.6 45003.8 45313.3 45539.3 45958.0 7802.5 7912.3 7940.0 8110.9 8155.8 30568.8 30742.4 30852.5 31257.4 32041.5 38336.4 38379.6 39852.8 40309.6 40349.2 10227.6 10317.8 10450.5 10587.2 10727.4 10247.6 10450.6 10467.1 10890.0 11121.3 8062.4 10597.3 11197.5 11250.7 11414.7 11576.4 11687.7 11898.9 11960.9 12185.8 11260.3 11554.5 11724.9 11776.0 11842.2 9115.4 9297.6 9396.8 9544.1 9607.5 9743.6 10153.2 10171.2 10215.5 10218.6 14:00:25 INFO - PROCESS | 3990 | -------- Summary: end -------- 14:00:25 INFO - PROCESS | 3990 | 14:00:26 INFO - TEST-INFO | 3990: exit 0 14:00:26 INFO - TEST-OK | dromaeo_css | took 1120686ms 14:00:26 INFO - TEST-START | kraken 14:00:26 INFO - operating with platform_type : linux_ 14:00:26 INFO - Initialising browser for kraken test... 14:00:26 INFO - TEST-INFO | started process 4167 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQAltKf/profile http://localhost:48090/getInfo.html) 14:00:31 INFO - PROCESS | 4167 | __metrics Screen width/height:1600/1200 14:00:31 INFO - PROCESS | 4167 | colorDepth:24 14:00:31 INFO - PROCESS | 4167 | Browser inner width/height: 1024/697 14:00:31 INFO - PROCESS | 4167 | __metrics 14:00:32 ERROR - PROCESS | 4167 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:00:33 INFO - TEST-INFO | 4167: exit 0 14:00:33 INFO - Browser initialized. 14:00:33 INFO - Running cycle 1/1 for kraken test... 14:00:33 INFO - TEST-INFO | started process 4248 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQAltKf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:00:45 INFO - PROCESS | 4248 | RSS: Main: 157384704 14:00:45 INFO - PROCESS | 4248 | 14:00:47 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48090/tests/kraken/driver.html?testName=audio-beat-detection) 14:00:48 INFO - PROCESS | 4248 | RSS: Main: 214892544 14:00:48 INFO - PROCESS | 4248 | 14:00:51 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48090/tests/kraken/driver.html?testName=audio-dft) 14:00:52 INFO - PROCESS | 4248 | RSS: Main: 203694080 14:00:52 INFO - PROCESS | 4248 | 14:00:55 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48090/tests/kraken/driver.html?testName=audio-fft) 14:00:56 INFO - PROCESS | 4248 | RSS: Main: 270245888 14:00:56 INFO - PROCESS | 4248 | 14:00:58 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48090/tests/kraken/driver.html?testName=audio-oscillator) 14:00:58 INFO - PROCESS | 4248 | RSS: Main: 181059584 14:00:58 INFO - PROCESS | 4248 | 14:01:01 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48090/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:01:01 INFO - PROCESS | 4248 | RSS: Main: 184553472 14:01:01 INFO - PROCESS | 4248 | 14:01:05 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48090/tests/kraken/driver.html?testName=imaging-darkroom) 14:01:05 INFO - PROCESS | 4248 | RSS: Main: 220110848 14:01:05 INFO - PROCESS | 4248 | 14:01:09 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48090/tests/kraken/driver.html?testName=imaging-desaturate) 14:01:09 INFO - PROCESS | 4248 | RSS: Main: 221126656 14:01:09 INFO - PROCESS | 4248 | 14:01:12 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48090/tests/kraken/driver.html?testName=json-parse-financial) 14:01:13 INFO - PROCESS | 4248 | RSS: Main: 221171712 14:01:13 INFO - PROCESS | 4248 | 14:01:14 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48090/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:01:15 INFO - PROCESS | 4248 | RSS: Main: 206000128 14:01:15 INFO - PROCESS | 4248 | 14:01:17 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:01:18 INFO - PROCESS | 4248 | RSS: Main: 208592896 14:01:18 INFO - PROCESS | 4248 | 14:01:20 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:01:20 INFO - PROCESS | 4248 | RSS: Main: 215949312 14:01:20 INFO - PROCESS | 4248 | 14:01:23 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:01:24 INFO - PROCESS | 4248 | RSS: Main: 222887936 14:01:24 INFO - PROCESS | 4248 | 14:01:27 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:01:27 INFO - PROCESS | 4248 | RSS: Main: 223281152 14:01:27 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | Cycle 1(1): loaded http://localhost:48090/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48090/tests/kraken/driver.html?testName=ai-astar) 14:01:29 INFO - PROCESS | 4248 | RSS: Main: 266313728 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | __start_tp_report 14:01:29 INFO - PROCESS | 4248 | _x_x_mozilla_page_load 14:01:29 INFO - PROCESS | 4248 | _x_x_mozilla_page_load_details 14:01:29 INFO - PROCESS | 4248 | |i|pagename|runs| 14:01:29 INFO - PROCESS | 4248 | |0;ai-astar;114;108;113;113;112;113;112;111;111;112 14:01:29 INFO - PROCESS | 4248 | |1;audio-beat-detection;172;138;136;179;132;131;154;129;187;133 14:01:29 INFO - PROCESS | 4248 | |2;audio-dft;161;162;160;161;161;162;161;162;163;160 14:01:29 INFO - PROCESS | 4248 | |3;audio-fft;75;77;76;105;77;76;75;75;98;75 14:01:29 INFO - PROCESS | 4248 | |4;audio-oscillator;84;84;126;84;85;86;124;84;84;118 14:01:29 INFO - PROCESS | 4248 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;118 14:01:29 INFO - PROCESS | 4248 | |6;imaging-darkroom;146;146;147;148;145;145;146;149;145;146 14:01:29 INFO - PROCESS | 4248 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 14:01:29 INFO - PROCESS | 4248 | |8;json-parse-financial;63;62;63;63;63;65;63;62;62;62 14:01:29 INFO - PROCESS | 4248 | |9;json-stringify-tinderbox;65;65;66;65;65;65;65;66;67;66 14:01:29 INFO - PROCESS | 4248 | |10;stanford-crypto-aes;83;82;83;83;85;86;86;85;119;83 14:01:29 INFO - PROCESS | 4248 | |11;stanford-crypto-ccm;138;142;143;127;131;134;140;134;134;133 14:01:29 INFO - PROCESS | 4248 | |12;stanford-crypto-pbkdf2;170;171;173;168;168;168;166;175;171;172 14:01:29 INFO - PROCESS | 4248 | |13;stanford-crypto-sha256-iterative;67;70;68;68;67;72;67;66;67;68 14:01:29 INFO - PROCESS | 4248 | __end_tp_report 14:01:29 INFO - PROCESS | 4248 | __start_cc_report 14:01:29 INFO - PROCESS | 4248 | _x_x_mozilla_cycle_collect,1905 14:01:29 INFO - PROCESS | 4248 | __end_cc_report 14:01:29 INFO - PROCESS | 4248 | __startTimestamp1464728489835__endTimestamp 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | ------- Summary: start ------- 14:01:29 INFO - PROCESS | 4248 | Number of tests: 14 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:1.66 (1.5%) stddev-sans-first:1.58 14:01:29 INFO - PROCESS | 4248 | Values: 114.0 108.0 113.0 113.0 112.0 113.0 112.0 111.0 111.0 112.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:146.00 stddev:22.25 (15.2%) stddev-sans-first:22.01 14:01:29 INFO - PROCESS | 4248 | Values: 172.0 138.0 136.0 179.0 132.0 131.0 154.0 129.0 187.0 133.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 14:01:29 INFO - PROCESS | 4248 | Values: 161.0 162.0 160.0 161.0 161.0 162.0 161.0 162.0 163.0 160.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:11.01 (14.5%) stddev-sans-first:11.47 14:01:29 INFO - PROCESS | 4248 | Values: 75.0 77.0 76.0 105.0 77.0 76.0 75.0 75.0 98.0 75.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:84.00 stddev:18.59 (22.1%) stddev-sans-first:19.21 14:01:29 INFO - PROCESS | 4248 | Values: 84.0 84.0 126.0 84.0 85.0 86.0 124.0 84.0 84.0 118.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:01:29 INFO - PROCESS | 4248 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 14:01:29 INFO - PROCESS | 4248 | Values: 146.0 146.0 147.0 148.0 145.0 145.0 146.0 149.0 145.0 146.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:01:29 INFO - PROCESS | 4248 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 14:01:29 INFO - PROCESS | 4248 | Values: 63.0 62.0 63.0 63.0 63.0 65.0 63.0 62.0 62.0 62.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 14:01:29 INFO - PROCESS | 4248 | Values: 65.0 65.0 66.0 65.0 65.0 65.0 65.0 66.0 67.0 66.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:84.00 stddev:11.16 (13.3%) stddev-sans-first:11.72 14:01:29 INFO - PROCESS | 4248 | Values: 83.0 82.0 83.0 83.0 85.0 86.0 86.0 85.0 119.0 83.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:136.00 stddev:5.06 (3.7%) stddev-sans-first:5.29 14:01:29 INFO - PROCESS | 4248 | Values: 138.0 142.0 143.0 127.0 131.0 134.0 140.0 134.0 134.0 133.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:171.00 stddev:2.74 (1.6%) stddev-sans-first:2.91 14:01:29 INFO - PROCESS | 4248 | Values: 170.0 171.0 173.0 168.0 168.0 168.0 166.0 175.0 171.0 172.0 14:01:29 INFO - PROCESS | 4248 | 14:01:29 INFO - PROCESS | 4248 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.76 (2.6%) stddev-sans-first:1.83 14:01:29 INFO - PROCESS | 4248 | Values: 67.0 70.0 68.0 68.0 67.0 72.0 67.0 66.0 67.0 68.0 14:01:29 INFO - PROCESS | 4248 | -------- Summary: end -------- 14:01:29 INFO - PROCESS | 4248 | 14:01:30 INFO - TEST-INFO | 4248: exit 0 14:01:30 INFO - TEST-OK | kraken | took 64602ms 14:01:30 INFO - SUITE-END | took 1185s 14:01:30 INFO - Completed test suite (00:19:45) 14:01:30 INFO - javascript benchmark 14:01:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.7333266673327, 2675.47828173506, 2696.555068413712, 2703.8918443262264, 2707.878145483453, 4549.636029117671, 4613.077384523101, 4639.536046395358, 4711.481737008926, 4763.761811909404, 4654.8138074477065, 4660.231061874787, 4663.207254766689, 4693.671442998992, 4706.0, 4075.714699971003, 4221.345691417835, 4300.935485967713, 4329.220740266751, 4438.001449673814, 4341.196878577473, 4375.540568240337, 4392.209402307583, 4403.889902752425, 4429.7785110744335, 4166.500019997605, 4205.831766729327, 4259.893502662428, 4283.0363168287195, 4285.228658841407, 4239.130978149472, 4265.914681706348, 4278.101598664284, 4317.676174286941, 4333.133373325316, 4289.6139347458875, 4305.655547556219, 4315.956840431619, 4338.132373525307, 4356.019895523514, 4492.213862574036, 4538.818447262105, 4579.0, 4602.470715867685, 4606.5393460654, 4993.176125939216, 5022.020705962339, 5057.241413787924, 5100.795968161269, 5106.157484015133, 5116.539511443988, 5119.360079990001, 5144.228365745131, 5148.279240906276, 5153.742312884341, 4647.093816705744, 4683.7892294846815, 4719.032598317337, 4720.197566413718, 4781.354517140211, 5216.869578260498, 5223.4776522347465, 5229.6339256251695, 5231.503007214308, 5232.947670523322, 4854.5873600744335, 4978.626603004716, 5021.372328458942, 5026.095302845523, 5026.874328141753, 5005.27423523587, 5058.671186372948, 5063.3417655704525, 5079.466656001141, 5089.592832573459, 5040.218766091261, 5045.2432135180015, 5050.469700681376, 5052.191649336162, 5105.081085404663, 4895.339129217581, 4942.481039490874, 5027.773750181251, 5040.899182016339, 5041.949580504148, 4317.784110794448, 4337.067530480962, 4340.848070317524, 4366.716163449366, 4384.276594361894, 3765.1151979285396, 3785.5835858055593, 3793.6206379361843, 3832.693384529231, 3838.1939792643857, 4243.618074373321, 4278.358246263086, 4298.978505107517, 4335.3713711511655, 4343.761093139845, 4993.500649934977, 5022.648414610942, 5031.7987280509105, 5043.9747801260755, 5086.491350864958, 4330.718503297276, 4332.978335108304, 4335.566443355639, 4371.912561748811, 4392.165488557164, 4459.152760975404, 4497.033137875373, 4527.1172121436, 4565.360849481075, 4580.862574122782, 4656.9301460477445, 4682.2508398656055, 4688.531146885421, 4738.3840100787365, 4738.691985020962, 4985.825496107619, 4986.12742770021, 4995.275685025724, 4999.275105109811, 5037.269595908646, 4591.4949355571525, 4635.884102897455, 4651.86044418668, 4671.976640116778, 4677.883052923704, 4938.407391113089, 4963.950360496348, 4979.103761322996, 4979.477154898684, 5019.0, 4993.500649934977, 5008.549230569266, 5011.824586139468, 5013.774380152975, 5080.1363768160245, 4811.446683631287, 4857.441394239708, 4896.975515122402, 4907.77914993819, 4955.702657840541, 4886.193778026586, 4902.534259245366, 4910.467048181559, 4916.237983112624, 4960.578350840075, 4815.422149342101, 4832.154372984784, 4834.830780922651, 4862.854114376574, 4883.780229889592, 4820.638452116035, 4896.338994235733, 4945.035718035093, 4962.602991760578, 4984.925226121683, 4845.49122342149, 4877.587673693143, 4882.974575339219, 4887.3890763654545, 4910.877228069184], "value": 4620.7175396305665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.393764165026, 582.7872826418358, 591.0926727473332, 594.1978329255503, 596.6449962272449, 27802.30494237645, 40519.0, 41586.37620435696, 41599.37600935988, 41626.791866040665, 41976.0, 42430.15139697204, 42517.0, 42582.78708606461, 42602.360964585554, 7396.852062958737, 7694.576798276106, 7885.1326354101, 7887.526748395086, 9961.800763984716, 9696.612135514571, 10042.146417554517, 10045.447500387476, 10048.547815348327, 10049.547770350315, 10036.0, 10112.59549618018, 10122.0, 10126.886042535316, 10128.746781330452, 7608.35328997036, 9895.802083958353, 9896.455694936776, 10184.236182286359, 10190.89809101907, 10012.499375031291, 10105.39367637944, 10114.494275286279, 10128.54421551032, 10130.392176469435, 38326.61673383258, 38389.61610383888, 38805.80597097025, 38850.222995540214, 38854.805725971186, 3020.879164833404, 3063.1270088024808, 3078.6459557150993, 3126.8436578171227, 3214.1321843102464, 29416.11751647454, 29755.851220743756, 29855.552166717516, 30127.548086778716, 30247.0, 3253.723433508155, 3294.374068926896, 3298.323843612078, 3314.66853314669, 3384.5092461593176, 262821.9999999981, 263461.0, 263753.0, 263808.0, 264699.0, 52658.0, 52938.47061529412, 53075.73462132664, 53080.734596327544, 53084.469155307954, 33103.83448082744, 34340.31319373648, 56549.0, 56596.43403565994, 56737.0, 13339.66650833737, 13414.530491432948, 13432.261225632488, 13522.850557702706, 13523.39144738489, 9653.17947974416, 9899.80200395988, 9940.502974851373, 9960.0, 10161.390316581028, 18424.263029478938, 18437.53906152357, 18493.260269589067, 18514.351997680016, 18528.258869645062, 6877.65611719422, 6908.9309106909295, 6953.374196322356, 7065.92934070663, 7149.499535032498, 784.8351846112266, 880.9735707928772, 921.5530467723149, 929.3958926697566, 994.5077186792583, 27569.310767230978, 28601.85699071533, 28637.71362286392, 28670.426591468055, 29190.416191676046, 947.5641205045619, 951.543259235557, 1012.888582255966, 1017.9389236645825, 1085.9348439093521, 14486.203258820866, 14608.780868286984, 14979.70040599182, 15073.0, 15190.696186076217, 401424.0, 401599.0, 402888.0, 403927.0, 406307.0, 50809.49190508122, 51222.23166652428, 51242.23136652953, 51245.0, 51288.23067653988, 1087.206339372247, 1125.431657013203, 1156.4102307822902, 1205.8492688413949, 1244.3467179730715, 26297.868510657325, 29039.56440653434, 29642.258943526584, 29777.85111074431, 29986.25034374158, 42593.36109958415, 42907.570924290354, 43038.56961430346, 43065.0, 43127.35308970431, 292832.0, 294057.0, 294368.0, 294512.0, 295024.0, 22323.553528929657, 24832.0, 25693.229203123938, 26603.33491662724, 26859.597106043817, 42211.15577688368, 42424.0, 42462.0, 43011.784941076345, 43130.56869431266, 36120.638793611724, 36335.81832090928, 36514.0, 36876.631233687316, 37140.628593713715, 35707.0, 36555.81722091373, 36843.447348290334, 36877.26245475022, 37136.442953356265, 35349.469757953135, 35539.289214216085, 35637.287254254254, 36086.63913360938, 36384.0, 31966.52050219295, 32268.555466879676, 32496.0, 32498.350032999682, 32749.67250327466, 24623.138190163976, 24735.50528989446, 24784.628230576196, 25353.492930141663, 25401.87299063493], "value": 17971.30747920125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.3369301564169, 367.88227767114523, 387.83904679557986, 390.78897395406483, 395.1780296982277, 15734.921325393372, 16176.757348639765, 16992.915035424838, 17002.744958825595, 17027.659446811056, 11894.52421903125, 15571.922140389283, 16244.75632865508, 16289.511314660549, 17041.82958170418, 4623.398958135438, 4715.339852420664, 4765.046990601876, 4794.592459640935, 4803.543663351993, 3515.5605549306338, 5160.096983027974, 5422.945770542303, 5436.891262174754, 5452.263944367499, 5424.0, 5425.050616142179, 5431.565474762009, 5438.83683489494, 5450.345958484987, 5092.618053645972, 5415.566754659618, 5423.918641220384, 5486.396496385394, 5489.3412790465, 3397.3545026444895, 4893.632977526664, 5249.527542521191, 5249.658772179834, 5300.0, 11306.717332066683, 11704.590339338083, 12310.199837010565, 12471.93764031174, 12530.248185108923, 1852.2128095559333, 1904.209752952523, 1939.8933058681766, 1968.3602829080462, 2433.7322894481595, 8845.734627961172, 8880.600372983232, 9187.81624367509, 9360.204382627484, 9426.481543515103, 1661.551381127089, 1735.8350956659096, 1738.75657407963, 1768.4252617899235, 1787.436957358428, 66423.66788166076, 66974.0, 67194.3280567193, 67717.96846094626, 68098.31901680969, 9867.753306167404, 10264.230182736175, 10265.435401053013, 10265.897341026493, 10391.740206494747, 14216.502422415166, 14247.715045699235, 14312.355943982546, 14323.57029289123, 14335.354909029118, 7412.1476030256135, 7504.174540800616, 7669.233076692398, 7760.883586746203, 7785.688572457151, 7198.100237470317, 7315.122185337687, 7318.853622927618, 7402.7409040683315, 7481.401487880957, 4339.696221264481, 4421.5578442156175, 4475.686701930898, 4510.436195475566, 4547.295169248771, 1635.2069246415479, 1783.2153852304943, 2913.927151821179, 2990.7158819912074, 3051.755859531232, 782.3350152370554, 823.4524041512447, 939.1829108675496, 969.0890562870878, 990.6829814459304, 16175.757363639555, 16707.164641767864, 17021.574460638334, 17190.968541887527, 17333.47999560015, 844.0799528513827, 858.4891989266376, 879.9428037177563, 912.82656295305, 916.2898753465984, 9426.62293508252, 9497.952510237543, 9881.789615442196, 9982.0, 10091.142252908585, 368982.0, 381695.0, 382985.0, 383880.0, 384186.0, 68038.65980670066, 78525.21474785294, 78940.21059789443, 79072.20927790763, 79194.0, 897.4301318662697, 902.891653001644, 921.953902304882, 922.9261659067258, 926.5274709897866, 14970.47603333878, 15141.167235802137, 15181.16503592313, 15390.461333853262, 15430.305636246172, 33501.497477538345, 34137.31725365528, 34460.31079378448, 35235.0, 35980.64019359773, 44027.77986110049, 46062.539374607164, 48078.27882581834, 48214.0, 48272.275915861996, 7773.3004029637605, 7839.451238413257, 7844.960775196316, 7942.205779422243, 8052.033755949089, 10956.616518421817, 11788.941055294668, 11850.348230847041, 11859.288442693465, 12039.63881083569, 10111.292209545261, 10144.087032167141, 10187.337823041478, 10405.58377664885, 10494.842577361193, 10079.748006299902, 10128.797424051625, 10168.745781355525, 10382.3251488653, 10442.373457592264, 10316.27786054969, 10397.740056498647, 10464.529096190841, 10490.265681402228, 10652.520636571528, 7724.227577242231, 7763.844723105393, 7801.843963120819, 7818.804529886798, 7976.122626511195, 7834.4907581007055, 10217.438040907822, 10320.12278956297, 10492.422916739653, 10589.735256618338], "value": 8107.175245301479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1244.5955064604007, 1377.3595278195637, 1422.815034045574, 1506.8041154649893, 1747.6592064547403, 6837.521373503851, 7678.347340476066, 7885.487443316195, 7892.921070789291, 8022.518648881071, 7773.416993725465, 7925.167857374979, 7926.9207307927045, 7940.801479963004, 7941.7617471475805, 2169.4467910682793, 2192.967105493419, 2195.736511618608, 2195.9890200548975, 2200.2409168836725, 2182.8035476807086, 2236.284388995522, 2238.6865838782583, 2240.6863039174527, 2241.932742017742, 2183.465051062492, 2189.5292512109895, 2191.2659259148204, 2195.297504798465, 2238.3620668109593, 2164.2050823014633, 2181.0839447432118, 2186.431527802767, 2189.1681161158817, 2190.0144934779332, 2162.8269738421045, 2166.6100101981638, 2169.739631244245, 2173.0873033325884, 2173.445771328317, 7710.57591832449, 7765.1070126935565, 7871.055473343178, 7872.370210383156, 7989.0, 4642.210824159901, 4779.187538118528, 4779.545943135397, 4780.282957556359, 4782.163121453732, 2770.321271288547, 2906.157214407819, 2945.4109178164454, 2947.688278715972, 2967.7329040386467, 913.6665117232229, 921.4977837078741, 929.168394287116, 982.921366290695, 1018.2159737002466, 10600.098991585724, 10667.253292269545, 10674.466276686135, 10703.839442408447, 10708.1433485321, 3970.861019864291, 4058.066644671683, 4116.526599441103, 4118.0, 4139.19285739278, 8424.873626895602, 8478.109798471196, 8485.0666426693, 8523.275521580736, 8527.0, 4573.702709323883, 4633.4208223972, 4634.21218392874, 4636.374089497942, 4636.675432719745, 3779.3952967525065, 3892.902677433087, 4002.4996875390575, 4256.446661933929, 4300.569943005675, 3310.519974603717, 3405.7105146062368, 3423.281110966725, 3448.6551344865816, 3456.948145777815, 1008.4151192308443, 1030.7835354575473, 1031.0668844695563, 1034.829253173218, 1035.1563475767225, 669.2003056347618, 716.6560051175361, 751.7143485475484, 765.6669348833275, 792.5918152151646, 5322.494363035506, 5348.304720386325, 5457.672539647634, 5463.863403414867, 5534.972325138348, 391.5810083210937, 396.6985091330552, 400.0658462490118, 404.6095517825313, 405.3635791806894, 3672.485851980725, 3885.68914486846, 3890.6498415142205, 3921.1961547882615, 3942.6254505821903, 9514.33399662031, 9842.11420972116, 9853.06395892389, 9918.0, 9923.057309555581, 9853.113219810108, 9855.753606159904, 9863.506824658738, 9869.161121304625, 9880.802383952281, 7368.415800581731, 7471.0, 7640.579768112696, 7644.655990480442, 7659.387249020065, 5436.9456305438025, 5443.510084092451, 5482.451754824486, 5566.666000040011, 5584.218209450762, 8993.820123597623, 9086.318526110701, 9097.909020909707, 9146.222571081533, 9183.265338773015, 9186.954065229631, 9389.014153513783, 9406.200472959876, 9411.905880941104, 9427.104425079606, 5815.709214539341, 6257.906131408124, 6400.743970241138, 6408.743650254124, 6413.77551785704, 7435.739749108755, 7453.739119130804, 7478.775636730907, 7484.139323977594, 7503.174650788518, 6729.461643068445, 6885.139357580302, 6963.1644202696, 6969.686364113526, 7036.648167591702, 6781.355771201728, 6847.281035491202, 6867.484938629523, 6955.269696681776, 6966.0, 6033.064874944391, 6195.101710252078, 6201.658908759881, 6241.96879015602, 6326.2092238470195, 4600.7469589172915, 4727.220008698664, 4747.382840230748, 4752.857414277577, 4765.166095933128, 4642.37327960721, 4643.094596553639, 4674.392328997209, 4757.595604373529, 4780.378550788375], "value": 4049.2862513755754, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.434809731753, 303.5931851319232, 307.1139761787244, 308.0895952460478, 310.97667674924384, 4771.713697178167, 4774.069056533973, 4781.211100168468, 4831.637627177963, 4849.612031037517, 4738.668293219476, 4752.572268495835, 4770.332153498513, 4780.4741478437345, 4784.5933095686905, 3569.054200636827, 3657.963420365789, 3691.1913162550427, 3756.9436458453147, 3813.466114743938, 3721.9441708374397, 3744.64425879541, 3749.1189570450924, 3788.4317352397084, 3804.0, 3535.7171426285963, 3707.536557930259, 3719.0516418313427, 3727.0, 3729.403295472725, 3597.4963505109176, 3706.610805865372, 3708.9258214835822, 3710.7588006779606, 3721.832517536717, 3256.8208748518823, 3264.183954011497, 3267.0198940318046, 3268.5750852389037, 3275.1812046988252, 4522.23122069249, 4526.411566496335, 4528.9547104528865, 4570.611498022672, 4589.151007063704, 3962.8811135665687, 4226.978865105685, 4233.534311225762, 4257.169851878885, 4279.144171165748, 4975.0, 5000.524950129732, 5003.249512573107, 5011.6241281904, 5017.473165317626, 3464.272502774395, 3501.212227248874, 3503.684668379858, 3517.788932664023, 3518.8240587970754, 5092.872678183038, 5199.14214154664, 5211.869703257411, 5224.791008359661, 5260.184671375943, 3619.76471529355, 3637.9449959511444, 3641.253543023674, 3661.7436779425716, 3664.7434679572707, 3684.8341824617955, 3706.4440333950124, 3709.221063576625, 3721.2371463850386, 3741.607131251234, 4240.618344349024, 4248.256555102845, 4266.914661706748, 4287.97856010718, 4288.270993931039, 4859.538343857328, 4937.432195297587, 4966.701997880139, 5003.874903127451, 5033.7734801934, 1261.4323554400557, 1276.8020956751718, 1288.5361269942814, 1289.7807372746656, 1292.1407264169222, 802.4102284820652, 810.9984169550601, 838.7209505504155, 858.7505179963264, 863.3438586674173, 4396.988692600656, 4409.294512877924, 4420.734755914656, 4463.397441345442, 4471.1728330259, 5072.097963263776, 5082.466341034138, 5100.285959965608, 5121.7695203715175, 5137.178051511815, 4328.398492557711, 4368.432103826546, 4383.254846676073, 4390.736555806662, 4408.7575183365225, 2721.71422000691, 2722.4282900591097, 2744.67063952327, 2746.9725302747115, 2747.4505098980285, 4767.499412561631, 4774.618030557547, 4791.6645834791925, 4807.134715751128, 4808.831690890802, 5079.5428411443145, 5195.402528709171, 5203.687778733288, 5210.739463026834, 5221.642372983052, 4713.81144754206, 4741.881452963635, 4743.217369134054, 4759.881427864519, 4770.856874293777, 5096.566791822736, 5098.490150984871, 5119.6416250862085, 5119.769610367468, 5129.358380434005, 4789.784459699252, 4989.8752531185555, 5017.523335283142, 5031.84904452867, 5032.0, 4751.453582838018, 4754.786034628381, 4773.307870358848, 4776.593989510791, 4786.928196076992, 3602.8919132426067, 3606.260716553048, 3622.0039489139645, 3623.3115708015393, 3631.473436351767, 4843.903121937471, 5078.97460512695, 5111.309973153577, 5115.25828754821, 5151.175811870083, 4933.5066493351815, 4954.696066329174, 4959.975200123977, 4969.975150124226, 4974.427940786856, 4922.72924989129, 4936.9259461107395, 4948.233023881304, 4964.230544265644, 4997.075541024922, 4638.8608341749805, 4909.1899836528, 4912.778924948315, 4928.2853986171085, 4966.478519755518, 4839.540243676845, 4951.851444456672, 5054.620903432184, 5062.721550314768, 5091.261767043684, 4929.852104436873, 4950.8762280943265, 4956.157453232887, 4962.082014827269, 4969.6769709970185], "value": 3754.7924533410683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.7319389259561, 657.9374959378857, 665.8967859981705, 722.8482018776058, 763.248200522485, 11096.500657470413, 11310.434478276084, 11839.0, 11933.522659093636, 12007.3395963222, 11911.880881191186, 11954.521819127247, 12032.33822139782, 12040.638780836589, 12055.095867809905, 634.1154090044386, 650.5803756577005, 757.515190278221, 774.5739843086308, 776.1074764021392, 762.5462849604477, 763.2215140556629, 774.8334108166744, 795.3954994204418, 795.645937557787, 831.8128421105264, 836.3016880904422, 837.3175861672718, 837.6105111123327, 837.6984285657189, 660.0330515794384, 803.9758807235793, 825.1294883897485, 833.3041910005122, 834.9206825351582, 599.2060519811267, 599.4544964082664, 599.7810799058312, 600.7747094839435, 601.3685630088389, 11919.463624136935, 11938.343391113485, 11970.940145299304, 12006.939965300206, 12012.519499220021, 11908.702282443008, 12106.455209515592, 12381.319027453486, 12386.442610082568, 12542.435590398454, 10983.61557345497, 10988.0, 11000.779984400267, 11236.49435775384, 11350.262232954832, 4089.407035979796, 4115.218108559364, 4125.484314460692, 4167.791610419466, 4229.471316085489, 45521.54478455206, 45548.544514554764, 45671.77164114192, 45802.312965305544, 46012.0, 9474.431534107976, 9870.950645246729, 9898.80202395948, 9948.403095814274, 10041.548130334153, 17332.0, 17353.826461735473, 17389.826101739072, 17394.826051739572, 17417.825821741873, 6540.509461790385, 6586.901196482057, 6647.0, 6694.297098804653, 6743.763968261087, 3550.5739311282296, 3567.7859328440377, 3574.7855128692363, 3649.7810131392202, 3663.798491083016, 2651.7878569714376, 2714.6335244742104, 2720.782337413002, 2729.344957210295, 2730.2218867622632, 518.7561845932406, 524.2372348233388, 535.8258565966077, 539.2962184349461, 541.1990254423475, 525.6740820691119, 630.1996464490072, 651.491836367634, 653.9280679125293, 670.11210146556, 8122.268995790408, 8463.788405289793, 8482.957585212034, 8496.617652205665, 8570.528620925785, 555.9249501317352, 568.7270110347057, 573.8594044459135, 576.9989068965342, 604.6885853785304, 3799.9413405183177, 3930.331843586597, 3976.264391087658, 4013.337799263147, 4096.446979657768, 53073.46926530763, 54230.728846355516, 54490.18264726032, 55443.0, 55524.72237638786, 47983.28025079626, 48331.27503087457, 49210.75394623004, 49341.25988110181, 49499.0, 44628.55371446309, 45003.77498112554, 45313.320300194864, 45539.31691024637, 45958.0, 7802.453828231969, 7912.28789408956, 7939.960300198462, 8110.878336825071, 8155.755327340189, 30568.84715576408, 30742.385152297276, 30852.53721194139, 31257.433182222954, 32041.51937720983, 38336.42495362516, 38379.616203837606, 39852.80073599729, 40309.596904030586, 40349.1930161401, 10227.59089636436, 10317.793644127225, 10450.477476126316, 10587.20595955291, 10727.356358618508, 10247.590096396061, 10450.58197672115, 10467.110295624652, 10890.0, 11121.332720036824, 8062.3888411502, 10597.25819192649, 11197.496112674786, 11250.718732031764, 11414.657560273205, 11576.421178940851, 11687.707807304885, 11898.940505297418, 11960.880391195977, 12185.756284874431, 11260.324380537195, 11554.537818487166, 11724.941375293069, 11776.0, 11842.230255033395, 9115.361924665474, 9297.628094876129, 9396.775430714497, 9544.141027307576, 9607.471589062668, 9743.625387242877, 10153.187744980532, 10171.219017451804, 10215.537600994407, 10218.642347517802], "value": 5969.210956342245, "unit": "score"}], "name": "dromaeo_css", "value": 6275.986930445348, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 113.0, 113.0, 112.0, 113.0, 112.0, 111.0, 111.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 138.0, 136.0, 179.0, 132.0, 131.0, 154.0, 129.0, 187.0, 133.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 161.0, 162.0, 161.0, 162.0, 163.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 105.0, 77.0, 76.0, 75.0, 75.0, 98.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 126.0, 84.0, 85.0, 86.0, 124.0, 84.0, 84.0, 118.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 148.0, 145.0, 145.0, 146.0, 149.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 63.0, 65.0, 63.0, 62.0, 62.0, 62.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 66.0, 67.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 85.0, 86.0, 86.0, 85.0, 119.0, 83.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 143.0, 127.0, 131.0, 134.0, 140.0, 134.0, 134.0, 133.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 173.0, 168.0, 168.0, 168.0, 166.0, 175.0, 171.0, 172.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 68.0, 67.0, 72.0, 67.0, 66.0, 67.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1547.2, "alertThreshold": 2.0}]} 14:01:31 INFO - Return code: 0 14:01:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:01:31 ERROR - # TBPL SUCCESS # 14:01:31 INFO - Running post-action listener: _resource_record_post_action 14:01:31 INFO - ##### 14:01:31 INFO - ##### Finished run-tests step (success) 14:01:31 INFO - ##### 14:01:31 INFO - Running post-run listener: _resource_record_post_run 14:01:31 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 3874816; Write bytes: 284348416; Read time: 596; Write time: 431644 14:01:31 INFO - TinderboxPrint: CPU usage
13.1% 14:01:31 INFO - TinderboxPrint: I/O read bytes / time
3,874,816 / 596 14:01:31 INFO - TinderboxPrint: I/O write bytes / time
284,348,416 / 431,644 14:01:31 INFO - TinderboxPrint: CPU idle
8,336.0 (86.9%) 14:01:31 INFO - TinderboxPrint: CPU user
1,211.4 (12.6%) 14:01:31 INFO - TinderboxPrint: Swap in / out
0 / 0 14:01:31 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1331200; Read time: 0; Write time: 72 14:01:31 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 3874816; Write bytes: 283017216; Read time: 596; Write time: 431572 14:01:31 INFO - Running post-run listener: _upload_blobber_files 14:01:31 INFO - Blob upload gear active. 14:01:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:01:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:01:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:01:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:01:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:01:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:01:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:01:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:01:36 INFO - (blobuploader) - INFO - Done attempting. 14:01:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:01:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:01:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:01:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:01:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:01:37 INFO - (blobuploader) - INFO - Done attempting. 14:01:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:01:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:01:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:01:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:01:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:01:38 INFO - (blobuploader) - INFO - Done attempting. 14:01:38 INFO - (blobuploader) - INFO - Iteration through files over. 14:01:38 INFO - Return code: 0 14:01:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:01:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:01:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6131e567dd6aaaf5a2a6c4fae01e02e004ea50b603ca04c2ebaa9d5d26bb95b94c3e432c66ea8e0699fb7342a403cc17a9e039d9c20953cf4d554740e22ef844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0a00528cff9030f4aa4e34cbba28b1a1475148161177d7c6e47123f4395ea60618006ed5ac1ae0f05d658b8c50f8d5581f7c20647c6b077c58dae6fc2d2cd1cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d07153701b00266126b7e3ec249f8a20b38cd68dcf053f8369eac6920c09dcc26036f601f5bd07df8afdc01206116b4dbe9c37d7b0fcbf08457cb24e3e6ad9d7"} 14:01:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:01:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:01:38 INFO - Contents: 14:01:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6131e567dd6aaaf5a2a6c4fae01e02e004ea50b603ca04c2ebaa9d5d26bb95b94c3e432c66ea8e0699fb7342a403cc17a9e039d9c20953cf4d554740e22ef844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0a00528cff9030f4aa4e34cbba28b1a1475148161177d7c6e47123f4395ea60618006ed5ac1ae0f05d658b8c50f8d5581f7c20647c6b077c58dae6fc2d2cd1cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d07153701b00266126b7e3ec249f8a20b38cd68dcf053f8369eac6920c09dcc26036f601f5bd07df8afdc01206116b4dbe9c37d7b0fcbf08457cb24e3e6ad9d7"} 14:01:38 INFO - Running post-run listener: copy_logs_to_upload_dir 14:01:38 INFO - Copying logs to upload dir... 14:01:38 INFO - mkdir: /builds/slave/test/build/upload/logs 14:01:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.269621 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-31 14:01:38.085757) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:01:38.089145) ========= 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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6131e567dd6aaaf5a2a6c4fae01e02e004ea50b603ca04c2ebaa9d5d26bb95b94c3e432c66ea8e0699fb7342a403cc17a9e039d9c20953cf4d554740e22ef844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0a00528cff9030f4aa4e34cbba28b1a1475148161177d7c6e47123f4395ea60618006ed5ac1ae0f05d658b8c50f8d5581f7c20647c6b077c58dae6fc2d2cd1cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d07153701b00266126b7e3ec249f8a20b38cd68dcf053f8369eac6920c09dcc26036f601f5bd07df8afdc01206116b4dbe9c37d7b0fcbf08457cb24e3e6ad9d7"} build_url:https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/Ey2lUGyUS9W2zYrM9ft9tg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6131e567dd6aaaf5a2a6c4fae01e02e004ea50b603ca04c2ebaa9d5d26bb95b94c3e432c66ea8e0699fb7342a403cc17a9e039d9c20953cf4d554740e22ef844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0a00528cff9030f4aa4e34cbba28b1a1475148161177d7c6e47123f4395ea60618006ed5ac1ae0f05d658b8c50f8d5581f7c20647c6b077c58dae6fc2d2cd1cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d07153701b00266126b7e3ec249f8a20b38cd68dcf053f8369eac6920c09dcc26036f601f5bd07df8afdc01206116b4dbe9c37d7b0fcbf08457cb24e3e6ad9d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:01:38.119791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:01:38.120133) ========= 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=af724c981e2a0f424dbabf99000001e7-1464724495.881521-1321411536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:01:38.200774) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:01:38.201131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:01:38.201454) ========= ========= Total master_lag: 0.19 =========