builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-011 starttime: 1450703317.84 results: success (0) buildid: 20151221023004 builduid: 8077f643da094a69b16fadd903d2d4c4 revision: b76ea647debcadd234066150c78b3bba77a50c74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.842707) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.843090) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.843373) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006325 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.866450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.866719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.886260) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.886536) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.040813 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.938303) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.938562) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.938903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:37.939182) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 05:08:38-- 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% 43.4M=0s 2015-12-21 05:08:38 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096825 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.048130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.048478) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.091554 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.151122) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.151449) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b76ea647debcadd234066150c78b3bba77a50c74 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b76ea647debcadd234066150c78b3bba77a50c74 --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 05:08:38,754 truncating revision to first 12 chars 2015-12-21 05:08:38,754 Setting DEBUG logging. 2015-12-21 05:08:38,754 attempt 1/10 2015-12-21 05:08:38,754 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b76ea647debc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 05:08:38,985 unpacking tar archive at: fx-team-b76ea647debc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.475833 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.638429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.638716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.895145) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.895508) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 05:08:38.895936) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-21 05:08:38.896230) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:39 INFO - MultiFileLogger online at 20151221 05:08:39 in /builds/slave/test-pgo 05:08:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:08:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:08:39 INFO - {'append_to_log': False, 05:08:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:08:39 INFO - 'blob_upload_branch': 'Fx-Team', 05:08:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:08:39 INFO - 'branch': 'Fx-Team', 05:08:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:39 INFO - 'config_files': ('talos/linux_config.py',), 05:08:39 INFO - 'default_actions': ('clobber', 05:08:39 INFO - 'read-buildbot-config', 05:08:39 INFO - 'download-and-extract', 05:08:39 INFO - 'populate-webroot', 05:08:39 INFO - 'create-virtualenv', 05:08:39 INFO - 'install', 05:08:39 INFO - 'run-tests'), 05:08:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:39 INFO - 'download_minidump_stackwalk': True, 05:08:39 INFO - 'download_symbols': 'ondemand', 05:08:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:39 INFO - 'installer_path': 'installer.exe', 05:08:39 INFO - 'log_level': 'info', 05:08:39 INFO - 'log_name': 'talos', 05:08:39 INFO - 'log_to_console': True, 05:08:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:39 INFO - 'opt_config_files': (), 05:08:39 INFO - 'pip_index': False, 05:08:39 INFO - 'sps_profile': False, 05:08:39 INFO - 'sps_profile_interval': 0, 05:08:39 INFO - 'suite': 'dromaeojs-e10s', 05:08:39 INFO - 'system_bits': '64', 05:08:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:39 INFO - 'title': 'talos-linux64-ix-011', 05:08:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:39 INFO - 'use_talos_json': True, 05:08:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:08:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:39 INFO - 'work_dir': 'build'} 05:08:39 INFO - ##### 05:08:39 INFO - ##### Running clobber step. 05:08:39 INFO - ##### 05:08:39 INFO - Running pre-action listener: _resource_record_pre_action 05:08:39 INFO - Running main action method: clobber 05:08:39 INFO - rmtree: /builds/slave/test-pgo/build 05:08:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:08:41 INFO - Running post-action listener: _resource_record_post_action 05:08:41 INFO - ##### 05:08:41 INFO - ##### Running read-buildbot-config step. 05:08:41 INFO - ##### 05:08:41 INFO - Running pre-action listener: _resource_record_pre_action 05:08:41 INFO - Running main action method: read_buildbot_config 05:08:41 INFO - Using buildbot properties: 05:08:41 INFO - { 05:08:41 INFO - "project": "", 05:08:41 INFO - "product": "firefox", 05:08:41 INFO - "script_repo_revision": "production", 05:08:41 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:08:41 INFO - "repository": "", 05:08:41 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:08:41 INFO - "buildid": "20151221023004", 05:08:41 INFO - "pgo_build": "True", 05:08:41 INFO - "basedir": "/builds/slave/test-pgo", 05:08:41 INFO - "buildnumber": 395, 05:08:41 INFO - "slavename": "talos-linux64-ix-011", 05:08:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:08:41 INFO - "platform": "ubuntu64_hw", 05:08:41 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:08:41 INFO - "branch": "fx-team", 05:08:41 INFO - "repo_path": "integration/fx-team", 05:08:41 INFO - "revision": "b76ea647debcadd234066150c78b3bba77a50c74", 05:08:41 INFO - "stage_platform": "linux64-pgo", 05:08:41 INFO - "builduid": "8077f643da094a69b16fadd903d2d4c4", 05:08:41 INFO - "slavebuilddir": "test" 05:08:41 INFO - } 05:08:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:08:41 INFO - Running post-action listener: _resource_record_post_action 05:08:41 INFO - ##### 05:08:41 INFO - ##### Running download-and-extract step. 05:08:41 INFO - ##### 05:08:41 INFO - Running pre-action listener: _resource_record_pre_action 05:08:41 INFO - Running main action method: download_and_extract 05:08:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:41 INFO - https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:08:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/test_packages.json 05:08:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/test_packages.json 05:08:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:08:42 INFO - Downloaded 1302 bytes. 05:08:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:08:42 INFO - Using the following test package requirements: 05:08:42 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:42 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:08:42 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'jsshell-linux-x86_64.zip'], 05:08:42 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:08:42 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:42 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:08:42 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:08:42 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:08:42 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:42 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:42 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:08:42 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:08:42 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')]} 05:08:42 INFO - https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:44 INFO - Downloaded 23121249 bytes. 05:08:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:08:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:08:45 INFO - Return code: 0 05:08:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:45 INFO - https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:49 INFO - Downloaded 23121249 bytes. 05:08:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:08:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:08:51 INFO - Return code: 0 05:08: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')]} 05:08:51 INFO - https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:08:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:08:52 INFO - Downloaded 11132039 bytes. 05:08:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:08:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:08:52 INFO - Return code: 0 05:08: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')]} 05:08:52 INFO - https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:08:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:08:54 INFO - Downloaded 54652663 bytes. 05:08:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:54 INFO - mkdir: /builds/slave/test-pgo/properties 05:08:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:08:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:08:54 INFO - Contents: 05:08:54 INFO - build_url:https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - Running post-action listener: set_extra_try_arguments 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running populate-webroot step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: populate_webroot 05:08:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:54 INFO - '--tppagecycles', 05:08:54 INFO - '7'], 05:08:54 INFO - 'tests': ['tsvgm']}}, 05:08:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:08:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:08:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:08:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['damp', 'tps']}, 05:08:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['damp', 'tps']}, 05:08:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:08:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:54 INFO - 'other': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other_l64': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other_nol64': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'svgr': {'tests': ['tsvgx', 05:08:54 INFO - 'tsvgr_opacity', 05:08:54 INFO - 'tart', 05:08:54 INFO - 'tscrollx', 05:08:54 INFO - 'cart']}, 05:08:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tsvgx', 05:08:54 INFO - 'tsvgr_opacity', 05:08:54 INFO - 'tart', 05:08:54 INFO - 'tscrollx', 05:08:54 INFO - 'cart']}, 05:08:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['tp5o']}, 05:08:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tp5o']}, 05:08:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--xperf_path', 05:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:54 INFO - 'tests': ['tp5n']}, 05:08:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s', 05:08:54 INFO - '--xperf_path', 05:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:54 INFO - 'tests': ['tp5n']}}, 05:08:54 INFO - 'talos.zip': {'path': '', 05:08:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running create-virtualenv step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: create_virtualenv 05:08:54 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:08:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:08:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:08:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:55 INFO - Using real prefix '/usr' 05:08:55 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:08:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:57 INFO - Installing pip.................done. 05:08:57 INFO - Return code: 0 05:08:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:08:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 05:08:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:08:57 INFO - Using env: {'DISPLAY': ':0', 05:08:57 INFO - 'HOME': '/home/cltbld', 05:08:57 INFO - 'LANG': 'en_US.UTF-8', 05:08:57 INFO - 'LANGUAGE': 'en_US:en', 05:08:57 INFO - 'LOGNAME': 'cltbld', 05:08:57 INFO - 'MAIL': '/var/mail/cltbld', 05:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:57 INFO - 'MOZ_NO_REMOTE': '1', 05:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:57 INFO - 'NO_EM_RESTART': '1', 05:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:08:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:08:57 INFO - 'SHELL': '/bin/bash', 05:08:57 INFO - 'SHLVL': '1', 05:08:57 INFO - 'TERM': 'linux', 05:08:57 INFO - 'TMOUT': '86400', 05:08:57 INFO - 'USER': 'cltbld', 05:08:57 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:57 INFO - '_': '/tools/buildbot/bin/python'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 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 05:08:57 INFO - Cleaning up... 05:08:57 INFO - Return code: 0 05:08:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:08:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:08:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:08:57 INFO - Using env: {'DISPLAY': ':0', 05:08:57 INFO - 'HOME': '/home/cltbld', 05:08:57 INFO - 'LANG': 'en_US.UTF-8', 05:08:57 INFO - 'LANGUAGE': 'en_US:en', 05:08:57 INFO - 'LOGNAME': 'cltbld', 05:08:57 INFO - 'MAIL': '/var/mail/cltbld', 05:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:57 INFO - 'MOZ_NO_REMOTE': '1', 05:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:57 INFO - 'NO_EM_RESTART': '1', 05:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:08:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:08:57 INFO - 'SHELL': '/bin/bash', 05:08:57 INFO - 'SHLVL': '1', 05:08:57 INFO - 'TERM': 'linux', 05:08:57 INFO - 'TMOUT': '86400', 05:08:57 INFO - 'USER': 'cltbld', 05:08:57 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:57 INFO - '_': '/tools/buildbot/bin/python'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 INFO - Downloading/unpacking psutil>=0.7.1 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:08:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:08:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:59 INFO - Installing collected packages: psutil 05:08:59 INFO - Running setup.py install for psutil 05:08:59 INFO - building 'psutil._psutil_linux' extension 05:08:59 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 05:09:00 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 05:09:00 INFO - building 'psutil._psutil_posix' extension 05:09:00 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 05:09:00 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 05:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:00 INFO - Successfully installed psutil 05:09:00 INFO - Cleaning up... 05:09:00 INFO - Return code: 0 05:09:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:09:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:09:00 INFO - Using env: {'DISPLAY': ':0', 05:09:00 INFO - 'HOME': '/home/cltbld', 05:09:00 INFO - 'LANG': 'en_US.UTF-8', 05:09:00 INFO - 'LANGUAGE': 'en_US:en', 05:09:00 INFO - 'LOGNAME': 'cltbld', 05:09:00 INFO - 'MAIL': '/var/mail/cltbld', 05:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:00 INFO - 'MOZ_NO_REMOTE': '1', 05:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:00 INFO - 'NO_EM_RESTART': '1', 05:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:00 INFO - 'SHELL': '/bin/bash', 05:09:00 INFO - 'SHLVL': '1', 05:09:00 INFO - 'TERM': 'linux', 05:09:00 INFO - 'TMOUT': '86400', 05:09:00 INFO - 'USER': 'cltbld', 05:09:00 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:00 INFO - '_': '/tools/buildbot/bin/python'} 05:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09:00 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 05:09:00 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 05:09:00 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 05:09:00 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 05:09:00 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 05:09:00 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 05:09:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:09:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:02 INFO - Installing collected packages: mozsystemmonitor 05:09:02 INFO - Running setup.py install for mozsystemmonitor 05:09:02 INFO - Successfully installed mozsystemmonitor 05:09:02 INFO - Cleaning up... 05:09:02 INFO - Return code: 0 05:09:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 05:09:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:09:02 INFO - Using env: {'DISPLAY': ':0', 05:09:02 INFO - 'HOME': '/home/cltbld', 05:09:02 INFO - 'LANG': 'en_US.UTF-8', 05:09:02 INFO - 'LANGUAGE': 'en_US:en', 05:09:02 INFO - 'LOGNAME': 'cltbld', 05:09:02 INFO - 'MAIL': '/var/mail/cltbld', 05:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:02 INFO - 'MOZ_NO_REMOTE': '1', 05:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:02 INFO - 'NO_EM_RESTART': '1', 05:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:02 INFO - 'SHELL': '/bin/bash', 05:09:02 INFO - 'SHLVL': '1', 05:09:02 INFO - 'TERM': 'linux', 05:09:02 INFO - 'TMOUT': '86400', 05:09:02 INFO - 'USER': 'cltbld', 05:09:02 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:02 INFO - '_': '/tools/buildbot/bin/python'} 05:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:02 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 05:09:02 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 05:09:02 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 05:09:02 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 05:09:02 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 05:09:02 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 05:09:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:09:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:09:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:09:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - Downloading docopt-0.6.1.tar.gz 05:09:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:09:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:05 INFO - Running setup.py install for blobuploader 05:09:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:05 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:09:05 INFO - Running setup.py install for requests 05:09:05 INFO - Running setup.py install for docopt 05:09:05 INFO - Successfully installed blobuploader requests docopt 05:09:05 INFO - Cleaning up... 05:09:05 INFO - Return code: 0 05:09:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:09: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')]} 05:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 05:09:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 05:09:05 INFO - Using env: {'DISPLAY': ':0', 05:09:05 INFO - 'HOME': '/home/cltbld', 05:09:05 INFO - 'LANG': 'en_US.UTF-8', 05:09:05 INFO - 'LANGUAGE': 'en_US:en', 05:09:05 INFO - 'LOGNAME': 'cltbld', 05:09:05 INFO - 'MAIL': '/var/mail/cltbld', 05:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:05 INFO - 'MOZ_NO_REMOTE': '1', 05:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:05 INFO - 'NO_EM_RESTART': '1', 05:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:05 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:05 INFO - 'SHELL': '/bin/bash', 05:09:05 INFO - 'SHLVL': '1', 05:09:05 INFO - 'TERM': 'linux', 05:09:05 INFO - 'TMOUT': '86400', 05:09:05 INFO - 'USER': 'cltbld', 05:09:05 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:05 INFO - '_': '/tools/buildbot/bin/python'} 05:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:09:05 INFO - Running setup.py (path:/tmp/pip-CnyjOd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:09:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:09:05 INFO - Running setup.py (path:/tmp/pip-7dyoKO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:09:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:09:05 INFO - Running setup.py (path:/tmp/pip-GutNqI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:09:06 INFO - Running setup.py (path:/tmp/pip-BVe2Rl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:09:06 INFO - Running setup.py (path:/tmp/pip-MYrMqi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:09:06 INFO - Running setup.py (path:/tmp/pip-Psd0Uo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:09:06 INFO - Running setup.py (path:/tmp/pip-nhfao_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:09:06 INFO - Running setup.py (path:/tmp/pip-EaGIMG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:09:06 INFO - Running setup.py (path:/tmp/pip-so2U26-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:09:06 INFO - Running setup.py (path:/tmp/pip-vwYXXo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:09:06 INFO - Running setup.py (path:/tmp/pip-L4uFFe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:09:06 INFO - Running setup.py (path:/tmp/pip-wlV0sh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:09:06 INFO - Running setup.py (path:/tmp/pip-raT2RG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:09:06 INFO - Running setup.py (path:/tmp/pip-25Wq05-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:09:06 INFO - Running setup.py (path:/tmp/pip-dXhz1l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:09:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:09:06 INFO - Running setup.py (path:/tmp/pip-dFwmr7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:09:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:09:07 INFO - Running setup.py (path:/tmp/pip-b_bXEE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:09:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:07 INFO - Running setup.py install for manifestparser 05:09:07 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Running setup.py install for mozcrash 05:09:07 INFO - Running setup.py install for mozdebug 05:09:07 INFO - Running setup.py install for mozdevice 05:09:07 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Running setup.py install for mozfile 05:09:07 INFO - Running setup.py install for mozhttpd 05:09:07 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Running setup.py install for mozinfo 05:09:07 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Running setup.py install for mozInstall 05:09:07 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:09:07 INFO - Running setup.py install for mozleak 05:09:07 INFO - Running setup.py install for mozlog 05:09:08 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Running setup.py install for moznetwork 05:09:08 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Running setup.py install for mozprocess 05:09:08 INFO - Running setup.py install for mozprofile 05:09:08 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Running setup.py install for mozrunner 05:09:08 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Running setup.py install for mozscreenshot 05:09:08 INFO - Running setup.py install for moztest 05:09:08 INFO - Running setup.py install for mozversion 05:09:08 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:09:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:08 INFO - Cleaning up... 05:09:08 INFO - Return code: 0 05:09:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:09: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')]} 05:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 05:09:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 05:09:08 INFO - Using env: {'DISPLAY': ':0', 05:09:08 INFO - 'HOME': '/home/cltbld', 05:09:08 INFO - 'LANG': 'en_US.UTF-8', 05:09:08 INFO - 'LANGUAGE': 'en_US:en', 05:09:08 INFO - 'LOGNAME': 'cltbld', 05:09:08 INFO - 'MAIL': '/var/mail/cltbld', 05:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:08 INFO - 'MOZ_NO_REMOTE': '1', 05:09:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:08 INFO - 'NO_EM_RESTART': '1', 05:09:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:08 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:08 INFO - 'SHELL': '/bin/bash', 05:09:08 INFO - 'SHLVL': '1', 05:09:08 INFO - 'TERM': 'linux', 05:09:08 INFO - 'TMOUT': '86400', 05:09:08 INFO - 'USER': 'cltbld', 05:09:08 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:08 INFO - '_': '/tools/buildbot/bin/python'} 05:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:09:09 INFO - Running setup.py (path:/tmp/pip-yrcEKE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:09:09 INFO - Running setup.py (path:/tmp/pip-APqfZO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:09:09 INFO - Running setup.py (path:/tmp/pip-EKWKzo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:09:09 INFO - Running setup.py (path:/tmp/pip-w0Ao3W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:09:09 INFO - Running setup.py (path:/tmp/pip-17p3gG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:09:09 INFO - Running setup.py (path:/tmp/pip-RTjZAF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:09:09 INFO - Running setup.py (path:/tmp/pip-Yg9wpB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:09:09 INFO - Running setup.py (path:/tmp/pip-Twgro4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:09:09 INFO - Running setup.py (path:/tmp/pip-biLePj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:09:09 INFO - Running setup.py (path:/tmp/pip-8Nttut-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:09:09 INFO - Running setup.py (path:/tmp/pip-BS_qO9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:09:09 INFO - Running setup.py (path:/tmp/pip-EgjFp9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:09:09 INFO - Running setup.py (path:/tmp/pip-7zGR26-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:09:09 INFO - Running setup.py (path:/tmp/pip-z5MEwQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:09:10 INFO - Running setup.py (path:/tmp/pip-5ioK0B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:09:10 INFO - Running setup.py (path:/tmp/pip-S2uShH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:09:10 INFO - Running setup.py (path:/tmp/pip-PS0Qbo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:10 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 05:09:10 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 05:09:10 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 05:09:10 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 05:09:10 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 05:09:10 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 05:09:11 INFO - Downloading blessings-1.5.1.tar.gz 05:09:11 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:09:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:12 INFO - Installing collected packages: blessings 05:09:12 INFO - Running setup.py install for blessings 05:09:12 INFO - Successfully installed blessings 05:09:12 INFO - Cleaning up... 05:09:12 INFO - Return code: 0 05:09:12 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:09:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:09:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:09:12 INFO - Reading from file tmpfile_stdout 05:09:12 INFO - Current package versions: 05:09:12 INFO - argparse == 1.2.1 05:09:12 INFO - blessings == 1.5.1 05:09:12 INFO - blobuploader == 1.2.4 05:09:12 INFO - docopt == 0.6.1 05:09:12 INFO - manifestparser == 1.1 05:09:12 INFO - mozInstall == 1.12 05:09:12 INFO - mozcrash == 0.16 05:09:12 INFO - mozdebug == 0.1 05:09:12 INFO - mozdevice == 0.47 05:09:12 INFO - mozfile == 1.2 05:09:12 INFO - mozhttpd == 0.7 05:09:12 INFO - mozinfo == 0.9 05:09:12 INFO - mozleak == 0.1 05:09:12 INFO - mozlog == 3.1 05:09:12 INFO - moznetwork == 0.27 05:09:12 INFO - mozprocess == 0.22 05:09:12 INFO - mozprofile == 0.27 05:09:12 INFO - mozrunner == 6.11 05:09:12 INFO - mozscreenshot == 0.1 05:09:12 INFO - mozsystemmonitor == 0.0 05:09:12 INFO - moztest == 0.7 05:09:12 INFO - mozversion == 1.4 05:09:12 INFO - psutil == 3.1.1 05:09:12 INFO - requests == 1.2.3 05:09:12 INFO - wsgiref == 0.1.2 05:09:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 05:09:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 05:09:12 INFO - Using env: {'DISPLAY': ':0', 05:09:12 INFO - 'HOME': '/home/cltbld', 05:09:12 INFO - 'LANG': 'en_US.UTF-8', 05:09:12 INFO - 'LANGUAGE': 'en_US:en', 05:09:12 INFO - 'LOGNAME': 'cltbld', 05:09:12 INFO - 'MAIL': '/var/mail/cltbld', 05:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:12 INFO - 'MOZ_NO_REMOTE': '1', 05:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:12 INFO - 'NO_EM_RESTART': '1', 05:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:12 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:12 INFO - 'SHELL': '/bin/bash', 05:09:12 INFO - 'SHLVL': '1', 05:09:12 INFO - 'TERM': 'linux', 05:09:12 INFO - 'TMOUT': '86400', 05:09:12 INFO - 'USER': 'cltbld', 05:09:12 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:12 INFO - '_': '/tools/buildbot/bin/python'} 05:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:09:12 INFO - Cleaning up... 05:09:12 INFO - Return code: 0 05:09:12 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 05:09:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:09:12 INFO - Using env: {'DISPLAY': ':0', 05:09:12 INFO - 'HOME': '/home/cltbld', 05:09:12 INFO - 'LANG': 'en_US.UTF-8', 05:09:12 INFO - 'LANGUAGE': 'en_US:en', 05:09:12 INFO - 'LOGNAME': 'cltbld', 05:09:12 INFO - 'MAIL': '/var/mail/cltbld', 05:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:12 INFO - 'MOZ_NO_REMOTE': '1', 05:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:12 INFO - 'NO_EM_RESTART': '1', 05:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:12 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:12 INFO - 'SHELL': '/bin/bash', 05:09:12 INFO - 'SHLVL': '1', 05:09:12 INFO - 'TERM': 'linux', 05:09:12 INFO - 'TMOUT': '86400', 05:09:12 INFO - 'USER': 'cltbld', 05:09:12 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:12 INFO - '_': '/tools/buildbot/bin/python'} 05:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:12 INFO - Downloading/unpacking jsonschema 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:09:14 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:14 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:09:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:14 INFO - warning: no files found matching '*.txt' 05:09:14 INFO - no previously-included directories found matching 'build' 05:09:14 INFO - no previously-included directories found matching 'dist' 05:09:14 INFO - no previously-included directories found matching '.git*' 05:09:14 INFO - Installing collected packages: jsonschema, functools32 05:09:14 INFO - Running setup.py install for functools32 05:09:14 INFO - warning: no files found matching '*.txt' 05:09:14 INFO - no previously-included directories found matching 'build' 05:09:14 INFO - no previously-included directories found matching 'dist' 05:09:14 INFO - no previously-included directories found matching '.git*' 05:09:14 INFO - Successfully installed jsonschema functools32 05:09:14 INFO - Cleaning up... 05:09:14 INFO - Return code: 0 05:09:14 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:09:14 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')]} 05:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:14 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')]} 05:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f24edf9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a89600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 05:09:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 05:09:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:09:14 INFO - Using env: {'DISPLAY': ':0', 05:09:14 INFO - 'HOME': '/home/cltbld', 05:09:14 INFO - 'LANG': 'en_US.UTF-8', 05:09:14 INFO - 'LANGUAGE': 'en_US:en', 05:09:14 INFO - 'LOGNAME': 'cltbld', 05:09:14 INFO - 'MAIL': '/var/mail/cltbld', 05:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:14 INFO - 'MOZ_NO_REMOTE': '1', 05:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:14 INFO - 'NO_EM_RESTART': '1', 05:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:14 INFO - 'SHELL': '/bin/bash', 05:09:14 INFO - 'SHLVL': '1', 05:09:14 INFO - 'TERM': 'linux', 05:09:14 INFO - 'TMOUT': '86400', 05:09:14 INFO - 'USER': 'cltbld', 05:09:14 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:14 INFO - '_': '/tools/buildbot/bin/python'} 05:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:15 INFO - Downloading/unpacking flake8 05:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:09:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:09:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:09:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:09:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:17 INFO - no previously-included directories found matching 'docs/_build' 05:09:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:17 INFO - Running setup.py install for pep8 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:17 INFO - no previously-included directories found matching 'docs/_build' 05:09:17 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:09:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:17 INFO - Cleaning up... 05:09:17 INFO - Return code: 0 05:09:17 INFO - Running post-action listener: _resource_record_post_action 05:09:17 INFO - Running post-action listener: _start_resource_monitoring 05:09:17 INFO - Starting resource monitoring. 05:09:17 INFO - ##### 05:09:17 INFO - ##### Running install step. 05:09:17 INFO - ##### 05:09:17 INFO - Running pre-action listener: _resource_record_pre_action 05:09:17 INFO - Running main action method: install 05:09:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:09:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:09:17 INFO - Reading from file tmpfile_stdout 05:09:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:09:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:09:18 INFO - Reading from file tmpfile_stdout 05:09:18 INFO - Output received: 05:09:18 INFO - Usage: mozinstall [options] installer 05:09:18 INFO - Options: 05:09:18 INFO - -h, --help show this help message and exit 05:09:18 INFO - -d DEST, --destination=DEST 05:09:18 INFO - Directory to install application into. [default: 05:09:18 INFO - "/builds/slave/test-pgo"] 05:09:18 INFO - --app=APP Application being installed. [default: firefox] 05:09:18 INFO - mkdir: /builds/slave/test-pgo/build/application 05:09:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:09:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:09:33 INFO - Reading from file tmpfile_stdout 05:09:33 INFO - Output received: 05:09:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:09:33 INFO - Running post-action listener: _resource_record_post_action 05:09:33 INFO - ##### 05:09:33 INFO - ##### Running run-tests step. 05:09:33 INFO - ##### 05:09:33 INFO - Running pre-action listener: _resource_record_pre_action 05:09:33 INFO - Running main action method: run_tests 05:09:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:09:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:09:33 INFO - Python 2.7.3 05:09:33 INFO - Return code: 0 05:09:33 INFO - grabbing minidump binary from tooltool 05:09:33 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')]} 05:09:33 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-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b18350>, '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 0x2b19250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b196e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:09:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:09:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:09:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:33 INFO - Return code: 0 05:09:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:09:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:09:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:09:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:09:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:09:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:09:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:09:34 INFO - Return code: 0 05:09:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:09:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost 05:09:34 INFO - Using env: {'DISPLAY': ':0', 05:09:34 INFO - 'HOME': '/home/cltbld', 05:09:34 INFO - 'LANG': 'en_US.UTF-8', 05:09:34 INFO - 'LANGUAGE': 'en_US:en', 05:09:34 INFO - 'LOGNAME': 'cltbld', 05:09:34 INFO - 'MAIL': '/var/mail/cltbld', 05:09:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:09:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:34 INFO - 'MOZ_NO_REMOTE': '1', 05:09:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:34 INFO - 'NO_EM_RESTART': '1', 05:09:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:09:34 INFO - 'PWD': '/builds/slave/test-pgo', 05:09:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:09:34 INFO - 'SHELL': '/bin/bash', 05:09:34 INFO - 'SHLVL': '1', 05:09:34 INFO - 'TERM': 'linux', 05:09:34 INFO - 'TMOUT': '86400', 05:09:34 INFO - 'USER': 'cltbld', 05:09:34 INFO - 'XDG_SESSION_COOKIE': '76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289', 05:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:34 INFO - '_': '/tools/buildbot/bin/python'} 05:09:34 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost'] with output_timeout 3600 05:09:35 INFO - mozversion INFO | application_buildid: 20151221023004 05:09:35 INFO - mozversion INFO | application_changeset: b76ea647debcadd234066150c78b3bba77a50c74 05:09:35 INFO - mozversion INFO | application_display_name: Nightly 05:09:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:35 INFO - mozversion INFO | application_name: Firefox 05:09:35 INFO - mozversion INFO | application_remotingname: firefox 05:09:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 05:09:35 INFO - mozversion INFO | application_vendor: Mozilla 05:09:35 INFO - mozversion INFO | application_version: 46.0a1 05:09:35 INFO - mozversion INFO | platform_buildid: 20151221023004 05:09:35 INFO - mozversion INFO | platform_changeset: b76ea647debcadd234066150c78b3bba77a50c74 05:09:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 05:09:35 INFO - mozversion INFO | platform_version: 46.0a1 05:09:35 INFO - 2015-12-21 05:09:35,004 DEBUG : using testdate: 1450703375 05:09:35 INFO - 2015-12-21 05:09:35,004 DEBUG : actual date: 1450703375 05:09:35 INFO - 2015-12-21 05:09:35,015 INFO : starting webserver on 'localhost:34912' 05:09:35 INFO - 2015-12-21 05:09:35,016 INFO : Starting test suite talos-linux64-ix-011.e 05:09:35 INFO - 2015-12-21 05:09:35,016 INFO : Starting test dromaeo_css 05:09:35 INFO - 2015-12-21 05:09:35,016 DEBUG : operating with platform_type : linux_ 05:09:35 INFO - 2015-12-21 05:09:35,017 INFO : Initialising browser for dromaeo_css test... 05:09:35 INFO - 2015-12-21 05:09:35,032 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp16HXVf/profile http://localhost:34912/getInfo.html 05:09:39 INFO - 2015-12-21 05:09:39,056 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:09:39 INFO - 2015-12-21 05:09:39,927 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:09:39 INFO - 2015-12-21 05:09:39,927 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:09:39 INFO - 2015-12-21 05:09:39,927 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:09:39 INFO - 2015-12-21 05:09:39,927 DEBUG : BROWSER_OUTPUT: __metrics 05:09:40 INFO - 2015-12-21 05:09:40,064 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:09:40 INFO - 2015-12-21 05:09:40,921 INFO : Browser initialized. 05:09:40 INFO - 2015-12-21 05:09:40,921 INFO : Running cycle 1/1 for dromaeo_css test... 05:09:40 INFO - 2015-12-21 05:09:40,922 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp16HXVf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:09:52 INFO - 2015-12-21 05:09:52,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 150781952 05:09:52 INFO - 2015-12-21 05:09:52,738 DEBUG : BROWSER_OUTPUT: 05:10:42 INFO - 2015-12-21 05:10:42,347 DEBUG : BROWSER_OUTPUT: 1450703442342 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] 05:12:51 INFO - 2015-12-21 05:12:51,079 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34912/tests/dromaeo/cssquery-ext.html) 05:12:52 INFO - 2015-12-21 05:12:52,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 160030720 05:12:52 INFO - 2015-12-21 05:12:52,096 DEBUG : BROWSER_OUTPUT: 05:15:53 INFO - 2015-12-21 05:15:53,971 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-ext.html (next: http://localhost:34912/tests/dromaeo/cssquery-jquery.html) 05:15:54 INFO - 2015-12-21 05:15:54,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 153473024 05:15:54 INFO - 2015-12-21 05:15:54,559 DEBUG : BROWSER_OUTPUT: 05:18:56 INFO - 2015-12-21 05:18:56,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34912/tests/dromaeo/cssquery-mootools.html) 05:18:56 INFO - 2015-12-21 05:18:56,762 DEBUG : BROWSER_OUTPUT: RSS: Main: 153735168 05:18:56 INFO - 2015-12-21 05:18:56,762 DEBUG : BROWSER_OUTPUT: 05:21:58 INFO - 2015-12-21 05:21:58,401 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34912/tests/dromaeo/cssquery-prototype.html) 05:21:59 INFO - 2015-12-21 05:21:59,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 152555520 05:21:59 INFO - 2015-12-21 05:21:59,002 DEBUG : BROWSER_OUTPUT: 05:25:01 INFO - 2015-12-21 05:25:01,274 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34912/tests/dromaeo/cssquery-yui.html) 05:25:01 INFO - 2015-12-21 05:25:01,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 152489984 05:25:01 INFO - 2015-12-21 05:25:01,900 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,263 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/dromaeo/cssquery-yui.html (next: http://localhost:34912/tests/dromaeo/cssquery-dojo.html) 05:28:04 INFO - 2015-12-21 05:28:04,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 155963392 05:28:04 INFO - 2015-12-21 05:28:04,513 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,513 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:28:04 INFO - 2015-12-21 05:28:04,513 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:28:04 INFO - 2015-12-21 05:28:04,513 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:28:04 INFO - 2015-12-21 05:28:04,513 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:28:04 INFO - 2015-12-21 05:28:04,515 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3157.3211759471715;3210.5184222366643;3230.2085988932713;3258.5438038674574;3260.1197676627303;5191;5299.205119232112;5369.114096174131;5377.838664840051;5383.1656093305555;5189.532942035216;5266.4733526647415;5302.973485132578;5307.840764777054;5408.999335123005;4839.854804355866;4895.36360273164;4897.706137631753;4942.728149951751;4992.87517812054;4866.343043689109;4884.829030983917;4918.827841025565;4935.136351138554;4958.404991401019;4722.338872557844;4743.857684269477;4824.276358546229;4875.536824001714;4883.291922671228;4886.047220791947;4889.511048895118;4900.436449808282;4917.950820491785;4944;4678.134545109166;4892.266160075981;4892.8532144035735;4897.730624815634;4905.779239934212;5021.422536408323;5025.773840177164;5047.242913562958;5117.1556693145585;5135.460776618439;5634.887302253932;5661.490465858093;5665.2351932489;5667.574931880125;5688.658680479184;5655.717214139277;5672.546196304285;5680.233168522238;5689.516391106761;5729.312482502085;5369.838904832861;5389.51494365509;5424.321959755031;5469.562435005229;5526.834194974117;5859.414058594191;5896.086106653475;5908.05471124618;5925.674087925119;5937.465628093492;5541.5843811714285;5640.069388550925;5712.971435142797;5728.77084916607;5756.798512052058;5798.710064496759;5852.414758524199;5853.765849366063;5865.736041878125;5906.173135760997;5660.2075709400715;5701.543876489871;5710.8001219957105;5716.114002329646;5719.28508936383;5474.206240095244;5577.8047768327915;5634.830955071355;5698.401667824902;5708.372079071299;4443.600075993176;4454.955450445519;4456.46522417312;4468.396766436489;4493;3972.106276087857;3973.1060511385285;3976.0855003349393;3998.080441498472;4011.919761604752;5241.397239317527;5313.442088580643;5362.436944120889;5493.038718224375;5519.69641669704;5823.359430462646;5827.650340979555;5829.47534721877;5834.708264586839;5836.533077353802;5472.972635136878;5478.835634930959;5489.752961116679;5492.5605951523785;5508.807191748269;4861.489543597942;4898.877528061756;4953.950460495349;4975.477574854661;5029.622778291569;5737.454941780524;5772.480476757028;5776.018076926933;5795.159701843209;5798.768049277982;5537.473939975695;5719.942800571941;5722.2561067061015;5727.799527016535;5730.054541000688;5669.376368599534;5814.04068328721;5905.704714764159;5914.526837853047;5936.643801371932;5701.515371193495;5725.2843394575675;5726.455986681259;5731;5757.625754325955;5552.194931734795;5635.351934527582;5768.567357448124;5841.18223448709;5842.474177324061;5614.07367784311;5678.602497825113;5680.630759000652;5688.40271771458;5725.570582206268;5675.545956323567;5682.744276507485;5694.886102277848;5698.800541981011;5716.256886604715;5325.680459172462;5398;5412.918806217831;5566.860828479158;5620.044592419216;5692.459216374438;5696.487316141402;5697.572682048946;5717.599768016199;5723.656580605177;5513.338399392099;5543.639663422026;5544.251526044094;5562.860928476821;5584.497395234448 05:28:04 INFO - 2015-12-21 05:28:04,518 DEBUG : BROWSER_OUTPUT: |1;ext.html;521.8669239343969;569.4391024840534;570.221647451229;577.1919312961852;585.7686213945489;28879.27801804956;37850.81074594626;37916.43125353122;38222.42666360003;38316;40463;41088;41154.794226028826;41171.58828411715;41202.587974120324;8620.525871077089;8772.69295574655;8798.780030499256;8802.295816334678;8827.911720882805;8608.784780380478;8707.434016788888;8721.825563488726;8723.30213582915;8726.127387261287;8699.347548933823;8707.477551346909;8718.564071796416;8756.737297881074;8768.34237432192;8688.695895643654;8699.60851761669;8755.868661970075;8778.29773618109;8796.34027447944;8571.400001999862;8673.869891951626;8675.783105422352;8685.348598855111;8691.217790398894;38263.617363826284;38853.22293554142;38891.611083889366;38938.41592376117;39127.60872391268;3257.397381484433;3400.540926974876;3413.5903691557173;3746.400575907842;3876.0503676599133;28873.42253154925;29045.12864614065;29083;29224.70775292241;29843.850780746176;3765.548134223883;3818.4081467372603;3818.522684664417;3939.822707978119;3981.124152686404;242781;243102;243322.7833860837;243623;244600;55106;55521.72239138778;56694;58234;58749.41250587525;52087.739561301954;52237.47762522326;52451.21323180155;52458;52597.7370113147;13255.204687718768;13258;13411.061225714106;13417.262050587116;13450.12574182675;10387.532561034772;10462;10474.947625261824;10498.055175034284;10508.947455262674;19375.4187374379;19488.309706758075;19497.545596040767;19510.488461942256;19570.70643940342;7727.227277272228;7850.293473587404;8002.279794818494;8002.3598112150885;8007.839843203104;966.709987003896;981.7790997025612;985.5072463768116;1039.6517166749072;1043.222799014739;24723;25067;25091.121810736902;25121.748782512306;25147;1036.2538971940191;1065.9360438373722;1077.4612693653173;1135.6593022093339;1185.9703507412212;12955.481780728665;13213.73572528544;13219.339033048309;13345.866541334657;13397.263150526815;292795;296304;296624;296806;297092;48366;48379.516204838204;48455.515444845805;48602;48610;1280.8462984441926;1358.6942937838908;1358.7825947848296;1359.6396954806985;1372.3275594958598;24748.752512474646;25895;25939.0921317753;25944.48111037731;26084.478310433304;41097.794511027256;41333.79333103315;41530.37704434375;41598;41653.16693666049;279760;280571;281354;282098;282316;30262.54606180953;30879;31327.53008704917;31380.843095784374;31467.213319667193;36758.26483470369;37170;37264.06839829026;37402.81298593598;37482.625173749;30228.84885575558;33803.323933521686;33894.66105338915;35421.114472138404;38479.422808658455;33884.830575846965;33937.32125357528;34194.316113678084;34427.483587746705;34544.654553454144;34085.31829363448;34160.14599635029;34184.316313674084;34243.82878085594;34384.65615343815;29189.41621167607;30842.84578577093;31154.22114447157;31180.532292015185;31308;23996.160134395213;24279.75720242775;24320;24713.382165446008;24754.628680569447 05:28:04 INFO - 2015-12-21 05:28:04,518 DEBUG : BROWSER_OUTPUT: |2;jquery.html;356.2678695281197;359.12373807908716;360.17519879476004;360.35676317772766;365.92681463707265;14199.361028753705;14318.355673994669;16600.086995215257;16725.247363868624;17394.826051739477;16385.83614163858;16718.498445046636;17361.392351267707;17718.379856705018;17718.55703607407;5406.189071639266;5502.477264659851;5562.610617256773;5567.415421380758;5570.498655121039;5003.724795136266;5100.668456550331;5128.282040514331;5131.897362052757;5547.696291371524;5015.297858299841;5039.168537191377;5087.745612719368;5119.411267704225;5517.36550296717;5499.835004949858;5510.531604813616;5524.171374293868;5535.944640553583;5538.529225015878;5212.4266330703595;5272;5283.656562323476;5314.97342513285;5432.782688692448;12740.745185096246;12940.15888967214;13024.348782560834;13394.933025334909;20903.163873445043;2048.365006847867;2114.1332053858;2184.2901057156487;2186.202036256755;2255.5263394687136;9892.406455612687;10009;12722.936385318108;13097.607071787766;13173.670658233525;1798.2716999615177;1862.590230149365;1887.1790771014516;1903.60085337277;1907.961840763177;61213.387866121215;63060.68469657622;63658;64090.679546602434;64730.676346618435;10057.64798232073;10232.948835255776;10329.948350258352;10400.375977441377;10427.791444171226;13547.729045419037;13714.86285137136;13766.380512876945;13773;13773.724525509635;6840.281770414035;6932.445404367639;7029.648517574101;7132.429405647639;7139;6634.270230274666;6642.933570664328;6644;6660.567063140978;6706.5640733352175;4341.8480353188115;4419.49175844782;4443.86668399942;4482.282834746425;4523.977380113079;1857.4984754116583;1857.7863545692148;1868.4207895552422;1880.9529761756066;1895.7251298441925;836.460482988469;874.4359887872287;928.4243768863348;939.1313035442189;952.2286947572488;16048.839511604734;16149.515514534583;16380.918095409685;16388.18059097064;16755.2460139294;886.0784783824879;914.4604683236922;915.5971372596036;918.4489306416231;968.801395713877;9145.588448519833;9213.723588292361;9221.81556368869;9324;9451.393263145144;489282;504681;505348;506776;535779;77783.16626253053;77893.22106778972;78940;79572.21497247684;80604;1079.5897558927713;1082.7509672775307;1094.8686157661132;1098.3794156301665;1102.1733699725205;14851.777223341442;14917.104973701613;14936.775948361;15001.299766042217;15221;35305;35370;36750.44874326834;36822;36897.44653830141;46789;47091;47761.52238477571;48235.75882120567;50682.493175069256;14243.359048843035;14258;14653.780193297322;14678.706425871209;14741;11260.493277802683;11438.542458301576;11587.88412115868;11678.182527223353;18070.728939066186;10231.590736370461;10324.793504130026;10367.481625918523;10398;10418.739531511774;9703.12671859536;10055;10314.484275786332;10403.843942341022;10408.427536485264;10161.339512931636;10166.339187952759;10184.185265178916;10229.28395012342;10324.948375258076;14525;14928.253587320374;14963.700725985636;15041.172735499653;15063.924680376966;14276.928615356857;15273.389064437742;15635.530934071996;15640;15866.44467443634 05:28:04 INFO - 2015-12-21 05:28:04,519 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1507.276507276508;1652.9752053719194;1708.3593652380357;1712.8201538838414;1729.69730297198;6053.969730151354;6377.489800815935;6955.791326260214;7126.643667816615;7148.678309476073;6753.898691519631;6848.486363522731;6900.447964162868;6914.8962765558435;6975.372216500514;2082.229575057231;2129.6273152198305;2132.4242454537266;2136.75427325858;2137.5190582119053;2092.393205970267;2102.726645536086;2125.691774692669;2134.8185404240658;2149.0973791007814;2041.9387418377473;2047.9488012799725;2055.537504061582;2062.5668609592003;2071.9171233150655;2104.757952835428;2134.2210093315985;2136.134865379524;2138.5402138540135;2140.5076832328573;2067.1627990663656;2067.431456349501;2094.748630164375;2100.9369718908456;2101.2120454829437;6895.379415852547;6936;6964.756233531853;6967.825804354881;6988.161420629506;4086.5096188457574;4166.625003749647;4167.312393455076;4180.91638167238;4180.937285940713;2630.658014458127;2691.286808995618;2712.5253080710795;2743.7256274372403;2750.4636595863813;888.1873086126187;898;909.6997990663066;928.5171710710392;943.4103685196752;9082.137196966343;9088.727338179866;9096.45421274719;9098.135677110664;9110.180083792491;3578.4274516077303;3619.6923261523066;3669.798161101112;3674.3018826422895;3701.6113308102263;7893.802654933673;7927.048754149424;7957.920420795834;7960.243776841191;7969.083555391088;4186.769727664947;4199.559046300094;4202;4202.957970420318;4206.915861682749;3785.5835858055593;3851.6533511983494;3853.5183102112237;3873.0511024798543;3887.3391523441082;3130.9373812523622;3134.420132275536;3157.194915296631;3158.38411509758;3176.47588147953;894.7294841325333;895.8163576466809;901.2294488212484;902.6930843513237;922.8707980882682;1288.5683296095913;1290.6708789258678;1293.4761421624257;1342.3892129081244;1349.7907824287252;6561.376669216417;6657.1012913257055;6689.799306020827;6694.297098804653;6710.396064354231;436.31448434883345;437.5646231999181;441.94696636403836;442.59281461055906;445.54554354558815;5255.894882102337;5279.551238144801;5305.708186049805;5315.548178404802;5328.893422131613;8100.068492123363;8127.796805079802;8240.258376746122;8377.70678026266;8400.369972252105;8073.434859559775;8109.148539403395;8167.795805104801;8171.10117887032;8213.79465513367;6558.114654521579;6691.933080669131;6700.698468568926;6723;6756.324367563303;6419.903701444388;6469.932461143859;6475.449586785176;6479.092926990131;6480.546361754632;7277;7460.179380268275;7480.812979675552;7503.624818758931;7559.735409260649;7559.811004724926;7620.923790762243;7621.69513219465;7639.465237433326;7667.539947603162;5464.371597266207;5539;6056.8182954509675;6129.662868542273;6130.601510901777;6599.571027883172;6674.632895190811;6685.699143538455;6692.130023096966;6734.56225345351;6315.5894866833505;6336.524760643062;6385.45723613498;6457.225132984072;6466.579672321473;6212.316645168938;6329.335419781041;6353.3011368748475;6381.521385895983;6427.517936154901;6378.681065946591;6417.679116044273;6420.3258657842125;6452.774152904626;6461.321561236003;5936;5949.791757288474;5951.702414879325;5979.282486101695;5993.700314984146;5891.734871930687;5954.851128721816;5983.863066017443;5995.820125396071;6003.489703375262 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: |4;prototype.html;335.2222843004231;336.95114208439793;342.8525733934408;343.1815120936566;346.6083325841799;6145.661988590626;6292.874142517153;6312.968435157829;6325.936740632592;6359.841003974903;6113.755449782015;6241.531885108612;6242.594231374957;6255.655938923357;6268.937310626892;4841.152798260308;4842.2252439609665;4853.611711063115;4855.053264613402;4885.267209918523;4882.267659851015;4888.853334399956;4894.902101957959;4904.215325547913;4905.190643543811;4763.69036012658;4861.683990540603;4865.927011094819;4866.780994855222;4873.853784386474;4840.2255639097575;4840.903181936359;4853.466118726938;4854.368932038848;4864.173090574611;4279.593438623326;4286.057067445188;4293.377460268243;4294.957050429487;4301.204277208726;5888.440598143169;6005.039193729026;6006.669633170174;6012.18835457212;6013.248343957005;5678.205051292823;5678.574106941955;5682.261306030231;5690.971545142289;5694.943050569483;6637.601743895333;6663.900041499382;6664.566803157779;6665.833354166185;6673.165854268216;4737.431508219036;4760.547747963938;4768.61851051915;4769.976150119227;4773.80904763809;6780.08468856703;6912.170539535237;6920.377166055079;6945.7221711132;6962.512624116314;4883.267509873547;4888.731119788375;4888.82889098887;4890.315355850184;4891.755412229374;4940.061388336205;4943.332650092263;4947.060058588857;4958.628102892298;4960.330355402047;5503.284573005483;5553.583481238923;5605.990921634145;5609.8597535061135;5638.887222255532;6701.564398314192;6702.83242918931;6709.463242940553;6709.832254193684;6749.662516874137;1705.4713038957714;1722.0786879019915;1757.2795153986804;1758.4197214919302;1760.260690509989;1156.5200441816553;1184.1651635597057;1190.9761804763857;1198.6404078776507;1201.3212534917743;6342.080398342215;6361.777337793155;6364.268109167412;6364.586301890455;6370.936290637034;6854.006169105452;6879.415249703731;6880.415164710956;6928.7228510859;6941;6261.311255761862;6338.366163383625;6368.395002474757;6373.075903993895;6375.34241097318;3733.9439908401396;3777.962220377816;3783.5459744830246;3785.470034195215;3787.715921305858;6971.616561089088;7000.859982800417;7022.92977070233;7023.578585284797;7034.331738484836;6879.828004299932;7085.397741191953;7090.964545177344;7105.680244389015;7109.608971506616;7011.89482157768;7017.789466316019;7022.824429389306;7024.332688394595;7034.859302813915;6900.413464855544;6918.896216556655;6932.584044957117;6937.895931561132;6950.443964482728;6673.065770791996;6781.152355955505;6784.864302714017;6824.351686589766;6831.863362732618;6942.097527321416;7012.474064445085;7018.929810701828;7022.54353467023;7037.331453511908;4900.362952816254;4915.729634870117;4916.729579873141;4917.262410638433;4919.803207871645;6747.392734653905;6867.484938629723;6889.655517224019;6895.34494223048;6905.136857892763;6688.3646053624825;6697.129373181455;6702.229243637044;6703.8324041897395;6712.39588437043;6677.966610166918;6693.966530167318;6697.430718388991;6701.592665540291;6721.059051732664;6343.682815859096;6508.446782023581;6537.477001839937;6570.638614876037;6580.736770529125;6691.5315927884585;6709.060731497497;6735.461163106842;6739.831504212434;6757.797266082025;6590.934090659032;6593.0769692244085;6593.241777195684;6597.208334999639;6608.975608780646 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: |5;yui.html;752.9736459223928;792.7344339646219;820.7209548753426;834.6494472321624;845.2223953962354;13003.414846331914;13112.27882466464;13212;13244.337783110843;13281.203127812327;12014.699632509191;12399;12510.437030333635;12565.497380104785;12584.182028168163;663.300218269724;780.0288640642408;797.3222760653456;799.6121880887765;800.2597597222575;781.1953687701676;790.8576456237874;791.1455627921831;797.6131576185545;798.6246464161835;822.979425514361;827.8096037911284;834.6828205281985;835.603088532948;836.9748907532753;817.7178873288706;823.7281697039992;824.0523398092212;827.6730691376932;828.6436832162168;607.104520831774;611.6085705148708;615.0804547201917;615.1541630258395;615.9445649891486;12969.092163548556;13005.934970325088;13040.739185216242;13051.738965220737;13099.86900130996;11659.941700291529;12201.145919785618;12626.24242545441;13001.739965200737;13002.349882505838;10453.163746900305;10468.267221294513;10478.214133939984;10518.526666300035;10687.732806679818;3540.451230059345;3651;3681.668649821529;3699.223163135745;3734.066483379155;43832.3425148623;44144.779276103414;44155;44330.55669443296;44442.99999999968;9756.975517570694;9914.206863450907;9979.950100249598;10113.342632728996;10459.163266938627;17405.82594174042;17460.38888638917;17564.5608859777;17588.472345829647;17610;7332.156801967735;7537.095548534101;7586.544807311579;7589.1272503661685;7673.232676732282;3981.8606348777653;4004.139110091286;4025.19496100781;4074.531428885716;4101.5488296287385;2955.3350496138196;2957.778166637511;2977.6277965254344;2981.7465515431;2983.2392739851634;523.8428471458548;535.066309290291;538.6660270632154;542.9809956651499;548.3885467703482;579.5884921705637;591.1221835574169;597.8714576366102;645.6513482719373;664.5746722097862;7940;8089.838203235902;8304.211099945496;8331.541765202972;8414.453060551044;500.5845148526714;517.9585633149339;634.4353525362432;637.7417146055855;649.1690635985946;3637.6907962823457;3931.5282166140246;3985.980070099631;4038.2932986727205;4164.229617520768;59548.10677839836;59838.401615984156;59885;59979;60140;47692.284615730794;48468.51531484711;48700;48956.75521622369;54110.7294463533;50244.49755502471;51013;51171;52145;52367.73816130895;8600.22597966186;8726.956365218133;8781.29749620016;8824.205821476098;8881.689140880038;36783.63216367802;37427.251454971294;37540;37669.434958476195;37795.81102094472;45294.547054530354;46800.063998720514;47041.29438058363;47545.28682069702;47700.284495731896;11207.383593902414;11464.197506174487;11506.539738410369;11523.769524609628;11709.590164344207;12289.262644241375;12393.752124957631;12417.875821241672;12627.558035468715;12823.935880320912;10666.626668066581;10988;11039.834402483808;11094.889051109385;11671.8328167186;10864.293820901616;10926.453677315943;11063.336199828036;11387.544498219979;11461.312321260752;9995.200383969119;9997.400155990665;10197.235207359627;10216.795664086529;10254;8965.237954773656;9193.356465047382;9234.907650923673;9241.260699143917;9695.612175513183;8863.601137948679;8965.417247878868;9344.252459803336;9404.899301027954;10193.337433067123 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,323 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: __startTimestamp1450704484511__endTimestamp 05:28:04 INFO - 2015-12-21 05:28:04,521 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,522 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:28:04 INFO - 2015-12-21 05:28:04,522 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:28:04 INFO - 2015-12-21 05:28:04,522 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,522 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5327.53 Median:5543.64 stddev:584.20 (10.5%) stddev-sans-first:560.64 05:28:04 INFO - 2015-12-21 05:28:04,523 DEBUG : BROWSER_OUTPUT: Values: 3157.3 3210.5 3230.2 3258.5 3260.1 5191.0 5299.2 5369.1 5377.8 5383.2 5189.5 5266.5 5303.0 5307.8 5409.0 4839.9 4895.4 4897.7 4942.7 4992.9 4866.3 4884.8 4918.8 4935.1 4958.4 4722.3 4743.9 4824.3 4875.5 4883.3 4886.0 4889.5 4900.4 4918.0 4944.0 4678.1 4892.3 4892.9 4897.7 4905.8 5021.4 5025.8 5047.2 5117.2 5135.5 5634.9 5661.5 5665.2 5667.6 5688.7 5655.7 5672.5 5680.2 5689.5 5729.3 5369.8 5389.5 5424.3 5469.6 5526.8 5859.4 5896.1 5908.1 5925.7 5937.5 5541.6 5640.1 5713.0 5728.8 5756.8 5798.7 5852.4 5853.8 5865.7 5906.2 5660.2 5701.5 5710.8 5716.1 5719.3 5474.2 5577.8 5634.8 5698.4 5708.4 4443.6 4455.0 4456.5 4468.4 4493.0 3972.1 3973.1 3976.1 3998.1 4011.9 5241.4 5313.4 5362.4 5493.0 5519.7 5823.4 5827.7 5829.5 5834.7 5836.5 5473.0 5478.8 5489.8 5492.6 5508.8 4861.5 4898.9 4954.0 4975.5 5029.6 5737.5 5772.5 5776.0 5795.2 5798.8 5537.5 5719.9 5722.3 5727.8 5730.1 5669.4 5814.0 5905.7 5914.5 5936.6 5701.5 5725.3 5726.5 5731.0 5757.6 5552.2 5635.4 5768.6 5841.2 5842.5 5614.1 5678.6 5680.6 5688.4 5725.6 5675.5 5682.7 5694.9 5698.8 5716.3 5325.7 5398.0 5412.9 5566.9 5620.0 5692.5 5696.5 5697.6 5717.6 5723.7 5513.3 5543.6 5544.3 5562.9 5584.5 05:28:04 INFO - 2015-12-21 05:28:04,523 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,523 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43374.70 Median:34314.24 stddev:71624.45 (208.7%) stddev-sans-first:71753.10 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: Values: 521.9 569.4 570.2 577.2 585.8 28879.3 37850.8 37916.4 38222.4 38316.0 40463.0 41088.0 41154.8 41171.6 41202.6 8620.5 8772.7 8798.8 8802.3 8827.9 8608.8 8707.4 8721.8 8723.3 8726.1 8699.3 8707.5 8718.6 8756.7 8768.3 8688.7 8699.6 8755.9 8778.3 8796.3 8571.4 8673.9 8675.8 8685.3 8691.2 38263.6 38853.2 38891.6 38938.4 39127.6 3257.4 3400.5 3413.6 3746.4 3876.1 28873.4 29045.1 29083.0 29224.7 29843.9 3765.5 3818.4 3818.5 3939.8 3981.1 242781.0 243102.0 243322.8 243623.0 244600.0 55106.0 55521.7 56694.0 58234.0 58749.4 52087.7 52237.5 52451.2 52458.0 52597.7 13255.2 13258.0 13411.1 13417.3 13450.1 10387.5 10462.0 10474.9 10498.1 10508.9 19375.4 19488.3 19497.5 19510.5 19570.7 7727.2 7850.3 8002.3 8002.4 8007.8 966.7 981.8 985.5 1039.7 1043.2 24723.0 25067.0 25091.1 25121.7 25147.0 1036.3 1065.9 1077.5 1135.7 1186.0 12955.5 13213.7 13219.3 13345.9 13397.3 292795.0 296304.0 296624.0 296806.0 297092.0 48366.0 48379.5 48455.5 48602.0 48610.0 1280.8 1358.7 1358.8 1359.6 1372.3 24748.8 25895.0 25939.1 25944.5 26084.5 41097.8 41333.8 41530.4 41598.0 41653.2 279760.0 280571.0 281354.0 282098.0 282316.0 30262.5 30879.0 31327.5 31380.8 31467.2 36758.3 37170.0 37264.1 37402.8 37482.6 30228.8 33803.3 33894.7 35421.1 38479.4 33884.8 33937.3 34194.3 34427.5 34544.7 34085.3 34160.1 34184.3 34243.8 34384.7 29189.4 30842.8 31154.2 31180.5 31308.0 23996.2 24279.8 24320.0 24713.4 24754.6 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27814.47 Median:2185.25 stddev:83215.69 (3808.1%) stddev-sans-first:83423.59 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: Values: 356.3 359.1 360.2 360.4 365.9 14199.4 14318.4 16600.1 16725.2 17394.8 16385.8 16718.5 17361.4 17718.4 17718.6 5406.2 5502.5 5562.6 5567.4 5570.5 5003.7 5100.7 5128.3 5131.9 5547.7 5015.3 5039.2 5087.7 5119.4 5517.4 5499.8 5510.5 5524.2 5535.9 5538.5 5212.4 5272.0 5283.7 5315.0 5432.8 12740.7 12940.2 13024.3 13394.9 20903.2 2048.4 2114.1 2184.3 2186.2 2255.5 9892.4 10009.0 12722.9 13097.6 13173.7 1798.3 1862.6 1887.2 1903.6 1908.0 61213.4 63060.7 63658.0 64090.7 64730.7 10057.6 10232.9 10329.9 10400.4 10427.8 13547.7 13714.9 13766.4 13773.0 13773.7 6840.3 6932.4 7029.6 7132.4 7139.0 6634.3 6642.9 6644.0 6660.6 6706.6 4341.8 4419.5 4443.9 4482.3 4524.0 1857.5 1857.8 1868.4 1881.0 1895.7 836.5 874.4 928.4 939.1 952.2 16048.8 16149.5 16380.9 16388.2 16755.2 886.1 914.5 915.6 918.4 968.8 9145.6 9213.7 9221.8 9324.0 9451.4 489282.0 504681.0 505348.0 506776.0 535779.0 77783.2 77893.2 78940.0 79572.2 80604.0 1079.6 1082.8 1094.9 1098.4 1102.2 14851.8 14917.1 14936.8 15001.3 15221.0 35305.0 35370.0 36750.4 36822.0 36897.4 46789.0 47091.0 47761.5 48235.8 50682.5 14243.4 14258.0 14653.8 14678.7 14741.0 11260.5 11438.5 11587.9 11678.2 18070.7 10231.6 10324.8 10367.5 10398.0 10418.7 9703.1 10055.0 10314.5 10403.8 10408.4 10161.3 10166.3 10184.2 10229.3 10324.9 14525.0 14928.3 14963.7 15041.2 15063.9 14276.9 15273.4 15635.5 15640.0 15866.4 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,524 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4868.76 Median:6171.46 stddev:2490.35 (40.4%) stddev-sans-first:2484.53 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: Values: 1507.3 1653.0 1708.4 1712.8 1729.7 6054.0 6377.5 6955.8 7126.6 7148.7 6753.9 6848.5 6900.4 6914.9 6975.4 2082.2 2129.6 2132.4 2136.8 2137.5 2092.4 2102.7 2125.7 2134.8 2149.1 2041.9 2047.9 2055.5 2062.6 2071.9 2104.8 2134.2 2136.1 2138.5 2140.5 2067.2 2067.4 2094.7 2100.9 2101.2 6895.4 6936.0 6964.8 6967.8 6988.2 4086.5 4166.6 4167.3 4180.9 4180.9 2630.7 2691.3 2712.5 2743.7 2750.5 888.2 898.0 909.7 928.5 943.4 9082.1 9088.7 9096.5 9098.1 9110.2 3578.4 3619.7 3669.8 3674.3 3701.6 7893.8 7927.0 7957.9 7960.2 7969.1 4186.8 4199.6 4202.0 4203.0 4206.9 3785.6 3851.7 3853.5 3873.1 3887.3 3130.9 3134.4 3157.2 3158.4 3176.5 894.7 895.8 901.2 902.7 922.9 1288.6 1290.7 1293.5 1342.4 1349.8 6561.4 6657.1 6689.8 6694.3 6710.4 436.3 437.6 441.9 442.6 445.5 5255.9 5279.6 5305.7 5315.5 5328.9 8100.1 8127.8 8240.3 8377.7 8400.4 8073.4 8109.1 8167.8 8171.1 8213.8 6558.1 6691.9 6700.7 6723.0 6756.3 6419.9 6469.9 6475.4 6479.1 6480.5 7277.0 7460.2 7480.8 7503.6 7559.7 7559.8 7620.9 7621.7 7639.5 7667.5 5464.4 5539.0 6056.8 6129.7 6130.6 6599.6 6674.6 6685.7 6692.1 6734.6 6315.6 6336.5 6385.5 6457.2 6466.6 6212.3 6329.3 6353.3 6381.5 6427.5 6378.7 6417.7 6420.3 6452.8 6461.3 5936.0 5949.8 5951.7 5979.3 5993.7 5891.7 5954.9 5983.9 5995.8 6003.5 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5606.46 Median:6340.22 stddev:1651.71 (26.1%) stddev-sans-first:1608.26 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: Values: 335.2 337.0 342.9 343.2 346.6 6145.7 6292.9 6313.0 6325.9 6359.8 6113.8 6241.5 6242.6 6255.7 6268.9 4841.2 4842.2 4853.6 4855.1 4885.3 4882.3 4888.9 4894.9 4904.2 4905.2 4763.7 4861.7 4865.9 4866.8 4873.9 4840.2 4840.9 4853.5 4854.4 4864.2 4279.6 4286.1 4293.4 4295.0 4301.2 5888.4 6005.0 6006.7 6012.2 6013.2 5678.2 5678.6 5682.3 5691.0 5694.9 6637.6 6663.9 6664.6 6665.8 6673.2 4737.4 4760.5 4768.6 4770.0 4773.8 6780.1 6912.2 6920.4 6945.7 6962.5 4883.3 4888.7 4888.8 4890.3 4891.8 4940.1 4943.3 4947.1 4958.6 4960.3 5503.3 5553.6 5606.0 5609.9 5638.9 6701.6 6702.8 6709.5 6709.8 6749.7 1705.5 1722.1 1757.3 1758.4 1760.3 1156.5 1184.2 1191.0 1198.6 1201.3 6342.1 6361.8 6364.3 6364.6 6370.9 6854.0 6879.4 6880.4 6928.7 6941.0 6261.3 6338.4 6368.4 6373.1 6375.3 3733.9 3778.0 3783.5 3785.5 3787.7 6971.6 7000.9 7022.9 7023.6 7034.3 6879.8 7085.4 7091.0 7105.7 7109.6 7011.9 7017.8 7022.8 7024.3 7034.9 6900.4 6918.9 6932.6 6937.9 6950.4 6673.1 6781.2 6784.9 6824.4 6831.9 6942.1 7012.5 7018.9 7022.5 7037.3 4900.4 4915.7 4916.7 4917.3 4919.8 6747.4 6867.5 6889.7 6895.3 6905.1 6688.4 6697.1 6702.2 6703.8 6712.4 6678.0 6694.0 6697.4 6701.6 6721.1 6343.7 6508.4 6537.5 6570.6 6580.7 6691.5 6709.1 6735.5 6739.8 6757.8 6590.9 6593.1 6593.2 6597.2 6609.0 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: 05:28:04 INFO - 2015-12-21 05:28:04,526 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13862.87 Median:47696.28 stddev:16349.49 (34.3%) stddev-sans-first:16365.72 05:28:04 INFO - 2015-12-21 05:28:04,528 DEBUG : BROWSER_OUTPUT: Values: 753.0 792.7 820.7 834.6 845.2 13003.4 13112.3 13212.0 13244.3 13281.2 12014.7 12399.0 12510.4 12565.5 12584.2 663.3 780.0 797.3 799.6 800.3 781.2 790.9 791.1 797.6 798.6 823.0 827.8 834.7 835.6 837.0 817.7 823.7 824.1 827.7 828.6 607.1 611.6 615.1 615.2 615.9 12969.1 13005.9 13040.7 13051.7 13099.9 11659.9 12201.1 12626.2 13001.7 13002.3 10453.2 10468.3 10478.2 10518.5 10687.7 3540.5 3651.0 3681.7 3699.2 3734.1 43832.3 44144.8 44155.0 44330.6 44443.0 9757.0 9914.2 9980.0 10113.3 10459.2 17405.8 17460.4 17564.6 17588.5 17610.0 7332.2 7537.1 7586.5 7589.1 7673.2 3981.9 4004.1 4025.2 4074.5 4101.5 2955.3 2957.8 2977.6 2981.7 2983.2 523.8 535.1 538.7 543.0 548.4 579.6 591.1 597.9 645.7 664.6 7940.0 8089.8 8304.2 8331.5 8414.5 500.6 518.0 634.4 637.7 649.2 3637.7 3931.5 3986.0 4038.3 4164.2 59548.1 59838.4 59885.0 59979.0 60140.0 47692.3 48468.5 48700.0 48956.8 54110.7 50244.5 51013.0 51171.0 52145.0 52367.7 8600.2 8727.0 8781.3 8824.2 8881.7 36783.6 37427.3 37540.0 37669.4 37795.8 45294.5 46800.1 47041.3 47545.3 47700.3 11207.4 11464.2 11506.5 11523.8 11709.6 12289.3 12393.8 12417.9 12627.6 12823.9 10666.6 10988.0 11039.8 11094.9 11671.8 10864.3 10926.5 11063.3 11387.5 11461.3 9995.2 9997.4 10197.2 10216.8 10254.0 8965.2 9193.4 9234.9 9241.3 9695.6 8863.6 8965.4 9344.3 9404.9 10193.3 05:28:04 INFO - 2015-12-21 05:28:04,528 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:28:04 INFO - 2015-12-21 05:28:04,528 DEBUG : BROWSER_OUTPUT: 05:28:05 INFO - 2015-12-21 05:28:05,201 INFO : Browser exited with error code: 0 05:28:05 INFO - 2015-12-21 05:28:05,220 INFO : Completed test dromaeo_css (00:18:30) 05:28:05 INFO - 2015-12-21 05:28:05,220 INFO : Starting test kraken 05:28:05 INFO - 2015-12-21 05:28:05,220 DEBUG : operating with platform_type : linux_ 05:28:05 INFO - 2015-12-21 05:28:05,220 INFO : Initialising browser for kraken test... 05:28:05 INFO - 2015-12-21 05:28:05,226 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1vFyAV/profile http://localhost:34912/getInfo.html 05:28:09 INFO - 2015-12-21 05:28:09,067 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:28:09 INFO - 2015-12-21 05:28:09,999 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:28:09 INFO - 2015-12-21 05:28:09,999 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:28:09 INFO - 2015-12-21 05:28:09,999 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:28:10 INFO - 2015-12-21 05:28:09,999 DEBUG : BROWSER_OUTPUT: __metrics 05:28:10 INFO - 2015-12-21 05:28:10,100 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:28:11 INFO - 2015-12-21 05:28:11,037 INFO : Browser initialized. 05:28:11 INFO - 2015-12-21 05:28:11,038 INFO : Running cycle 1/1 for kraken test... 05:28:11 INFO - 2015-12-21 05:28:11,038 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1vFyAV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:28:22 INFO - 2015-12-21 05:28:22,843 DEBUG : BROWSER_OUTPUT: RSS: Main: 151015424 05:28:22 INFO - 2015-12-21 05:28:22,844 DEBUG : BROWSER_OUTPUT: 05:28:25 INFO - 2015-12-21 05:28:25,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34912/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:25 INFO - 2015-12-21 05:28:25,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 153133056 05:28:25 INFO - 2015-12-21 05:28:25,938 DEBUG : BROWSER_OUTPUT: 05:28:28 INFO - 2015-12-21 05:28:28,559 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34912/tests/kraken/driver.html?testName=audio-dft) 05:28:29 INFO - 2015-12-21 05:28:29,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 152014848 05:28:29 INFO - 2015-12-21 05:28:29,154 DEBUG : BROWSER_OUTPUT: 05:28:32 INFO - 2015-12-21 05:28:32,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34912/tests/kraken/driver.html?testName=audio-fft) 05:28:32 INFO - 2015-12-21 05:28:32,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 145620992 05:28:32 INFO - 2015-12-21 05:28:32,620 DEBUG : BROWSER_OUTPUT: 05:28:34 INFO - 2015-12-21 05:28:34,713 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34912/tests/kraken/driver.html?testName=audio-oscillator) 05:28:35 INFO - 2015-12-21 05:28:35,314 DEBUG : BROWSER_OUTPUT: RSS: Main: 146104320 05:28:35 INFO - 2015-12-21 05:28:35,314 DEBUG : BROWSER_OUTPUT: 05:28:37 INFO - 2015-12-21 05:28:37,515 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:38 INFO - 2015-12-21 05:28:38,101 DEBUG : BROWSER_OUTPUT: RSS: Main: 146472960 05:28:38 INFO - 2015-12-21 05:28:38,101 DEBUG : BROWSER_OUTPUT: 05:28:41 INFO - 2015-12-21 05:28:41,059 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34912/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:41 INFO - 2015-12-21 05:28:41,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 146591744 05:28:41 INFO - 2015-12-21 05:28:41,640 DEBUG : BROWSER_OUTPUT: 05:28:45 INFO - 2015-12-21 05:28:45,046 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34912/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:45 INFO - 2015-12-21 05:28:45,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 148176896 05:28:45 INFO - 2015-12-21 05:28:45,640 DEBUG : BROWSER_OUTPUT: 05:28:48 INFO - 2015-12-21 05:28:48,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34912/tests/kraken/driver.html?testName=json-parse-financial) 05:28:49 INFO - 2015-12-21 05:28:49,022 DEBUG : BROWSER_OUTPUT: RSS: Main: 148340736 05:28:49 INFO - 2015-12-21 05:28:49,023 DEBUG : BROWSER_OUTPUT: 05:28:50 INFO - 2015-12-21 05:28:50,603 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:51 INFO - 2015-12-21 05:28:51,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 149483520 05:28:51 INFO - 2015-12-21 05:28:51,184 DEBUG : BROWSER_OUTPUT: 05:28:52 INFO - 2015-12-21 05:28:52,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:53 INFO - 2015-12-21 05:28:53,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 149770240 05:28:53 INFO - 2015-12-21 05:28:53,598 DEBUG : BROWSER_OUTPUT: 05:28:55 INFO - 2015-12-21 05:28:55,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:28:56 INFO - 2015-12-21 05:28:56,238 DEBUG : BROWSER_OUTPUT: RSS: Main: 149897216 05:28:56 INFO - 2015-12-21 05:28:56,238 DEBUG : BROWSER_OUTPUT: 05:28:58 INFO - 2015-12-21 05:28:58,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:28:59 INFO - 2015-12-21 05:28:59,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 149725184 05:28:59 INFO - 2015-12-21 05:28:59,260 DEBUG : BROWSER_OUTPUT: 05:29:01 INFO - 2015-12-21 05:29:01,887 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:02 INFO - 2015-12-21 05:29:02,475 DEBUG : BROWSER_OUTPUT: RSS: Main: 149487616 05:29:02 INFO - 2015-12-21 05:29:02,475 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34912/tests/kraken/driver.html?testName=ai-astar) 05:29:04 INFO - 2015-12-21 05:29:04,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 149520384 05:29:04 INFO - 2015-12-21 05:29:04,415 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,418 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:29:04 INFO - 2015-12-21 05:29:04,418 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:29:04 INFO - 2015-12-21 05:29:04,419 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:29:04 INFO - 2015-12-21 05:29:04,419 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:29:04 INFO - 2015-12-21 05:29:04,419 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;106;114;113;113;122;113;115;113;110 05:29:04 INFO - 2015-12-21 05:29:04,419 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;125;149;160;128;151;151;131;167;127 05:29:04 INFO - 2015-12-21 05:29:04,420 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;162;163;161;164;164;166;166;162 05:29:04 INFO - 2015-12-21 05:29:04,420 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;74;72;71;72;72;137;76;71 05:29:04 INFO - 2015-12-21 05:29:04,420 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;132;102;93;93;135;93;94;135 05:29:04 INFO - 2015-12-21 05:29:04,420 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;109;110;110;110;110;110;110;109 05:29:04 INFO - 2015-12-21 05:29:04,420 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;181;146;146;147;146;147;147 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;96;98;96;96;96;95;96 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;63;63;62;63;62;62;63;61;61 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;67;65;66;67;67;67;66;66;67 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;81;84;84;85;85;87;84;85;83 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;126;117;121;119;127;117;127;116;121 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;155;156;159;148;155;158;152;153;144 05:29:04 INFO - 2015-12-21 05:29:04,421 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;71;69;67;71;66;73;67;65;67 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,730 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: __startTimestamp1450704544416__endTimestamp 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,422 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:29:04 INFO - 2015-12-21 05:29:04,423 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.20 Median:113.00 stddev:3.99 (3.5%) stddev-sans-first:4.24 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: Values: 113.0 106.0 114.0 113.0 113.0 122.0 113.0 115.0 113.0 110.0 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.30 Median:151.00 stddev:15.21 (10.1%) stddev-sans-first:15.72 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: Values: 154.0 125.0 149.0 160.0 128.0 151.0 151.0 131.0 167.0 127.0 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,424 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:1.65 (1.0%) stddev-sans-first:1.74 05:29:04 INFO - 2015-12-21 05:29:04,425 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 162.0 163.0 161.0 164.0 164.0 166.0 166.0 162.0 05:29:04 INFO - 2015-12-21 05:29:04,425 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,425 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.70 Median:72.00 stddev:20.55 (28.5%) stddev-sans-first:21.61 05:29:04 INFO - 2015-12-21 05:29:04,425 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 74.0 72.0 71.0 72.0 72.0 137.0 76.0 71.0 05:29:04 INFO - 2015-12-21 05:29:04,426 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,426 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:93.00 stddev:19.25 (20.7%) stddev-sans-first:19.89 05:29:04 INFO - 2015-12-21 05:29:04,426 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 132.0 102.0 93.0 93.0 135.0 93.0 94.0 135.0 05:29:04 INFO - 2015-12-21 05:29:04,426 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,426 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:29:04 INFO - 2015-12-21 05:29:04,427 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 05:29:04 INFO - 2015-12-21 05:29:04,427 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,427 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:149.90 Median:147.00 stddev:10.94 (7.4%) stddev-sans-first:11.51 05:29:04 INFO - 2015-12-21 05:29:04,427 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 181.0 146.0 146.0 147.0 146.0 147.0 147.0 05:29:04 INFO - 2015-12-21 05:29:04,428 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,428 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 05:29:04 INFO - 2015-12-21 05:29:04,428 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 96.0 98.0 96.0 96.0 96.0 95.0 96.0 05:29:04 INFO - 2015-12-21 05:29:04,428 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,428 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 05:29:04 INFO - 2015-12-21 05:29:04,429 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 62.0 63.0 62.0 62.0 63.0 61.0 61.0 05:29:04 INFO - 2015-12-21 05:29:04,429 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,429 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:67.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 05:29:04 INFO - 2015-12-21 05:29:04,429 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 65.0 66.0 67.0 67.0 67.0 66.0 66.0 67.0 05:29:04 INFO - 2015-12-21 05:29:04,430 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,430 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.50 stddev:1.70 (2.0%) stddev-sans-first:1.64 05:29:04 INFO - 2015-12-21 05:29:04,430 DEBUG : BROWSER_OUTPUT: Values: 82.0 81.0 84.0 84.0 85.0 85.0 87.0 84.0 85.0 83.0 05:29:04 INFO - 2015-12-21 05:29:04,430 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,431 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.50 Median:121.00 stddev:4.77 (3.9%) stddev-sans-first:4.44 05:29:04 INFO - 2015-12-21 05:29:04,431 DEBUG : BROWSER_OUTPUT: Values: 114.0 126.0 117.0 121.0 119.0 127.0 117.0 127.0 116.0 121.0 05:29:04 INFO - 2015-12-21 05:29:04,431 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,431 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:155.00 stddev:5.97 (3.9%) stddev-sans-first:4.80 05:29:04 INFO - 2015-12-21 05:29:04,432 DEBUG : BROWSER_OUTPUT: Values: 141.0 155.0 156.0 159.0 148.0 155.0 158.0 152.0 153.0 144.0 05:29:04 INFO - 2015-12-21 05:29:04,432 DEBUG : BROWSER_OUTPUT: 05:29:04 INFO - 2015-12-21 05:29:04,432 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:70.00 stddev:2.67 (3.8%) stddev-sans-first:2.70 05:29:04 INFO - 2015-12-21 05:29:04,432 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 69.0 67.0 71.0 66.0 73.0 67.0 65.0 67.0 05:29:04 INFO - 2015-12-21 05:29:04,432 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:29:04 INFO - 2015-12-21 05:29:04,433 DEBUG : BROWSER_OUTPUT: 05:29:05 INFO - 2015-12-21 05:29:05,289 INFO : Browser exited with error code: 0 05:29:05 INFO - 2015-12-21 05:29:05,347 INFO : Completed test kraken (00:01:00) 05:29:05 INFO - 2015-12-21 05:29:05,347 INFO : Starting test v8_7 05:29:05 INFO - 2015-12-21 05:29:05,347 DEBUG : operating with platform_type : linux_ 05:29:05 INFO - 2015-12-21 05:29:05,348 INFO : Initialising browser for v8_7 test... 05:29:05 INFO - 2015-12-21 05:29:05,354 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyz3_Ug/profile http://localhost:34912/getInfo.html 05:29:09 INFO - 2015-12-21 05:29:09,231 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:29:10 INFO - 2015-12-21 05:29:10,227 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:10 INFO - 2015-12-21 05:29:10,227 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:10 INFO - 2015-12-21 05:29:10,227 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:29:10 INFO - 2015-12-21 05:29:10,227 DEBUG : BROWSER_OUTPUT: __metrics 05:29:10 INFO - 2015-12-21 05:29:10,396 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:29:10 INFO - 2015-12-21 05:29:10,558 DEBUG : BROWSER_OUTPUT: [Parent 29920] WARNING: pipe error: Broken pipe: file /builds/slave/fx-team-l64-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 760 05:29:11 INFO - 2015-12-21 05:29:11,293 INFO : Browser initialized. 05:29:11 INFO - 2015-12-21 05:29:11,294 INFO : Running cycle 1/1 for v8_7 test... 05:29:11 INFO - 2015-12-21 05:29:11,294 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyz3_Ug/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:23 INFO - 2015-12-21 05:29:23,031 DEBUG : BROWSER_OUTPUT: RSS: Main: 150896640 05:29:23 INFO - 2015-12-21 05:29:23,031 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,400 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34912/tests/v8_7/run.html (next: http://localhost:34912/tests/v8_7/run.html) 05:29:44 INFO - 2015-12-21 05:29:44,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 147193856 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: |0;Richards;164.74464579901152 05:29:44 INFO - 2015-12-21 05:29:44,651 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;187.82870022539444 05:29:44 INFO - 2015-12-21 05:29:44,652 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.0336040324839 05:29:44 INFO - 2015-12-21 05:29:44,652 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 05:29:44 INFO - 2015-12-21 05:29:44,652 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 05:29:44 INFO - 2015-12-21 05:29:44,652 DEBUG : BROWSER_OUTPUT: |5;Earley;703.2348804500704 05:29:44 INFO - 2015-12-21 05:29:44,652 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: |7;RegExp;25200 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: |8;Splay;702.3809523809524 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: __startTimestamp1450704584649__endTimestamp 05:29:44 INFO - 2015-12-21 05:29:44,653 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: Values: 164.7 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,654 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:187.83 Median:187.83 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,656 DEBUG : BROWSER_OUTPUT: Values: 187.8 05:29:44 INFO - 2015-12-21 05:29:44,656 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,656 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.03 Median:280.03 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,656 DEBUG : BROWSER_OUTPUT: Values: 280.0 05:29:44 INFO - 2015-12-21 05:29:44,656 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: Values: 5122.4 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: Values: 1062.7 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:703.23 Median:703.23 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: Values: 703.2 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: Values: 12219.5 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,657 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25200.00 Median:25200.00 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: Values: 25200.0 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:702.38 Median:702.38 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: Values: 702.4 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: Values: 4826.9 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:29:44 INFO - 2015-12-21 05:29:44,658 DEBUG : BROWSER_OUTPUT: 05:29:45 INFO - 2015-12-21 05:29:45,545 INFO : Browser exited with error code: 0 05:29:45 INFO - 2015-12-21 05:29:45,560 INFO : Completed test v8_7 (00:00:40) 05:29:45 INFO - 2015-12-21 05:29:45,684 INFO : Completed test suite (00:20:10) 05:29:45 INFO - 2015-12-21 05:29:45,684 DEBUG : Working with test: dromaeo_css 05:29:45 INFO - 2015-12-21 05:29:45,684 DEBUG : Generating results file: dromaeo_css 05:29:45 INFO - 2015-12-21 05:29:45,685 DEBUG : Working with test: kraken 05:29:45 INFO - 2015-12-21 05:29:45,685 DEBUG : Generating results file: kraken 05:29:45 INFO - 2015-12-21 05:29:45,686 INFO : javascript benchmark 05:29:45 INFO - 2015-12-21 05:29:45,686 DEBUG : Working with test: v8_7 05:29:45 INFO - 2015-12-21 05:29:45,686 DEBUG : Generating results file: v8_7 05:29:45 INFO - 2015-12-21 05:29:45,686 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:45 INFO - 2015-12-21 05:29:45,735 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 05:29:45 INFO - 2015-12-21 05:29:45,735 DEBUG : process_Request line: dromaeo_css 6827.61 graph.html#tests=[[72,64,43]] 05:29:45 INFO - 2015-12-21 05:29:45,736 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:45 INFO - 2015-12-21 05:29:45,768 DEBUG : process_Request line: kraken 1515.90 graph.html#tests=[[232,64,43]] 05:29:45 INFO - 2015-12-21 05:29:45,768 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:45 INFO - 2015-12-21 05:29:45,796 DEBUG : process_Request line: v8_7 21160.74 graph.html#tests=[[230,64,43]] 05:29:45 INFO - 2015-12-21 05:29:45,798 INFO : javascript benchmark 05:29:45 INFO - 2015-12-21 05:29:45,801 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3157.3211759471715, 3210.5184222366643, 3230.2085988932713, 3258.5438038674574, 3260.1197676627303, 5191.0, 5299.205119232112, 5369.114096174131, 5377.838664840051, 5383.1656093305555, 5189.532942035216, 5266.4733526647415, 5302.973485132578, 5307.840764777054, 5408.999335123005, 4839.854804355866, 4895.36360273164, 4897.706137631753, 4942.728149951751, 4992.87517812054, 4866.343043689109, 4884.829030983917, 4918.827841025565, 4935.136351138554, 4958.404991401019, 4722.338872557844, 4743.857684269477, 4824.276358546229, 4875.536824001714, 4883.291922671228, 4886.047220791947, 4889.511048895118, 4900.436449808282, 4917.950820491785, 4944.0, 4678.134545109166, 4892.266160075981, 4892.8532144035735, 4897.730624815634, 4905.779239934212, 5021.422536408323, 5025.773840177164, 5047.242913562958, 5117.1556693145585, 5135.460776618439, 5634.887302253932, 5661.490465858093, 5665.2351932489, 5667.574931880125, 5688.658680479184, 5655.717214139277, 5672.546196304285, 5680.233168522238, 5689.516391106761, 5729.312482502085, 5369.838904832861, 5389.51494365509, 5424.321959755031, 5469.562435005229, 5526.834194974117, 5859.414058594191, 5896.086106653475, 5908.05471124618, 5925.674087925119, 5937.465628093492, 5541.5843811714285, 5640.069388550925, 5712.971435142797, 5728.77084916607, 5756.798512052058, 5798.710064496759, 5852.414758524199, 5853.765849366063, 5865.736041878125, 5906.173135760997, 5660.2075709400715, 5701.543876489871, 5710.8001219957105, 5716.114002329646, 5719.28508936383, 5474.206240095244, 5577.8047768327915, 5634.830955071355, 5698.401667824902, 5708.372079071299, 4443.600075993176, 4454.955450445519, 4456.46522417312, 4468.396766436489, 4493.0, 3972.106276087857, 3973.1060511385285, 3976.0855003349393, 3998.080441498472, 4011.919761604752, 5241.397239317527, 5313.442088580643, 5362.436944120889, 5493.038718224375, 5519.69641669704, 5823.359430462646, 5827.650340979555, 5829.47534721877, 5834.708264586839, 5836.533077353802, 5472.972635136878, 5478.835634930959, 5489.752961116679, 5492.5605951523785, 5508.807191748269, 4861.489543597942, 4898.877528061756, 4953.950460495349, 4975.477574854661, 5029.622778291569, 5737.454941780524, 5772.480476757028, 5776.018076926933, 5795.159701843209, 5798.768049277982, 5537.473939975695, 5719.942800571941, 5722.2561067061015, 5727.799527016535, 5730.054541000688, 5669.376368599534, 5814.04068328721, 5905.704714764159, 5914.526837853047, 5936.643801371932, 5701.515371193495, 5725.2843394575675, 5726.455986681259, 5731.0, 5757.625754325955, 5552.194931734795, 5635.351934527582, 5768.567357448124, 5841.18223448709, 5842.474177324061, 5614.07367784311, 5678.602497825113, 5680.630759000652, 5688.40271771458, 5725.570582206268, 5675.545956323567, 5682.744276507485, 5694.886102277848, 5698.800541981011, 5716.256886604715, 5325.680459172462, 5398.0, 5412.918806217831, 5566.860828479158, 5620.044592419216, 5692.459216374438, 5696.487316141402, 5697.572682048946, 5717.599768016199, 5723.656580605177, 5513.338399392099, 5543.639663422026, 5544.251526044094, 5562.860928476821, 5584.497395234448], "unit": "score", "name": "dojo.html", "value": 5290.272575534634}, {"lowerIsBetter": false, "replicates": [521.8669239343969, 569.4391024840534, 570.221647451229, 577.1919312961852, 585.7686213945489, 28879.27801804956, 37850.81074594626, 37916.43125353122, 38222.42666360003, 38316.0, 40463.0, 41088.0, 41154.794226028826, 41171.58828411715, 41202.587974120324, 8620.525871077089, 8772.69295574655, 8798.780030499256, 8802.295816334678, 8827.911720882805, 8608.784780380478, 8707.434016788888, 8721.825563488726, 8723.30213582915, 8726.127387261287, 8699.347548933823, 8707.477551346909, 8718.564071796416, 8756.737297881074, 8768.34237432192, 8688.695895643654, 8699.60851761669, 8755.868661970075, 8778.29773618109, 8796.34027447944, 8571.400001999862, 8673.869891951626, 8675.783105422352, 8685.348598855111, 8691.217790398894, 38263.617363826284, 38853.22293554142, 38891.611083889366, 38938.41592376117, 39127.60872391268, 3257.397381484433, 3400.540926974876, 3413.5903691557173, 3746.400575907842, 3876.0503676599133, 28873.42253154925, 29045.12864614065, 29083.0, 29224.70775292241, 29843.850780746176, 3765.548134223883, 3818.4081467372603, 3818.522684664417, 3939.822707978119, 3981.124152686404, 242781.0, 243102.0, 243322.7833860837, 243623.0, 244600.0, 55106.0, 55521.72239138778, 56694.0, 58234.0, 58749.41250587525, 52087.739561301954, 52237.47762522326, 52451.21323180155, 52458.0, 52597.7370113147, 13255.204687718768, 13258.0, 13411.061225714106, 13417.262050587116, 13450.12574182675, 10387.532561034772, 10462.0, 10474.947625261824, 10498.055175034284, 10508.947455262674, 19375.4187374379, 19488.309706758075, 19497.545596040767, 19510.488461942256, 19570.70643940342, 7727.227277272228, 7850.293473587404, 8002.279794818494, 8002.3598112150885, 8007.839843203104, 966.709987003896, 981.7790997025612, 985.5072463768116, 1039.6517166749072, 1043.222799014739, 24723.0, 25067.0, 25091.121810736902, 25121.748782512306, 25147.0, 1036.2538971940191, 1065.9360438373722, 1077.4612693653173, 1135.6593022093339, 1185.9703507412212, 12955.481780728665, 13213.73572528544, 13219.339033048309, 13345.866541334657, 13397.263150526815, 292795.0, 296304.0, 296624.0, 296806.0, 297092.0, 48366.0, 48379.516204838204, 48455.515444845805, 48602.0, 48610.0, 1280.8462984441926, 1358.6942937838908, 1358.7825947848296, 1359.6396954806985, 1372.3275594958598, 24748.752512474646, 25895.0, 25939.0921317753, 25944.48111037731, 26084.478310433304, 41097.794511027256, 41333.79333103315, 41530.37704434375, 41598.0, 41653.16693666049, 279760.0, 280571.0, 281354.0, 282098.0, 282316.0, 30262.54606180953, 30879.0, 31327.53008704917, 31380.843095784374, 31467.213319667193, 36758.26483470369, 37170.0, 37264.06839829026, 37402.81298593598, 37482.625173749, 30228.84885575558, 33803.323933521686, 33894.66105338915, 35421.114472138404, 38479.422808658455, 33884.830575846965, 33937.32125357528, 34194.316113678084, 34427.483587746705, 34544.654553454144, 34085.31829363448, 34160.14599635029, 34184.316313674084, 34243.82878085594, 34384.65615343815, 29189.41621167607, 30842.84578577093, 31154.22114447157, 31180.532292015185, 31308.0, 23996.160134395213, 24279.75720242775, 24320.0, 24713.382165446008, 24754.628680569447], "unit": "score", "name": "ext.html", "value": 17648.15151673524}, {"lowerIsBetter": false, "replicates": [356.2678695281197, 359.12373807908716, 360.17519879476004, 360.35676317772766, 365.92681463707265, 14199.361028753705, 14318.355673994669, 16600.086995215257, 16725.247363868624, 17394.826051739477, 16385.83614163858, 16718.498445046636, 17361.392351267707, 17718.379856705018, 17718.55703607407, 5406.189071639266, 5502.477264659851, 5562.610617256773, 5567.415421380758, 5570.498655121039, 5003.724795136266, 5100.668456550331, 5128.282040514331, 5131.897362052757, 5547.696291371524, 5015.297858299841, 5039.168537191377, 5087.745612719368, 5119.411267704225, 5517.36550296717, 5499.835004949858, 5510.531604813616, 5524.171374293868, 5535.944640553583, 5538.529225015878, 5212.4266330703595, 5272.0, 5283.656562323476, 5314.97342513285, 5432.782688692448, 12740.745185096246, 12940.15888967214, 13024.348782560834, 13394.933025334909, 20903.163873445043, 2048.365006847867, 2114.1332053858, 2184.2901057156487, 2186.202036256755, 2255.5263394687136, 9892.406455612687, 10009.0, 12722.936385318108, 13097.607071787766, 13173.670658233525, 1798.2716999615177, 1862.590230149365, 1887.1790771014516, 1903.60085337277, 1907.961840763177, 61213.387866121215, 63060.68469657622, 63658.0, 64090.679546602434, 64730.676346618435, 10057.64798232073, 10232.948835255776, 10329.948350258352, 10400.375977441377, 10427.791444171226, 13547.729045419037, 13714.86285137136, 13766.380512876945, 13773.0, 13773.724525509635, 6840.281770414035, 6932.445404367639, 7029.648517574101, 7132.429405647639, 7139.0, 6634.270230274666, 6642.933570664328, 6644.0, 6660.567063140978, 6706.5640733352175, 4341.8480353188115, 4419.49175844782, 4443.86668399942, 4482.282834746425, 4523.977380113079, 1857.4984754116583, 1857.7863545692148, 1868.4207895552422, 1880.9529761756066, 1895.7251298441925, 836.460482988469, 874.4359887872287, 928.4243768863348, 939.1313035442189, 952.2286947572488, 16048.839511604734, 16149.515514534583, 16380.918095409685, 16388.18059097064, 16755.2460139294, 886.0784783824879, 914.4604683236922, 915.5971372596036, 918.4489306416231, 968.801395713877, 9145.588448519833, 9213.723588292361, 9221.81556368869, 9324.0, 9451.393263145144, 489282.0, 504681.0, 505348.0, 506776.0, 535779.0, 77783.16626253053, 77893.22106778972, 78940.0, 79572.21497247684, 80604.0, 1079.5897558927713, 1082.7509672775307, 1094.8686157661132, 1098.3794156301665, 1102.1733699725205, 14851.777223341442, 14917.104973701613, 14936.775948361, 15001.299766042217, 15221.0, 35305.0, 35370.0, 36750.44874326834, 36822.0, 36897.44653830141, 46789.0, 47091.0, 47761.52238477571, 48235.75882120567, 50682.493175069256, 14243.359048843035, 14258.0, 14653.780193297322, 14678.706425871209, 14741.0, 11260.493277802683, 11438.542458301576, 11587.88412115868, 11678.182527223353, 18070.728939066186, 10231.590736370461, 10324.793504130026, 10367.481625918523, 10398.0, 10418.739531511774, 9703.12671859536, 10055.0, 10314.484275786332, 10403.843942341022, 10408.427536485264, 10161.339512931636, 10166.339187952759, 10184.185265178916, 10229.28395012342, 10324.948375258076, 14525.0, 14928.253587320374, 14963.700725985636, 15041.172735499653, 15063.924680376966, 14276.928615356857, 15273.389064437742, 15635.530934071996, 15640.0, 15866.44467443634], "unit": "score", "name": "jquery.html", "value": 8705.586753448757}, {"lowerIsBetter": false, "replicates": [1507.276507276508, 1652.9752053719194, 1708.3593652380357, 1712.8201538838414, 1729.69730297198, 6053.969730151354, 6377.489800815935, 6955.791326260214, 7126.643667816615, 7148.678309476073, 6753.898691519631, 6848.486363522731, 6900.447964162868, 6914.8962765558435, 6975.372216500514, 2082.229575057231, 2129.6273152198305, 2132.4242454537266, 2136.75427325858, 2137.5190582119053, 2092.393205970267, 2102.726645536086, 2125.691774692669, 2134.8185404240658, 2149.0973791007814, 2041.9387418377473, 2047.9488012799725, 2055.537504061582, 2062.5668609592003, 2071.9171233150655, 2104.757952835428, 2134.2210093315985, 2136.134865379524, 2138.5402138540135, 2140.5076832328573, 2067.1627990663656, 2067.431456349501, 2094.748630164375, 2100.9369718908456, 2101.2120454829437, 6895.379415852547, 6936.0, 6964.756233531853, 6967.825804354881, 6988.161420629506, 4086.5096188457574, 4166.625003749647, 4167.312393455076, 4180.91638167238, 4180.937285940713, 2630.658014458127, 2691.286808995618, 2712.5253080710795, 2743.7256274372403, 2750.4636595863813, 888.1873086126187, 898.0, 909.6997990663066, 928.5171710710392, 943.4103685196752, 9082.137196966343, 9088.727338179866, 9096.45421274719, 9098.135677110664, 9110.180083792491, 3578.4274516077303, 3619.6923261523066, 3669.798161101112, 3674.3018826422895, 3701.6113308102263, 7893.802654933673, 7927.048754149424, 7957.920420795834, 7960.243776841191, 7969.083555391088, 4186.769727664947, 4199.559046300094, 4202.0, 4202.957970420318, 4206.915861682749, 3785.5835858055593, 3851.6533511983494, 3853.5183102112237, 3873.0511024798543, 3887.3391523441082, 3130.9373812523622, 3134.420132275536, 3157.194915296631, 3158.38411509758, 3176.47588147953, 894.7294841325333, 895.8163576466809, 901.2294488212484, 902.6930843513237, 922.8707980882682, 1288.5683296095913, 1290.6708789258678, 1293.4761421624257, 1342.3892129081244, 1349.7907824287252, 6561.376669216417, 6657.1012913257055, 6689.799306020827, 6694.297098804653, 6710.396064354231, 436.31448434883345, 437.5646231999181, 441.94696636403836, 442.59281461055906, 445.54554354558815, 5255.894882102337, 5279.551238144801, 5305.708186049805, 5315.548178404802, 5328.893422131613, 8100.068492123363, 8127.796805079802, 8240.258376746122, 8377.70678026266, 8400.369972252105, 8073.434859559775, 8109.148539403395, 8167.795805104801, 8171.10117887032, 8213.79465513367, 6558.114654521579, 6691.933080669131, 6700.698468568926, 6723.0, 6756.324367563303, 6419.903701444388, 6469.932461143859, 6475.449586785176, 6479.092926990131, 6480.546361754632, 7277.0, 7460.179380268275, 7480.812979675552, 7503.624818758931, 7559.735409260649, 7559.811004724926, 7620.923790762243, 7621.69513219465, 7639.465237433326, 7667.539947603162, 5464.371597266207, 5539.0, 6056.8182954509675, 6129.662868542273, 6130.601510901777, 6599.571027883172, 6674.632895190811, 6685.699143538455, 6692.130023096966, 6734.56225345351, 6315.5894866833505, 6336.524760643062, 6385.45723613498, 6457.225132984072, 6466.579672321473, 6212.316645168938, 6329.335419781041, 6353.3011368748475, 6381.521385895983, 6427.517936154901, 6378.681065946591, 6417.679116044273, 6420.3258657842125, 6452.774152904626, 6461.321561236003, 5936.0, 5949.791757288474, 5951.702414879325, 5979.282486101695, 5993.700314984146, 5891.734871930687, 5954.851128721816, 5983.863066017443, 5995.820125396071, 6003.489703375262], "unit": "score", "name": "mootools.html", "value": 3962.746532245112}, {"lowerIsBetter": false, "replicates": [335.2222843004231, 336.95114208439793, 342.8525733934408, 343.1815120936566, 346.6083325841799, 6145.661988590626, 6292.874142517153, 6312.968435157829, 6325.936740632592, 6359.841003974903, 6113.755449782015, 6241.531885108612, 6242.594231374957, 6255.655938923357, 6268.937310626892, 4841.152798260308, 4842.2252439609665, 4853.611711063115, 4855.053264613402, 4885.267209918523, 4882.267659851015, 4888.853334399956, 4894.902101957959, 4904.215325547913, 4905.190643543811, 4763.69036012658, 4861.683990540603, 4865.927011094819, 4866.780994855222, 4873.853784386474, 4840.2255639097575, 4840.903181936359, 4853.466118726938, 4854.368932038848, 4864.173090574611, 4279.593438623326, 4286.057067445188, 4293.377460268243, 4294.957050429487, 4301.204277208726, 5888.440598143169, 6005.039193729026, 6006.669633170174, 6012.18835457212, 6013.248343957005, 5678.205051292823, 5678.574106941955, 5682.261306030231, 5690.971545142289, 5694.943050569483, 6637.601743895333, 6663.900041499382, 6664.566803157779, 6665.833354166185, 6673.165854268216, 4737.431508219036, 4760.547747963938, 4768.61851051915, 4769.976150119227, 4773.80904763809, 6780.08468856703, 6912.170539535237, 6920.377166055079, 6945.7221711132, 6962.512624116314, 4883.267509873547, 4888.731119788375, 4888.82889098887, 4890.315355850184, 4891.755412229374, 4940.061388336205, 4943.332650092263, 4947.060058588857, 4958.628102892298, 4960.330355402047, 5503.284573005483, 5553.583481238923, 5605.990921634145, 5609.8597535061135, 5638.887222255532, 6701.564398314192, 6702.83242918931, 6709.463242940553, 6709.832254193684, 6749.662516874137, 1705.4713038957714, 1722.0786879019915, 1757.2795153986804, 1758.4197214919302, 1760.260690509989, 1156.5200441816553, 1184.1651635597057, 1190.9761804763857, 1198.6404078776507, 1201.3212534917743, 6342.080398342215, 6361.777337793155, 6364.268109167412, 6364.586301890455, 6370.936290637034, 6854.006169105452, 6879.415249703731, 6880.415164710956, 6928.7228510859, 6941.0, 6261.311255761862, 6338.366163383625, 6368.395002474757, 6373.075903993895, 6375.34241097318, 3733.9439908401396, 3777.962220377816, 3783.5459744830246, 3785.470034195215, 3787.715921305858, 6971.616561089088, 7000.859982800417, 7022.92977070233, 7023.578585284797, 7034.331738484836, 6879.828004299932, 7085.397741191953, 7090.964545177344, 7105.680244389015, 7109.608971506616, 7011.89482157768, 7017.789466316019, 7022.824429389306, 7024.332688394595, 7034.859302813915, 6900.413464855544, 6918.896216556655, 6932.584044957117, 6937.895931561132, 6950.443964482728, 6673.065770791996, 6781.152355955505, 6784.864302714017, 6824.351686589766, 6831.863362732618, 6942.097527321416, 7012.474064445085, 7018.929810701828, 7022.54353467023, 7037.331453511908, 4900.362952816254, 4915.729634870117, 4916.729579873141, 4917.262410638433, 4919.803207871645, 6747.392734653905, 6867.484938629723, 6889.655517224019, 6895.34494223048, 6905.136857892763, 6688.3646053624825, 6697.129373181455, 6702.229243637044, 6703.8324041897395, 6712.39588437043, 6677.966610166918, 6693.966530167318, 6697.430718388991, 6701.592665540291, 6721.059051732664, 6343.682815859096, 6508.446782023581, 6537.477001839937, 6570.638614876037, 6580.736770529125, 6691.5315927884585, 6709.060731497497, 6735.461163106842, 6739.831504212434, 6757.797266082025, 6590.934090659032, 6593.0769692244085, 6593.241777195684, 6597.208334999639, 6608.975608780646], "unit": "score", "name": "prototype.html", "value": 5066.936335115729}, {"lowerIsBetter": false, "replicates": [752.9736459223928, 792.7344339646219, 820.7209548753426, 834.6494472321624, 845.2223953962354, 13003.414846331914, 13112.27882466464, 13212.0, 13244.337783110843, 13281.203127812327, 12014.699632509191, 12399.0, 12510.437030333635, 12565.497380104785, 12584.182028168163, 663.300218269724, 780.0288640642408, 797.3222760653456, 799.6121880887765, 800.2597597222575, 781.1953687701676, 790.8576456237874, 791.1455627921831, 797.6131576185545, 798.6246464161835, 822.979425514361, 827.8096037911284, 834.6828205281985, 835.603088532948, 836.9748907532753, 817.7178873288706, 823.7281697039992, 824.0523398092212, 827.6730691376932, 828.6436832162168, 607.104520831774, 611.6085705148708, 615.0804547201917, 615.1541630258395, 615.9445649891486, 12969.092163548556, 13005.934970325088, 13040.739185216242, 13051.738965220737, 13099.86900130996, 11659.941700291529, 12201.145919785618, 12626.24242545441, 13001.739965200737, 13002.349882505838, 10453.163746900305, 10468.267221294513, 10478.214133939984, 10518.526666300035, 10687.732806679818, 3540.451230059345, 3651.0, 3681.668649821529, 3699.223163135745, 3734.066483379155, 43832.3425148623, 44144.779276103414, 44155.0, 44330.55669443296, 44442.99999999968, 9756.975517570694, 9914.206863450907, 9979.950100249598, 10113.342632728996, 10459.163266938627, 17405.82594174042, 17460.38888638917, 17564.5608859777, 17588.472345829647, 17610.0, 7332.156801967735, 7537.095548534101, 7586.544807311579, 7589.1272503661685, 7673.232676732282, 3981.8606348777653, 4004.139110091286, 4025.19496100781, 4074.531428885716, 4101.5488296287385, 2955.3350496138196, 2957.778166637511, 2977.6277965254344, 2981.7465515431, 2983.2392739851634, 523.8428471458548, 535.066309290291, 538.6660270632154, 542.9809956651499, 548.3885467703482, 579.5884921705637, 591.1221835574169, 597.8714576366102, 645.6513482719373, 664.5746722097862, 7940.0, 8089.838203235902, 8304.211099945496, 8331.541765202972, 8414.453060551044, 500.5845148526714, 517.9585633149339, 634.4353525362432, 637.7417146055855, 649.1690635985946, 3637.6907962823457, 3931.5282166140246, 3985.980070099631, 4038.2932986727205, 4164.229617520768, 59548.10677839836, 59838.401615984156, 59885.0, 59979.0, 60140.0, 47692.284615730794, 48468.51531484711, 48700.0, 48956.75521622369, 54110.7294463533, 50244.49755502471, 51013.0, 51171.0, 52145.0, 52367.73816130895, 8600.22597966186, 8726.956365218133, 8781.29749620016, 8824.205821476098, 8881.689140880038, 36783.63216367802, 37427.251454971294, 37540.0, 37669.434958476195, 37795.81102094472, 45294.547054530354, 46800.063998720514, 47041.29438058363, 47545.28682069702, 47700.284495731896, 11207.383593902414, 11464.197506174487, 11506.539738410369, 11523.769524609628, 11709.590164344207, 12289.262644241375, 12393.752124957631, 12417.875821241672, 12627.558035468715, 12823.935880320912, 10666.626668066581, 10988.0, 11039.834402483808, 11094.889051109385, 11671.8328167186, 10864.293820901616, 10926.453677315943, 11063.336199828036, 11387.544498219979, 11461.312321260752, 9995.200383969119, 9997.400155990665, 10197.235207359627, 10216.795664086529, 10254.0, 8965.237954773656, 9193.356465047382, 9234.907650923673, 9241.260699143917, 9695.612175513183, 8863.601137948679, 8965.417247878868, 9344.252459803336, 9404.899301027954, 10193.337433067123], "unit": "score", "name": "yui.html", "value": 6206.6071147911925}], "name": "dromaeo_css", "value": 6827.608132012892}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 106.0, 114.0, 113.0, 113.0, 122.0, 113.0, 115.0, 113.0, 110.0], "unit": "score", "name": "ai-astar", "value": 113.2}, {"lowerIsBetter": true, "replicates": [154.0, 125.0, 149.0, 160.0, 128.0, 151.0, 151.0, 131.0, 167.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 144.3}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 162.0, 163.0, 161.0, 164.0, 164.0, 166.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 74.0, 72.0, 71.0, 72.0, 72.0, 137.0, 76.0, 71.0], "unit": "score", "name": "audio-fft", "value": 78.7}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 132.0, 102.0, 93.0, 93.0, 135.0, 93.0, 94.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 181.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 149.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 98.0, 96.0, 96.0, 96.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0, 63.0, 61.0, 61.0], "unit": "score", "name": "json-parse-financial", "value": 62.3}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 65.0, 66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.5}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 84.0, 84.0, 85.0, 85.0, 87.0, 84.0, 85.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.0}, {"lowerIsBetter": true, "replicates": [114.0, 126.0, 117.0, 121.0, 119.0, 127.0, 117.0, 127.0, 116.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.5}, {"lowerIsBetter": true, "replicates": [141.0, 155.0, 156.0, 159.0, 148.0, 155.0, 158.0, 152.0, 153.0, 144.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.1}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 69.0, 67.0, 71.0, 66.0, 73.0, 67.0, 65.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1515.8999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "Richards", "value": 214.28313999999997}, {"lowerIsBetter": false, "replicates": [187.82870022539444], "unit": "score", "name": "DeltaBlue", "value": 352.01223200000004}, {"lowerIsBetter": false, "replicates": [280.0336040324839], "unit": "score", "name": "Encrypt", "value": 950.5323509999997}, {"lowerIsBetter": false, "replicates": [5122.448979591837], "unit": "score", "name": "Decrypt", "value": 51.96362151394425}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [703.2348804500704], "unit": "score", "name": "Earley", "value": 947.7103860000001}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [25200.0], "unit": "score", "name": "RegExp", "value": 36.150198412698415}, {"lowerIsBetter": false, "replicates": [702.3809523809524], "unit": "score", "name": "Splay", "value": 116.02108474576269}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 21160.74444912318}]} 05:29:45 INFO - RETURN: dromaeo_css: 6827.61 05:29:45 INFO - RETURN: kraken: 1515.90 05:29:45 INFO - RETURN: v8_7: 21160.74 05:29:45 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21160.74"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1515.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "6827.61"}}} 05:29:45 INFO - Return code: 0 05:29:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:45 INFO - # TBPL SUCCESS # 05:29:45 INFO - Running post-action listener: _resource_record_post_action 05:29:45 INFO - Running post-run listener: _resource_record_post_run 05:29:47 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 24576; Write bytes: 566542336; Read time: 328; Write time: 891924 05:29:47 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 4096; Write bytes: 234360832; Read time: 292; Write time: 541136 05:29:47 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 20480; Write bytes: 320790528; Read time: 36; Write time: 347132 05:29:47 INFO - Running post-run listener: _upload_blobber_files 05:29:47 INFO - Blob upload gear active. 05:29:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:29:47 INFO - Copying logs to upload dir... 05:29:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.595863 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-21 05:29:46.510762) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:29:46.514439) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010082 build_url: 'https://queue.taskcluster.net/v1/task/XSDTTqG9TySmHlfwaasy3g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:29:46.564963) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:29:46.565305) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=76a4ffbc7ba64825c1a171dc000001de-1450703268.863992-1120877289 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004605 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:29:46.614971) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 05:29:46.615287) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 05:29:46.615571) ========= ========= Total master_lag: 0.17 =========