builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1450454186.98 results: success (0) buildid: 20151218053000 builduid: d3e9cca2c314464a8c462a073578dd45 revision: 01a10d89e8a145f5f41e0d6f582ef431fe40fdbd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:26.984309) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:26.984792) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:26.985085) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004415 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.17 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.158144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.158501) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.182838) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.183234) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024166 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.232350) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.232670) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.233046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.233321) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 07:56:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2015-12-18 07:56:27 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107396 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.353321) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.353652) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.141121 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:27.505582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 07:56:27.505982) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 01a10d89e8a145f5f41e0d6f582ef431fe40fdbd --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 01a10d89e8a145f5f41e0d6f582ef431fe40fdbd --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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 07:56:27,702 truncating revision to first 12 chars 2015-12-18 07:56:27,702 Setting DEBUG logging. 2015-12-18 07:56:27,702 attempt 1/10 2015-12-18 07:56:27,702 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/01a10d89e8a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 07:56:28,632 unpacking tar archive at: fx-team-01a10d89e8a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.619287 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 07:56:29.138000) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:29.138319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:29.148578) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:29.149651) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 07:56:29.150105) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-18 07:56:29.150410) ========= /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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:56:29 INFO - MultiFileLogger online at 20151218 07:56:29 in /builds/slave/test-pgo 07:56:29 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 07:56:29 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:56:29 INFO - {'append_to_log': False, 07:56:29 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:56:29 INFO - 'blob_upload_branch': 'Fx-Team', 07:56:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:56:29 INFO - 'branch': 'Fx-Team', 07:56:29 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:29 INFO - 'config_files': ('talos/linux_config.py',), 07:56:29 INFO - 'default_actions': ('clobber', 07:56:29 INFO - 'read-buildbot-config', 07:56:29 INFO - 'download-and-extract', 07:56:29 INFO - 'populate-webroot', 07:56:29 INFO - 'create-virtualenv', 07:56:29 INFO - 'install', 07:56:29 INFO - 'run-tests'), 07:56:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:29 INFO - 'download_minidump_stackwalk': True, 07:56:29 INFO - 'download_symbols': 'ondemand', 07:56:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:29 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:29 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:29 INFO - 'installer_path': 'installer.exe', 07:56:29 INFO - 'log_level': 'info', 07:56:29 INFO - 'log_name': 'talos', 07:56:29 INFO - 'log_to_console': True, 07:56:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:56:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:56:29 INFO - 'opt_config_files': (), 07:56:29 INFO - 'pip_index': False, 07:56:29 INFO - 'sps_profile': False, 07:56:29 INFO - 'sps_profile_interval': 0, 07:56:29 INFO - 'suite': 'dromaeojs-e10s', 07:56:29 INFO - 'system_bits': '64', 07:56:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:29 INFO - 'title': 'talos-linux64-ix-015', 07:56:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:29 INFO - 'use_talos_json': True, 07:56:29 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:56:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:29 INFO - 'work_dir': 'build'} 07:56:29 INFO - ##### 07:56:29 INFO - ##### Running clobber step. 07:56:29 INFO - ##### 07:56:29 INFO - Running pre-action listener: _resource_record_pre_action 07:56:29 INFO - Running main action method: clobber 07:56:29 INFO - rmtree: /builds/slave/test-pgo/build 07:56:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:56:30 INFO - Running post-action listener: _resource_record_post_action 07:56:30 INFO - ##### 07:56:30 INFO - ##### Running read-buildbot-config step. 07:56:30 INFO - ##### 07:56:30 INFO - Running pre-action listener: _resource_record_pre_action 07:56:30 INFO - Running main action method: read_buildbot_config 07:56:30 INFO - Using buildbot properties: 07:56:30 INFO - { 07:56:30 INFO - "project": "", 07:56:30 INFO - "product": "firefox", 07:56:30 INFO - "script_repo_revision": "production", 07:56:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 07:56:30 INFO - "repository": "", 07:56:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 07:56:30 INFO - "buildid": "20151218053000", 07:56:30 INFO - "pgo_build": "True", 07:56:30 INFO - "basedir": "/builds/slave/test-pgo", 07:56:30 INFO - "buildnumber": 109, 07:56:30 INFO - "slavename": "talos-linux64-ix-015", 07:56:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:56:30 INFO - "platform": "ubuntu64_hw", 07:56:30 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 07:56:30 INFO - "branch": "fx-team", 07:56:30 INFO - "repo_path": "integration/fx-team", 07:56:30 INFO - "revision": "01a10d89e8a145f5f41e0d6f582ef431fe40fdbd", 07:56:30 INFO - "stage_platform": "linux64-pgo", 07:56:30 INFO - "builduid": "d3e9cca2c314464a8c462a073578dd45", 07:56:30 INFO - "slavebuilddir": "test" 07:56:30 INFO - } 07:56:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 07:56:30 INFO - Running post-action listener: _resource_record_post_action 07:56:30 INFO - ##### 07:56:30 INFO - ##### Running download-and-extract step. 07:56:30 INFO - ##### 07:56:30 INFO - Running pre-action listener: _resource_record_pre_action 07:56:30 INFO - Running main action method: download_and_extract 07:56:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:30 INFO - https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:56:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/test_packages.json 07:56:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/test_packages.json 07:56:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:56:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:56:31 INFO - Downloaded 1302 bytes. 07:56:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:56:31 INFO - Using the following test package requirements: 07:56:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:56:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:56:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'jsshell-linux-x86_64.zip'], 07:56:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:56:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:56:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:56:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:56:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:56:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:56:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:56:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:56:31 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')]} 07:56:31 INFO - https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:56:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:32 INFO - Downloaded 23119752 bytes. 07:56:32 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 07:56:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:32 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 07:56:33 INFO - Return code: 0 07:56:33 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 07:56: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')]} 07:56:33 INFO - https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:56:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:34 INFO - Downloaded 23119752 bytes. 07:56:34 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 07:56:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:56:34 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 07:56:35 INFO - Return code: 0 07:56:35 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')]} 07:56:35 INFO - https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:56:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:56:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:56:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:37 INFO - Downloaded 11132039 bytes. 07:56:37 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 07:56:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:56:37 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 07:56:38 INFO - Return code: 0 07:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:38 INFO - https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:56:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:56:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:56:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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 07:56:39 INFO - Downloaded 54651916 bytes. 07:56:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:56:39 INFO - mkdir: /builds/slave/test-pgo/properties 07:56:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:56:39 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:56:39 INFO - Contents: 07:56:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:56:39 INFO - Running post-action listener: _resource_record_post_action 07:56:39 INFO - Running post-action listener: set_extra_try_arguments 07:56:39 INFO - ##### 07:56:39 INFO - ##### Running populate-webroot step. 07:56:39 INFO - ##### 07:56:39 INFO - Running pre-action listener: _resource_record_pre_action 07:56:39 INFO - Running main action method: populate_webroot 07:56:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:56:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:56:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:56:39 INFO - '--tppagecycles', 07:56:39 INFO - '7'], 07:56:39 INFO - 'tests': ['tsvgm']}}, 07:56:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:56:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:56:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:56:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:56:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'tests': ['damp', 'tps']}, 07:56:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['damp', 'tps']}, 07:56:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:56:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:56:39 INFO - 'other': {'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'other_l64': {'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'other_nol64': {'tests': ['a11yr', 07:56:39 INFO - 'ts_paint', 07:56:39 INFO - 'tpaint', 07:56:39 INFO - 'sessionrestore', 07:56:39 INFO - 'sessionrestore_no_auto_restore']}, 07:56:39 INFO - 'svgr': {'tests': ['tsvgx', 07:56:39 INFO - 'tsvgr_opacity', 07:56:39 INFO - 'tart', 07:56:39 INFO - 'tscrollx', 07:56:39 INFO - 'cart']}, 07:56:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['tsvgx', 07:56:39 INFO - 'tsvgr_opacity', 07:56:39 INFO - 'tart', 07:56:39 INFO - 'tscrollx', 07:56:39 INFO - 'cart']}, 07:56:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'tests': ['tp5o']}, 07:56:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'talos_options': ['--e10s'], 07:56:39 INFO - 'tests': ['tp5o']}, 07:56:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'talos_options': ['--xperf_path', 07:56:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:39 INFO - 'tests': ['tp5n']}, 07:56:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:39 INFO - 'talos_options': ['--e10s', 07:56:39 INFO - '--xperf_path', 07:56:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:39 INFO - 'tests': ['tp5n']}}, 07:56:39 INFO - 'talos.zip': {'path': '', 07:56:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:56:39 INFO - Running post-action listener: _resource_record_post_action 07:56:39 INFO - ##### 07:56:39 INFO - ##### Running create-virtualenv step. 07:56:39 INFO - ##### 07:56:39 INFO - Running pre-action listener: _resource_record_pre_action 07:56:39 INFO - Running main action method: create_virtualenv 07:56:39 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:56:39 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 07:56:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:56:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:56:39 INFO - Using real prefix '/usr' 07:56:39 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:56:40 INFO - Installing distribute.............................................................................................................................................................................................done. 07:56:41 INFO - Installing pip.................done. 07:56:41 INFO - Return code: 0 07:56:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:56: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')]} 07:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:41 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:41 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 07:56:41 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 07:56:41 INFO - Using env: {'DISPLAY': ':0', 07:56:41 INFO - 'HOME': '/home/cltbld', 07:56:41 INFO - 'LANG': 'en_US.UTF-8', 07:56:41 INFO - 'LANGUAGE': 'en_US:en', 07:56:41 INFO - 'LOGNAME': 'cltbld', 07:56:41 INFO - 'MAIL': '/var/mail/cltbld', 07:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:41 INFO - 'MOZ_NO_REMOTE': '1', 07:56:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:41 INFO - 'NO_EM_RESTART': '1', 07:56:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:41 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:41 INFO - 'SHELL': '/bin/bash', 07:56:41 INFO - 'SHLVL': '1', 07:56:41 INFO - 'TERM': 'linux', 07:56:41 INFO - 'TMOUT': '86400', 07:56:41 INFO - 'USER': 'cltbld', 07:56:41 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:41 INFO - '_': '/tools/buildbot/bin/python'} 07:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:42 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 07:56:42 INFO - Cleaning up... 07:56:42 INFO - Return code: 0 07:56:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:56: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')]} 07:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:42 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:42 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 07:56:42 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 07:56:42 INFO - Using env: {'DISPLAY': ':0', 07:56:42 INFO - 'HOME': '/home/cltbld', 07:56:42 INFO - 'LANG': 'en_US.UTF-8', 07:56:42 INFO - 'LANGUAGE': 'en_US:en', 07:56:42 INFO - 'LOGNAME': 'cltbld', 07:56:42 INFO - 'MAIL': '/var/mail/cltbld', 07:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:42 INFO - 'MOZ_NO_REMOTE': '1', 07:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:42 INFO - 'NO_EM_RESTART': '1', 07:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:42 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:42 INFO - 'SHELL': '/bin/bash', 07:56:42 INFO - 'SHLVL': '1', 07:56:42 INFO - 'TERM': 'linux', 07:56:42 INFO - 'TMOUT': '86400', 07:56:42 INFO - 'USER': 'cltbld', 07:56:42 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:42 INFO - '_': '/tools/buildbot/bin/python'} 07:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:42 INFO - Downloading/unpacking psutil>=0.7.1 07:56:42 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 07:56:42 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 07:56:42 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 07:56:42 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 07:56:42 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 07:56:42 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 07:56:44 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 07:56:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:56:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:56:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:44 INFO - Installing collected packages: psutil 07:56:44 INFO - Running setup.py install for psutil 07:56:44 INFO - building 'psutil._psutil_linux' extension 07:56:44 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 07:56:44 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 07:56:44 INFO - building 'psutil._psutil_posix' extension 07:56:44 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 07:56:44 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 07:56:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:44 INFO - Successfully installed psutil 07:56:44 INFO - Cleaning up... 07:56:44 INFO - Return code: 0 07:56:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:44 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:44 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 07:56:44 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 07:56:44 INFO - Using env: {'DISPLAY': ':0', 07:56:44 INFO - 'HOME': '/home/cltbld', 07:56:44 INFO - 'LANG': 'en_US.UTF-8', 07:56:44 INFO - 'LANGUAGE': 'en_US:en', 07:56:44 INFO - 'LOGNAME': 'cltbld', 07:56:44 INFO - 'MAIL': '/var/mail/cltbld', 07:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:44 INFO - 'MOZ_NO_REMOTE': '1', 07:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:44 INFO - 'NO_EM_RESTART': '1', 07:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:44 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:44 INFO - 'SHELL': '/bin/bash', 07:56:44 INFO - 'SHLVL': '1', 07:56:44 INFO - 'TERM': 'linux', 07:56:44 INFO - 'TMOUT': '86400', 07:56:44 INFO - 'USER': 'cltbld', 07:56:44 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:44 INFO - '_': '/tools/buildbot/bin/python'} 07:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:56:44 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 07:56:44 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 07:56:44 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 07:56:44 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 07:56:44 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 07:56:44 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 07:56:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:56:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:56:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:56:46 INFO - Installing collected packages: mozsystemmonitor 07:56:46 INFO - Running setup.py install for mozsystemmonitor 07:56:46 INFO - Successfully installed mozsystemmonitor 07:56:46 INFO - Cleaning up... 07:56:46 INFO - Return code: 0 07:56:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:46 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:46 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 07:56:46 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 07:56:46 INFO - Using env: {'DISPLAY': ':0', 07:56:46 INFO - 'HOME': '/home/cltbld', 07:56:46 INFO - 'LANG': 'en_US.UTF-8', 07:56:46 INFO - 'LANGUAGE': 'en_US:en', 07:56:46 INFO - 'LOGNAME': 'cltbld', 07:56:46 INFO - 'MAIL': '/var/mail/cltbld', 07:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:46 INFO - 'MOZ_NO_REMOTE': '1', 07:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:46 INFO - 'NO_EM_RESTART': '1', 07:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:46 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:46 INFO - 'SHELL': '/bin/bash', 07:56:46 INFO - 'SHLVL': '1', 07:56:46 INFO - 'TERM': 'linux', 07:56:46 INFO - 'TMOUT': '86400', 07:56:46 INFO - 'USER': 'cltbld', 07:56:46 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:46 INFO - '_': '/tools/buildbot/bin/python'} 07:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:46 INFO - Downloading/unpacking blobuploader==1.2.4 07:56:46 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 07:56:46 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 07:56:46 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 07:56:46 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 07:56:46 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 07:56:46 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 07:56:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:56:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:56:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:56:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:56:48 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 07:56:48 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 07:56:48 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 07:56:48 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 07:56:48 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 07:56:48 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 07:56:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:56:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:56:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:56:49 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 07:56:49 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 07:56:49 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 07:56:49 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 07:56:49 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 07:56:49 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 07:56:49 INFO - Downloading docopt-0.6.1.tar.gz 07:56:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:56:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:56:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:56:49 INFO - Running setup.py install for blobuploader 07:56:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:56:49 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:56:49 INFO - Running setup.py install for requests 07:56:49 INFO - Running setup.py install for docopt 07:56:49 INFO - Successfully installed blobuploader requests docopt 07:56:49 INFO - Cleaning up... 07:56:49 INFO - Return code: 0 07:56:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:56:49 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')]} 07:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:49 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')]} 07:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:49 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:49 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 07:56:49 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 07:56:49 INFO - Using env: {'DISPLAY': ':0', 07:56:49 INFO - 'HOME': '/home/cltbld', 07:56:49 INFO - 'LANG': 'en_US.UTF-8', 07:56:49 INFO - 'LANGUAGE': 'en_US:en', 07:56:49 INFO - 'LOGNAME': 'cltbld', 07:56:49 INFO - 'MAIL': '/var/mail/cltbld', 07:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:49 INFO - 'MOZ_NO_REMOTE': '1', 07:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:49 INFO - 'NO_EM_RESTART': '1', 07:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:49 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:49 INFO - 'SHELL': '/bin/bash', 07:56:49 INFO - 'SHLVL': '1', 07:56:49 INFO - 'TERM': 'linux', 07:56:49 INFO - 'TMOUT': '86400', 07:56:49 INFO - 'USER': 'cltbld', 07:56:49 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:49 INFO - '_': '/tools/buildbot/bin/python'} 07:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:56:50 INFO - Running setup.py (path:/tmp/pip-WvaemG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:56:50 INFO - Running setup.py (path:/tmp/pip-UIUlZP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:56:50 INFO - Running setup.py (path:/tmp/pip-MYYLwN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:56:50 INFO - Running setup.py (path:/tmp/pip-w2ZKgC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:56:50 INFO - Running setup.py (path:/tmp/pip-FlBeSj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:56:50 INFO - Running setup.py (path:/tmp/pip-G1jO8D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:56:50 INFO - Running setup.py (path:/tmp/pip-fLPh21-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:56:50 INFO - Running setup.py (path:/tmp/pip-l0Kb1G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:56:50 INFO - Running setup.py (path:/tmp/pip-ugZJqN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:56:50 INFO - Running setup.py (path:/tmp/pip-UJWNCA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:56:50 INFO - Running setup.py (path:/tmp/pip-LK7FXN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:56:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:56:50 INFO - Running setup.py (path:/tmp/pip-NWQZ1J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:56:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:56:51 INFO - Running setup.py (path:/tmp/pip-WyfCNk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:56:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:56:51 INFO - Running setup.py (path:/tmp/pip-R5WVm4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:56:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:56:51 INFO - Running setup.py (path:/tmp/pip-VObNbC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:56:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:56:51 INFO - Running setup.py (path:/tmp/pip-x6_ghF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:56:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:56:51 INFO - Running setup.py (path:/tmp/pip-SWLNwB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:56:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:56:51 INFO - Running setup.py install for manifestparser 07:56:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:56:51 INFO - Running setup.py install for mozcrash 07:56:51 INFO - Running setup.py install for mozdebug 07:56:51 INFO - Running setup.py install for mozdevice 07:56:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:56:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:56:51 INFO - Running setup.py install for mozfile 07:56:51 INFO - Running setup.py install for mozhttpd 07:56:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozinfo 07:56:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozInstall 07:56:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozleak 07:56:52 INFO - Running setup.py install for mozlog 07:56:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for moznetwork 07:56:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozprocess 07:56:52 INFO - Running setup.py install for mozprofile 07:56:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozrunner 07:56:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:56:52 INFO - Running setup.py install for mozscreenshot 07:56:52 INFO - Running setup.py install for moztest 07:56:53 INFO - Running setup.py install for mozversion 07:56:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:56:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:56:53 INFO - Cleaning up... 07:56:53 INFO - Return code: 0 07:56:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:53 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:53 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 07:56:53 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 07:56:53 INFO - Using env: {'DISPLAY': ':0', 07:56:53 INFO - 'HOME': '/home/cltbld', 07:56:53 INFO - 'LANG': 'en_US.UTF-8', 07:56:53 INFO - 'LANGUAGE': 'en_US:en', 07:56:53 INFO - 'LOGNAME': 'cltbld', 07:56:53 INFO - 'MAIL': '/var/mail/cltbld', 07:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:53 INFO - 'MOZ_NO_REMOTE': '1', 07:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:53 INFO - 'NO_EM_RESTART': '1', 07:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:53 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:53 INFO - 'SHELL': '/bin/bash', 07:56:53 INFO - 'SHLVL': '1', 07:56:53 INFO - 'TERM': 'linux', 07:56:53 INFO - 'TMOUT': '86400', 07:56:53 INFO - 'USER': 'cltbld', 07:56:53 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:53 INFO - '_': '/tools/buildbot/bin/python'} 07:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:56:53 INFO - Running setup.py (path:/tmp/pip-dqNcge-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:56:53 INFO - Running setup.py (path:/tmp/pip-bNCOQm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:56:53 INFO - Running setup.py (path:/tmp/pip-tPk2fw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:56:53 INFO - Running setup.py (path:/tmp/pip-gBjZGu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:56:53 INFO - Running setup.py (path:/tmp/pip-86UYoY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:56:53 INFO - Running setup.py (path:/tmp/pip-zmFp5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:56:53 INFO - Running setup.py (path:/tmp/pip-8RxgsB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:56:53 INFO - Running setup.py (path:/tmp/pip-f_S8V2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:56:53 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)) 07:56:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:56:53 INFO - Running setup.py (path:/tmp/pip-WH2F2F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:56:54 INFO - Running setup.py (path:/tmp/pip-kVfqLn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:56:54 INFO - Running setup.py (path:/tmp/pip-rD5lIZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:56:54 INFO - Running setup.py (path:/tmp/pip-_6HLW_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:56:54 INFO - Running setup.py (path:/tmp/pip-QKAjMO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:56:54 INFO - Running setup.py (path:/tmp/pip-bWFM1m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:56:54 INFO - Running setup.py (path:/tmp/pip-2Gp6Pd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:56:54 INFO - Running setup.py (path:/tmp/pip-IrRkQt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:56:54 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)) 07:56:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:56:54 INFO - Running setup.py (path:/tmp/pip-bC2j8N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:56:54 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)) 07:56:54 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)) 07:56:54 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)) 07:56:54 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)) 07:56:54 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)) 07:56:54 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)) 07:56:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:56 INFO - Downloading blessings-1.5.1.tar.gz 07:56:56 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 07:56:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:56:56 INFO - Installing collected packages: blessings 07:56:56 INFO - Running setup.py install for blessings 07:56:56 INFO - Successfully installed blessings 07:56:56 INFO - Cleaning up... 07:56:56 INFO - Return code: 0 07:56:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:56:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:56:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:56:56 INFO - Reading from file tmpfile_stdout 07:56:56 INFO - Current package versions: 07:56:56 INFO - argparse == 1.2.1 07:56:56 INFO - blessings == 1.5.1 07:56:56 INFO - blobuploader == 1.2.4 07:56:56 INFO - docopt == 0.6.1 07:56:56 INFO - manifestparser == 1.1 07:56:56 INFO - mozInstall == 1.12 07:56:56 INFO - mozcrash == 0.16 07:56:56 INFO - mozdebug == 0.1 07:56:56 INFO - mozdevice == 0.47 07:56:56 INFO - mozfile == 1.2 07:56:56 INFO - mozhttpd == 0.7 07:56:56 INFO - mozinfo == 0.9 07:56:56 INFO - mozleak == 0.1 07:56:56 INFO - mozlog == 3.1 07:56:56 INFO - moznetwork == 0.27 07:56:56 INFO - mozprocess == 0.22 07:56:56 INFO - mozprofile == 0.27 07:56:56 INFO - mozrunner == 6.11 07:56:56 INFO - mozscreenshot == 0.1 07:56:56 INFO - mozsystemmonitor == 0.0 07:56:56 INFO - moztest == 0.7 07:56:56 INFO - mozversion == 1.4 07:56:56 INFO - psutil == 3.1.1 07:56:56 INFO - requests == 1.2.3 07:56:56 INFO - wsgiref == 0.1.2 07:56:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:56 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:56 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 07:56:56 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 07:56:56 INFO - Using env: {'DISPLAY': ':0', 07:56:56 INFO - 'HOME': '/home/cltbld', 07:56:56 INFO - 'LANG': 'en_US.UTF-8', 07:56:56 INFO - 'LANGUAGE': 'en_US:en', 07:56:56 INFO - 'LOGNAME': 'cltbld', 07:56:56 INFO - 'MAIL': '/var/mail/cltbld', 07:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:56 INFO - 'MOZ_NO_REMOTE': '1', 07:56:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:56 INFO - 'NO_EM_RESTART': '1', 07:56:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:56 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:56 INFO - 'SHELL': '/bin/bash', 07:56:56 INFO - 'SHLVL': '1', 07:56:56 INFO - 'TERM': 'linux', 07:56:56 INFO - 'TMOUT': '86400', 07:56:56 INFO - 'USER': 'cltbld', 07:56:56 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:56 INFO - '_': '/tools/buildbot/bin/python'} 07:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 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)) 07:56:56 INFO - Cleaning up... 07:56:56 INFO - Return code: 0 07:56:56 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 07:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:56 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:56 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 07:56:56 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 07:56:56 INFO - Using env: {'DISPLAY': ':0', 07:56:56 INFO - 'HOME': '/home/cltbld', 07:56:56 INFO - 'LANG': 'en_US.UTF-8', 07:56:56 INFO - 'LANGUAGE': 'en_US:en', 07:56:56 INFO - 'LOGNAME': 'cltbld', 07:56:56 INFO - 'MAIL': '/var/mail/cltbld', 07:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:56 INFO - 'MOZ_NO_REMOTE': '1', 07:56:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:56 INFO - 'NO_EM_RESTART': '1', 07:56:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:56 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:56 INFO - 'SHELL': '/bin/bash', 07:56:56 INFO - 'SHLVL': '1', 07:56:56 INFO - 'TERM': 'linux', 07:56:56 INFO - 'TMOUT': '86400', 07:56:56 INFO - 'USER': 'cltbld', 07:56:56 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:56 INFO - '_': '/tools/buildbot/bin/python'} 07:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:57 INFO - Downloading/unpacking jsonschema 07:56: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 07:56: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 07:56: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 07:56: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 07:56: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 07:56: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 07:56:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:56:58 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 07:56:58 INFO - Downloading/unpacking functools32 (from jsonschema) 07:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:59 INFO - Downloading functools32-3.2.3-2.tar.gz 07:56:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 07:56:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 07:56:59 INFO - warning: no files found matching '*.txt' 07:56:59 INFO - no previously-included directories found matching 'build' 07:56:59 INFO - no previously-included directories found matching 'dist' 07:56:59 INFO - no previously-included directories found matching '.git*' 07:56:59 INFO - Installing collected packages: jsonschema, functools32 07:56:59 INFO - Running setup.py install for functools32 07:56:59 INFO - warning: no files found matching '*.txt' 07:56:59 INFO - no previously-included directories found matching 'build' 07:56:59 INFO - no previously-included directories found matching 'dist' 07:56:59 INFO - no previously-included directories found matching '.git*' 07:56:59 INFO - Successfully installed jsonschema functools32 07:56:59 INFO - Cleaning up... 07:56:59 INFO - Return code: 0 07:56:59 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 07:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:59 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 0x7f1a63d04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1636600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', '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 07:56:59 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 07:56:59 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 07:56:59 INFO - Using env: {'DISPLAY': ':0', 07:56:59 INFO - 'HOME': '/home/cltbld', 07:56:59 INFO - 'LANG': 'en_US.UTF-8', 07:56:59 INFO - 'LANGUAGE': 'en_US:en', 07:56:59 INFO - 'LOGNAME': 'cltbld', 07:56:59 INFO - 'MAIL': '/var/mail/cltbld', 07:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:59 INFO - 'MOZ_NO_REMOTE': '1', 07:56:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:59 INFO - 'NO_EM_RESTART': '1', 07:56:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:56:59 INFO - 'PWD': '/builds/slave/test-pgo', 07:56:59 INFO - 'SHELL': '/bin/bash', 07:56:59 INFO - 'SHLVL': '1', 07:56:59 INFO - 'TERM': 'linux', 07:56:59 INFO - 'TMOUT': '86400', 07:56:59 INFO - 'USER': 'cltbld', 07:56:59 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:59 INFO - '_': '/tools/buildbot/bin/python'} 07:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:59 INFO - Downloading/unpacking flake8 07:56:59 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 07:56:59 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 07:56:59 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 07:56:59 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 07:56:59 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 07:56:59 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 07:57:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:57:01 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 07:57:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:57:01 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 07:57:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 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 07:57:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 07:57:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 07:57:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:01 INFO - no previously-included directories found matching 'docs/_build' 07:57:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:57:01 INFO - Running setup.py install for pep8 07:57:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:02 INFO - no previously-included directories found matching 'docs/_build' 07:57:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 07:57:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:57:02 INFO - Cleaning up... 07:57:02 INFO - Return code: 0 07:57:02 INFO - Running post-action listener: _resource_record_post_action 07:57:02 INFO - Running post-action listener: _start_resource_monitoring 07:57:02 INFO - Starting resource monitoring. 07:57:02 INFO - ##### 07:57:02 INFO - ##### Running install step. 07:57:02 INFO - ##### 07:57:02 INFO - Running pre-action listener: _resource_record_pre_action 07:57:02 INFO - Running main action method: install 07:57:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:57:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:57:02 INFO - Reading from file tmpfile_stdout 07:57:02 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:57:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:57:02 INFO - Reading from file tmpfile_stdout 07:57:02 INFO - Output received: 07:57:02 INFO - Usage: mozinstall [options] installer 07:57:02 INFO - Options: 07:57:02 INFO - -h, --help show this help message and exit 07:57:02 INFO - -d DEST, --destination=DEST 07:57:02 INFO - Directory to install application into. [default: 07:57:02 INFO - "/builds/slave/test-pgo"] 07:57:02 INFO - --app=APP Application being installed. [default: firefox] 07:57:02 INFO - mkdir: /builds/slave/test-pgo/build/application 07:57:02 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'] 07:57:02 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 07:57:18 INFO - Reading from file tmpfile_stdout 07:57:18 INFO - Output received: 07:57:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:57:18 INFO - Running post-action listener: _resource_record_post_action 07:57:18 INFO - ##### 07:57:18 INFO - ##### Running run-tests step. 07:57:18 INFO - ##### 07:57:18 INFO - Running pre-action listener: _resource_record_pre_action 07:57:18 INFO - Running main action method: run_tests 07:57:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:57:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:57:18 INFO - Python 2.7.3 07:57:18 INFO - Return code: 0 07:57:18 INFO - grabbing minidump binary from tooltool 07:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:18 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 0x16c5350>, '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 0x16c6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c66e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:57:18 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 07:57:18 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 07:57:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:18 INFO - Return code: 0 07:57:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:57:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:57:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:57:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:57:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:57:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 07:57:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 07:57:19 INFO - Return code: 0 07:57:19 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/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:57:19 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/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost 07:57:19 INFO - Using env: {'DISPLAY': ':0', 07:57:19 INFO - 'HOME': '/home/cltbld', 07:57:19 INFO - 'LANG': 'en_US.UTF-8', 07:57:19 INFO - 'LANGUAGE': 'en_US:en', 07:57:19 INFO - 'LOGNAME': 'cltbld', 07:57:19 INFO - 'MAIL': '/var/mail/cltbld', 07:57:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:57:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:19 INFO - 'MOZ_NO_REMOTE': '1', 07:57:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:19 INFO - 'NO_EM_RESTART': '1', 07:57:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:57:19 INFO - 'PWD': '/builds/slave/test-pgo', 07:57:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:57:19 INFO - 'SHELL': '/bin/bash', 07:57:19 INFO - 'SHLVL': '1', 07:57:19 INFO - 'TERM': 'linux', 07:57:19 INFO - 'TMOUT': '86400', 07:57:19 INFO - 'USER': 'cltbld', 07:57:19 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585', 07:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:19 INFO - '_': '/tools/buildbot/bin/python'} 07:57:19 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/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] with output_timeout 3600 07:57:19 INFO - mozversion INFO | application_buildid: 20151218053000 07:57:19 INFO - mozversion INFO | application_changeset: 01a10d89e8a145f5f41e0d6f582ef431fe40fdbd 07:57:19 INFO - mozversion INFO | application_display_name: Nightly 07:57:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:19 INFO - mozversion INFO | application_name: Firefox 07:57:19 INFO - mozversion INFO | application_remotingname: firefox 07:57:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 07:57:19 INFO - mozversion INFO | application_vendor: Mozilla 07:57:19 INFO - mozversion INFO | application_version: 46.0a1 07:57:19 INFO - mozversion INFO | platform_buildid: 20151218053000 07:57:19 INFO - mozversion INFO | platform_changeset: 01a10d89e8a145f5f41e0d6f582ef431fe40fdbd 07:57:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 07:57:19 INFO - mozversion INFO | platform_version: 46.0a1 07:57:19 INFO - 2015-12-18 07:57:19,363 DEBUG : using testdate: 1450454239 07:57:19 INFO - 2015-12-18 07:57:19,363 DEBUG : actual date: 1450454239 07:57:19 INFO - 2015-12-18 07:57:19,372 INFO : starting webserver on 'localhost:56821' 07:57:19 INFO - 2015-12-18 07:57:19,373 INFO : Starting test suite talos-linux64-ix-015.e 07:57:19 INFO - 2015-12-18 07:57:19,373 INFO : Starting test dromaeo_css 07:57:19 INFO - 2015-12-18 07:57:19,373 DEBUG : operating with platform_type : linux_ 07:57:19 INFO - 2015-12-18 07:57:19,374 INFO : Initialising browser for dromaeo_css test... 07:57:19 INFO - 2015-12-18 07:57:19,387 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHmAHZt/profile http://localhost:56821/getInfo.html 07:57:24 INFO - 2015-12-18 07:57:24,115 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:57:24 INFO - 2015-12-18 07:57:24,115 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:57:24 INFO - 2015-12-18 07:57:24,116 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:57:24 INFO - 2015-12-18 07:57:24,116 DEBUG : BROWSER_OUTPUT: __metrics 07:57:24 INFO - 2015-12-18 07:57:24,237 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] 07:57:25 INFO - 2015-12-18 07:57:25,125 INFO : Browser initialized. 07:57:25 INFO - 2015-12-18 07:57:25,126 INFO : Running cycle 1/1 for dromaeo_css test... 07:57:25 INFO - 2015-12-18 07:57:25,126 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHmAHZt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:57:36 INFO - 2015-12-18 07:57:36,926 DEBUG : BROWSER_OUTPUT: RSS: Main: 150372352 07:57:36 INFO - 2015-12-18 07:57:36,927 DEBUG : BROWSER_OUTPUT: 07:58:26 INFO - 2015-12-18 07:58:26,527 DEBUG : BROWSER_OUTPUT: 1450454306526 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] 08:00:34 INFO - 2015-12-18 08:00:34,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56821/tests/dromaeo/cssquery-ext.html) 08:00:35 INFO - 2015-12-18 08:00:35,901 DEBUG : BROWSER_OUTPUT: RSS: Main: 157212672 08:00:35 INFO - 2015-12-18 08:00:35,901 DEBUG : BROWSER_OUTPUT: 08:03:37 INFO - 2015-12-18 08:03:37,853 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-ext.html (next: http://localhost:56821/tests/dromaeo/cssquery-jquery.html) 08:03:38 INFO - 2015-12-18 08:03:38,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 153559040 08:03:38 INFO - 2015-12-18 08:03:38,435 DEBUG : BROWSER_OUTPUT: 08:06:40 INFO - 2015-12-18 08:06:40,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56821/tests/dromaeo/cssquery-mootools.html) 08:06:40 INFO - 2015-12-18 08:06:40,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 154198016 08:06:40 INFO - 2015-12-18 08:06:40,721 DEBUG : BROWSER_OUTPUT: 08:09:42 INFO - 2015-12-18 08:09:42,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56821/tests/dromaeo/cssquery-prototype.html) 08:09:42 INFO - 2015-12-18 08:09:42,942 DEBUG : BROWSER_OUTPUT: RSS: Main: 152506368 08:09:42 INFO - 2015-12-18 08:09:42,942 DEBUG : BROWSER_OUTPUT: 08:12:45 INFO - 2015-12-18 08:12:45,218 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56821/tests/dromaeo/cssquery-yui.html) 08:12:45 INFO - 2015-12-18 08:12:45,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 152403968 08:12:45 INFO - 2015-12-18 08:12:45,804 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-yui.html (next: http://localhost:56821/tests/dromaeo/cssquery-dojo.html) 08:15:48 INFO - 2015-12-18 08:15:48,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 155189248 08:15:48 INFO - 2015-12-18 08:15:48,461 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,461 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3156.6369867465237;3166.683331666832;3175.0633563098913;3203.7276831469317;3216.147720853974;5207.192885102811;5230.660007099541;5256.474352564742;5275.55157811586;5279.208118782185;5395.81114660987;5408.134698448249;5413.53984911282;5414.7834086636585;5436.972815135929;4888.486708895568;4915.016996600685;4937.654364194508;4942.332785074005;4942.505749425047;453.2226249627427;4962.5533701966815;5031.698098114125;5034.496550344973;5078.289039534468;4965.329680493123;4968.180250258721;4983.72589507577;4989.90020199594;4992.126377883874;4969.478204788481;4988.925166122511;4993.700377977297;5008.123578373807;5043.773030213649;4807.447143578498;4988.226824842155;5001.7499125043605;5013.824516141917;5046.697198168122;5170.224466330043;5219.295395121648;5255.6583822051825;5273.419923808378;5332.173513105475;5753.367129615739;5759.884802303931;5773.971130144364;5799.3620701722775;5813.6221145625395;5659.236003139565;5758.1362795581;5817.680027598481;5839.503642190419;5866.6480011199465;5463.371712253102;5512.641678290898;5514.6139770216105;5530.474604912567;5571.220029195916;5891.9705401473575;5904.911426328523;5915.733791979371;5920.437558431942;5944;5497.312835895513;5684.317881854204;5699;5748.655080695172;5757.366689664133;5779.971100144472;5781.103928891029;5785.681787501644;5865.4427829357;5903.26209223847;5754.942450575524;5756.079027355687;5777.335606405317;5781;5797.188393624896;5639.520640745583;5745.482906538432;5754.539636829044;5809.970950145223;5814.3313518945015;4446.3552784846015;4463.486699029589;4479.059397526491;4483.282674772021;4502.077074199781;3991.5808840071954;4006.6193711597934;4017.678585713136;4041.1917616476235;4052.77709725962;5356.7857285708915;5440.80302333486;5462.153366228241;5515.531179849758;5556.499915007668;5840.941590584125;5952.732127054206;5954.106883967353;5966.164736936832;5970.641761494324;5569.359523654751;5575.163725441135;5589.776408943597;5602.803901863496;5605.047141985872;4959.256111583291;4995.575376093073;5033.79864805411;5079.949200508022;5138.434772175057;5903.527717782567;5937.465628093492;5939.227900372924;5952.077427998667;5957.255343082114;5774.538036956949;5885.499732522612;5887.116932460165;5888.17565540816;5900.76396944126;5833.4749872511675;5883.882322353444;5917.852053698692;5924.022536281465;5987.9401205989125;5787.594868359343;5796.42035796417;5824.796132135355;5852.5317974561085;5864.530837532901;5704.657720536781;5804.419558044162;5923.756011237689;5934.43622855828;5936.406359364029;5694.829155125353;5731.19763233156;5732.57005724564;5747.74135163927;5800.071988481992;5684.459976302244;5750.0512415450985;5779.393163717749;5785.710714464344;5789.073748200268;5434.10337294342;5578.916316255179;5579.218909352613;5599.80400685974;5684.857878553069;5666.263385759825;5667.4615911488345;5680.772769089355;5817.70911454434;5874;5699.715014249354;5725.770969161187;5727.284089488814;5747.166660834239;5757.798477053283 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: |1;ext.html;567.5629765080888;597.554821657865;619.6808643548574;627.5168120547179;628.1488582970078;37315.44026839596;38362.23275534488;38411.61588384115;38498;38536;42193;42322.36516452248;42732.57267427325;42794;42808.35787463183;8389.622466988985;8469.280111190556;8470.534120623362;8488.74533763988;8539.871901921477;8426.452280601741;8528.189821966935;8532.658693652247;8533.487990720545;8544.444611100289;8473.957630211871;8560.272376847975;8595.742127736177;8596.398252122353;8597.355198360148;8564.100769419214;8649.827003459959;8683.218510334069;8686.218240358397;8690.869636955451;8501.19238672325;8646.783830404227;8649.437786543918;8653.783655408602;8666.133386661284;39300;39354.803225983975;39666;39808.80095599532;39863.20273594541;3102.2399512119673;3183.6338821035647;3245.042712399848;3289.851956661956;3640.7087433005295;29615.555766663518;29725.40549189026;30652.8467357664;31195.532067019012;31631.84184079088;3919.6080391960577;3925.548561915388;3925.7448265862627;4034.616711412411;4092.877213683594;275621;276307;278173;278293;278818;53403.46596534063;53732.194017088994;66797.33202668009;67749;67808.6609566959;61433;61434.69282653558;61528;61564;61766;14312.499062532761;14534.418623255162;14594.41622335116;14695.485658002133;14724;10536.736581585521;10612.204084693525;10632.043116119587;10685.83971240432;10703.678889633324;20159.19363225484;20729.786883033154;20761.89619051895;20762.169513219604;20772.272970445963;7912.406569507195;7983.960080199678;8055.63749631268;8187.549684767395;8227.382946279053;911.5988964855575;947.8530827721714;953.1897886796168;1068.8984546468073;1080.670395529371;28225.294367640974;28503.429931401257;32874.342513149604;33423.65492485961;33675.326493470486;1005.9236616819933;1019.454591793387;1045.3727763341933;1056.9894301057045;1114.9386783726973;14422.639434014023;14548.63628409277;14619.415223390944;14749.852501475063;14808.111513309235;377117;377200;378707;382265;383554;53226.73386633042;53489.1976620351;53960.73019634877;54186.45813541893;54249;1274.3564499927663;1274.5475356248398;1288.1884412819863;1308.483149156097;1311.4229738914846;26757;27597.58603620907;27775.44449111047;27867.303317416416;28012.579811303254;43885.34171987359;44236.55763442325;44353;44395.77802110969;44614;309384;309694;310080;310725;311833;32773.50839737358;43599.34600981051;43842.342364863915;44463.55536444594;44624.55375446334;43466;44134.11731765411;44356.556434435246;44402.77798610986;44575;36592;36972.26055478929;37156;37426.43860342151;37905.81047094747;36671;36907;37161.62838371582;37238.44142337921;37512.812435937645;34763;40798.59201407948;40991.590084098774;41256.174876502904;41448.3782743253;28500.85749571239;30269.789208431415;32412;33468.66531334753;33750.66249337475;25222;25548.23355299344;25628.359291017874;25635.743642563335;25950 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.6298531020394;361.11166530335373;361.8299399282337;362.0983750461349;366.97064234861216;16769.49691509255;16835.15824208789;17359.218835152416;17428;18014.81985180148;10690.839637405426;11364.431778411088;15715.528534143994;15754.842451575481;15813.920930395332;4834.250691142878;5189.247559103927;5283.286756287909;5321.228421878825;5323.866903327409;5309.30978972733;5314.601404894629;5321.680699158043;5322.787088516474;5338.733063346836;3715.2569486102752;3820.7707537547835;5308.761105750251;5641.323041235058;5900.675462849542;5373.543248823835;5379.919301210485;5398.8920221595745;5401.594880383967;5405.945940540583;5240.868978275574;5279.86800329991;5314.681119132826;5437.755301011464;5463.562914966793;12181.695457613541;12292.508299668003;12321.876781232162;16611.584710382336;21366.679499807517;1959.3925882976323;2015.3699422253953;2069.6791997240452;2129.627315219846;2256.4810093678384;9336.953315233448;9482.146606805421;9552;9568.234541236754;9748.220142388593;1799.8560115190885;1843.907804609764;1857.9535511612182;1889.7070954002152;1912.2924517928316;58784;60706;63486;64512.67743661298;64799;10302.33034852732;10329.586816527255;10391.584336626602;10394.116500097425;10430.582776689;13708;13716.451341946211;13820.654483637789;13821.654458638413;13822.93088534551;6830.965845170742;7130.92869071313;7276.636168191675;7335.633218339169;7465.85068298631;7074.858502829915;7078.752243671447;7103.431725461951;7127.8574428512175;7143.857122857514;4287.228298906227;4431.8448854289945;4496.123255965055;4575.725456472623;4583.908321833612;1872.8220819022172;1935.5935253596622;1939.776925653568;1979.604079184169;1991.6116357310186;402.5431135660987;418.52915470096144;423.9109786944776;865.7705707987388;923.224491427191;10195.337303075275;10545.894541054644;10578.57685692579;10756.623518176826;10763.3541987481;430.29431731959613;437.0625009354916;439.387055058192;441.785733919049;446.5913688974552;9516.809663806685;10087.092161705335;10150.238732095122;10212.08091271789;10231.488425578691;546229;546308;550153;566098;567948;78502.6074869622;78815;81686;82896.58551707203;83522.58238708768;1101.0126721268189;1101.8126918423886;1103.2553026706942;1123.017359810166;1127.3517727306833;15716.371345146523;15760.92119539395;15850.920745396199;15916.0450372978;15926.68146637084;34702;34999;35416.114597135274;35625.1093722659;37051.258974819815;47466.525334747595;49196.26205606991;49242.26136608025;49744.75127624339;50035;9064.50145242018;9132.634694612407;9229;9265.351425400157;9268.95365523168;11028.669139925814;11047.779044419227;11063.391513466837;11206.159538034843;12231.449584768528;2888.6822449530114;14123.833469126426;14286.071405358618;14317.713645727235;14436.566902992927;14006.229657368942;14045.929770351082;14365.425382984564;14452.855471445151;14929.402823886923;14446.277686115862;14787.482438114614;14930.925345373204;15122.395104196148;15504.069755814688;9456.952715236379;9613.711588652352;9853.310268281151;10108.140808031105;10138.898611013796;13905.72188556203;14282.214478203383;14589.85410145885;14871.774386834113;15162.545123646309 08:15:48 INFO - 2015-12-18 08:15:48,462 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1395.4697215058277;1465.6995315960226;1570.2933679844066;1608.541565653789;1715.9227834747435;6336;6516.576422532538;6568.73725050998;6614.305497922709;6814.1482314710665;6765.7970260892125;6769;6842.96578517108;6854.417374523159;6855.554388964726;2132.3816093332953;2175.977290673389;2179.0521123311332;2180.9890950545223;2182.4980254541465;2111.915523379063;2142.817860481861;2172.0334451169238;2172.0877231562704;2173.3805865328386;2106.0838535237144;2109.4409981354875;2130.7762684918093;2141.582391433671;2145.6566949288117;2095.779943105975;2132.3389749177727;2134.039682143034;2137;2140.9357719268446;2120.4804822818355;2126.766055733868;2133.28534940795;2139.5506943541873;2140.636091864387;6742.898856517156;6755.088063111466;6760.76337328196;6768.796936091925;6805.421539169177;4064.390341448777;4068.9389659155136;4117.402976568381;4118.135191609766;4123.2165888481395;2861.3991061876823;2915.8687859046395;2918.1537354167267;2929.282325830164;2930.1356099950567;1010.0858722855827;1048.5176818663347;1053.9947300263527;1070.9410982395966;1076.4187338837019;8794.42836215644;8885;8889.111088891059;8906.643734250621;8929.553522323858;3666.7716315034227;3677.209399978964;3705.7776533408082;3722.4788529605876;3729.4032954727113;7477.476576639583;7481.21447248042;7496.400287976949;7502.474826762074;7509.924900751032;4100;4103.8974025649595;4116.8147433364975;4142.46148000764;4146.771927544014;3712.0348709335767;3799.848006079781;3828.5405751310022;3837.3860182370686;3866.3040652682225;3184.1561986073707;3204.5834041574913;3206.422843888076;3216.260260140181;3221.7744757866726;1013.083159740436;1014.5434554450527;1018.7707765752647;1019.2101121630648;1021.2085633633844;1013.7364285285879;1018.6587493189865;1130.8077626803463;1263.4693428759942;1276.8020956751718;6144.5084393248435;6307.117003619497;6459.547831651833;6496.0255961606135;6497.057926600616;1095.2497539185683;1097.7530055737554;1098.1873413673825;1110.3615421132881;1115.4924509348227;5148.562372198405;5212.139996900545;5226.790928362823;5266.447023062523;5269.604779641542;7727.459077864495;7744.380449564022;7952.125266220711;8015.559144247006;8153;7934.484258523177;7944.880826787603;7947.483413578099;7990.880136797953;8004.319632831158;6306.148669929593;6362.713677884506;6389.7763578274535;6422.165118534654;6450.516211284173;6133.74258277061;6171.660558669137;6174.413430724254;6197.1324014638885;6199.535034872493;7075.646217689198;7149.392301654418;7186.389156921511;7258.564486130849;7403.666834992546;7322.890156647761;7336.339729424378;7343.375813055736;7373.115226172894;7389.704411823682;5749;5811.82564523065;5853.560982926212;5893.410658934073;5911.940880591139;6517.771877984248;6532.281449040506;6540.934590654033;6542.8364290893105;6557.2131344239;6025.246844144482;6037.547183961133;6068.696565171812;6085.908711369421;6139.048447490646;5690.089585666274;5756.65460072397;5764.827055188351;5768.855778605568;5809.215755872903;6072.8785424292155;6079.939200607937;6110.297315808739;6112.266528016667;6162.630242185304;5698.202251684685;5745.109508026263;5754.9136762947755;5770.971145144247;5776.942230577641;5735.11105778605;5735.913961290667;5737.139429085671;5758;5768.221290125779 08:15:48 INFO - 2015-12-18 08:15:48,464 DEBUG : BROWSER_OUTPUT: |4;prototype.html;322.3874638187444;328.8700963119568;331.6318886036499;331.6964977046003;331.77439341247947;6387;6425.13260709804;6433.163688720466;6443.516736244777;6549.115869357635;6312.873742525147;6344.429001389874;6379.330170332118;6390.680465976706;6390.712417941192;4748.596369308613;4755.619550435957;4759.952400475986;4762.547557982003;4765.237561990074;4746.833860814872;4754.453237877637;4755.334253204537;4765.42814862217;4773.236282194849;4625.4911959684405;4674.719516829001;4678.298255261704;4695.413073365829;4705;4675.789589468465;4679.906401871961;4680.812767489313;4683.906321873577;4707.670463067587;3995.700322475826;3997.6801855851754;3998.1204135090234;4000.9399859002137;4008.959910400888;6047.78832740852;6053.485453736438;6054.182685337467;6064.727087281083;6066.939330606681;5357.491038351351;5414.756335964852;5444.265024221719;5458.754356053987;5461;6715.093462382565;6729.865402691968;6735.595864248161;6745.359190876908;6761.695723692445;4413.7351758894565;4430.933535996963;4433.866983990485;4439;4443.955560444387;6834.760783372608;6924.58452492852;6979.685914133876;6987.406070484015;7005.789826305219;4580.267157254823;4591.701539399928;4596.747178905125;4599.747013914267;4600.401947746838;4638.863478447802;4642.280446530725;4657.115148121846;4657.3246879202325;4660.207764679944;5472.753726082336;5473.124300111963;5482.424345443751;5491.835244942578;5492.286002819608;6667.533272670867;6674.499412544079;6752.291009444036;6769.356911093439;6782.2200446948245;1581.6204111013503;1620.3842539835023;1636.9345226191058;1639.3360688921007;1642.1050527462562;1043.3687618990552;1046.2937517175876;1051.4427353502656;1055.8205105132147;1068.220199254533;6375;6382.329855365213;6384.84037899047;6395.808125756142;6395.904061439082;6843.452523798084;6881.587104773729;6902.827429314207;6919.930800691928;6924.099867017256;6201.410865967726;6306.243250809932;6332.715027823759;6342.334054924167;6370;3766.6798322142927;3767.453719210699;3792.4311353296675;3796.3166630006313;3808.143167787281;6948.5483443576;7012.824679382954;7026;7048.823779405453;7052.188998265266;7077.1507419109;7091.326323999212;7106.360427561441;7107.218205997337;7117.217106118323;7200.8919866201;7201.89197162043;7204.819879503054;7210.603416812126;7218.422526197892;7040.542364746275;7042.647867606702;7045.471589630695;7045.612491313027;7093.361597456254;6880.31196880328;6971.51199416036;6981.685824138028;6985.685644145924;7005;7151.8569628608175;7155.499115061895;7157.427405807627;7176.96411517939;7182.928170718435;4445.222086134849;4451.732896026248;4469.687121901436;4471.597556219956;4490.393796837385;6925;6991.860162796817;7039.296070392919;7049.7885063448175;7055.470839686941;6873.48448866347;6877.862442751017;6881.380675739208;6895.586264824126;6903.930960690329;6852.451803855779;6859.622720750406;6863.07348507965;6871.209810871814;6881.759138430331;6571.145751052332;6689.163854518185;6689.966550167218;6705.631190284581;6709.563878347892;6865.141857267841;6867.931320686929;6870.690818913261;6873;6883.105196324646;6632.767853125118;6676.332366763285;6705;6709.966450167718;6721.462283017445 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: |5;yui.html;709.035711432452;709.3332267668393;739.5082270290254;830.5473516933271;840.7309660908504;12918.160319579221;13201.669958251048;13207.141535800167;13226.338683065844;13233.338333083344;12652.873471265284;13628.454861805541;13642.045056846026;13651.249181295025;13670.111442756213;803.7387848949086;809.8582748019088;819.115355416152;821.7329367955408;823.074041703084;811.3184924663282;811.8944537210155;815.8898548695939;816.8039670479102;817.4114637461018;862.6161358195574;865.1478293880523;871.1941454154882;871.6818361298112;874.4010352908247;852.4672079950032;853.5561508015859;858.4505916213628;862.0431321233426;862.9741107766777;631.356016862802;633.4488994574749;633.6008314761717;634.3878157577959;636.2492259134219;13129.540466083641;13355.866441335656;13424.664383390396;13427;13476.86523134766;11300.660980170607;11933.820992685116;12316.384180790925;12378.504859805596;12983.02627302956;9845.351331710657;10199.18406527476;10227.6420325289;10617.522211500502;10972.506237219264;3602.7297952653657;3641.0715267606593;3643.107438677541;3647.325244829715;3752.3808571585655;48245.517544824455;49261.261081083816;49356.50643493519;49373.75313123447;49467;9595;9946.45294508809;10032.197424206046;10094.14199793026;10501.684949451528;17297.481075567754;17335.21991510385;17336.913315433343;17343.13284335778;17365.392211272545;7248.528845625017;7338.559686418832;7485.7754267372065;7507.1742108368035;7508.962455187689;3797.1646237827636;3942.290387730236;3952.446657467957;3959.208158368338;3962.980185099056;2974.7768917331287;2991.326951435953;3002.1744020394567;3002.9549556756665;3004.2038859702197;532.9760160792773;537.7015756255238;538.0879409401027;552.7512619321321;560.9326880774333;495.86859482237236;571.2545128607208;607.0388782159242;633.4204203154154;669.0432681265739;7995;8237.217464340878;8250;8371.372147088992;8390.958045209856;580.4456743809652;619.5322531488697;651.04621729167;655.632845606462;671.5030877150873;3662.8351724171944;3965.147493288957;4098.446709694213;4139.3583994480905;4356.825726970886;58099;59182.11226831601;59974;60022;60077.69961150254;50791.23813142806;50797;51201.487985119675;51326;51426.48573514292;45276;47232.5276747235;47255;47519;48207.75896120497;8025.638846251816;8124.796880078045;8247.134050924567;8260.421770476249;8277.172282771675;34977.825110874284;35071.64928350786;35443;35866.82066589651;35959;40964.59035409727;45314;45818.312725309814;45967.77016114898;46476;10891.128709703364;11180.385078820744;11367.772644547229;11373.260738052;11617.128715346551;12092.395380230777;12103.515859365527;12172.695682608006;12545.463554828944;12627.431765570755;10386.272960892666;10600.946995264974;10720.785584288114;10875.293105948089;11125.499352528994;11293.153013523854;11366.318020918772;11499.712507187387;11565.421728913689;12020.579279725167;9919.603215871573;10113.241506886865;10138.391696498235;10494.212934029825;10506.579736810441;10312.948435257777;10363.097943060637;10422.947885260524;10824.729381765519;11024.283421577571;8871.600777964877;9223.861642075508;9269.953650231706;9511.143997040022;9588.089131532493 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,302 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: __startTimestamp1450455348454__endTimestamp 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:15:48 INFO - 2015-12-18 08:15:48,465 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5381.61 Median:5666.26 stddev:714.52 (12.6%) stddev-sans-first:695.07 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: Values: 3156.6 3166.7 3175.1 3203.7 3216.1 5207.2 5230.7 5256.5 5275.6 5279.2 5395.8 5408.1 5413.5 5414.8 5437.0 4888.5 4915.0 4937.7 4942.3 4942.5 453.2 4962.6 5031.7 5034.5 5078.3 4965.3 4968.2 4983.7 4989.9 4992.1 4969.5 4988.9 4993.7 5008.1 5043.8 4807.4 4988.2 5001.7 5013.8 5046.7 5170.2 5219.3 5255.7 5273.4 5332.2 5753.4 5759.9 5774.0 5799.4 5813.6 5659.2 5758.1 5817.7 5839.5 5866.6 5463.4 5512.6 5514.6 5530.5 5571.2 5892.0 5904.9 5915.7 5920.4 5944.0 5497.3 5684.3 5699.0 5748.7 5757.4 5780.0 5781.1 5785.7 5865.4 5903.3 5754.9 5756.1 5777.3 5781.0 5797.2 5639.5 5745.5 5754.5 5810.0 5814.3 4446.4 4463.5 4479.1 4483.3 4502.1 3991.6 4006.6 4017.7 4041.2 4052.8 5356.8 5440.8 5462.2 5515.5 5556.5 5840.9 5952.7 5954.1 5966.2 5970.6 5569.4 5575.2 5589.8 5602.8 5605.0 4959.3 4995.6 5033.8 5079.9 5138.4 5903.5 5937.5 5939.2 5952.1 5957.3 5774.5 5885.5 5887.1 5888.2 5900.8 5833.5 5883.9 5917.9 5924.0 5987.9 5787.6 5796.4 5824.8 5852.5 5864.5 5704.7 5804.4 5923.8 5934.4 5936.4 5694.8 5731.2 5732.6 5747.7 5800.1 5684.5 5750.1 5779.4 5785.7 5789.1 5434.1 5578.9 5579.2 5599.8 5684.9 5666.3 5667.5 5680.8 5817.7 5874.0 5699.7 5725.8 5727.3 5747.2 5757.8 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49419.12 Median:38386.92 stddev:85355.70 (222.4%) stddev-sans-first:85516.33 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: Values: 567.6 597.6 619.7 627.5 628.1 37315.4 38362.2 38411.6 38498.0 38536.0 42193.0 42322.4 42732.6 42794.0 42808.4 8389.6 8469.3 8470.5 8488.7 8539.9 8426.5 8528.2 8532.7 8533.5 8544.4 8474.0 8560.3 8595.7 8596.4 8597.4 8564.1 8649.8 8683.2 8686.2 8690.9 8501.2 8646.8 8649.4 8653.8 8666.1 39300.0 39354.8 39666.0 39808.8 39863.2 3102.2 3183.6 3245.0 3289.9 3640.7 29615.6 29725.4 30652.8 31195.5 31631.8 3919.6 3925.5 3925.7 4034.6 4092.9 275621.0 276307.0 278173.0 278293.0 278818.0 53403.5 53732.2 66797.3 67749.0 67808.7 61433.0 61434.7 61528.0 61564.0 61766.0 14312.5 14534.4 14594.4 14695.5 14724.0 10536.7 10612.2 10632.0 10685.8 10703.7 20159.2 20729.8 20761.9 20762.2 20772.3 7912.4 7984.0 8055.6 8187.5 8227.4 911.6 947.9 953.2 1068.9 1080.7 28225.3 28503.4 32874.3 33423.7 33675.3 1005.9 1019.5 1045.4 1057.0 1114.9 14422.6 14548.6 14619.4 14749.9 14808.1 377117.0 377200.0 378707.0 382265.0 383554.0 53226.7 53489.2 53960.7 54186.5 54249.0 1274.4 1274.5 1288.2 1308.5 1311.4 26757.0 27597.6 27775.4 27867.3 28012.6 43885.3 44236.6 44353.0 44395.8 44614.0 309384.0 309694.0 310080.0 310725.0 311833.0 32773.5 43599.3 43842.3 44463.6 44624.6 43466.0 44134.1 44356.6 44402.8 44575.0 36592.0 36972.3 37156.0 37426.4 37905.8 36671.0 36907.0 37161.6 37238.4 37512.8 34763.0 40798.6 40991.6 41256.2 41448.4 28500.9 30269.8 32412.0 33468.7 33750.7 25222.0 25548.2 25628.4 25635.7 25950.0 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,466 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28871.55 Median:359.37 stddev:90873.61 (25286.9%) stddev-sans-first:91103.31 08:15:48 INFO - 2015-12-18 08:15:48,467 DEBUG : BROWSER_OUTPUT: Values: 357.6 361.1 361.8 362.1 367.0 16769.5 16835.2 17359.2 17428.0 18014.8 10690.8 11364.4 15715.5 15754.8 15813.9 4834.3 5189.2 5283.3 5321.2 5323.9 5309.3 5314.6 5321.7 5322.8 5338.7 3715.3 3820.8 5308.8 5641.3 5900.7 5373.5 5379.9 5398.9 5401.6 5405.9 5240.9 5279.9 5314.7 5437.8 5463.6 12181.7 12292.5 12321.9 16611.6 21366.7 1959.4 2015.4 2069.7 2129.6 2256.5 9337.0 9482.1 9552.0 9568.2 9748.2 1799.9 1843.9 1858.0 1889.7 1912.3 58784.0 60706.0 63486.0 64512.7 64799.0 10302.3 10329.6 10391.6 10394.1 10430.6 13708.0 13716.5 13820.7 13821.7 13822.9 6831.0 7130.9 7276.6 7335.6 7465.9 7074.9 7078.8 7103.4 7127.9 7143.9 4287.2 4431.8 4496.1 4575.7 4583.9 1872.8 1935.6 1939.8 1979.6 1991.6 402.5 418.5 423.9 865.8 923.2 10195.3 10545.9 10578.6 10756.6 10763.4 430.3 437.1 439.4 441.8 446.6 9516.8 10087.1 10150.2 10212.1 10231.5 546229.0 546308.0 550153.0 566098.0 567948.0 78502.6 78815.0 81686.0 82896.6 83522.6 1101.0 1101.8 1103.3 1123.0 1127.4 15716.4 15760.9 15850.9 15916.0 15926.7 34702.0 34999.0 35416.1 35625.1 37051.3 47466.5 49196.3 49242.3 49744.8 50035.0 9064.5 9132.6 9229.0 9265.4 9269.0 11028.7 11047.8 11063.4 11206.2 12231.4 2888.7 14123.8 14286.1 14317.7 14436.6 14006.2 14045.9 14365.4 14452.9 14929.4 14446.3 14787.5 14930.9 15122.4 15504.1 9457.0 9613.7 9853.3 10108.1 10138.9 13905.7 14282.2 14589.9 14871.8 15162.5 08:15:48 INFO - 2015-12-18 08:15:48,468 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,468 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4760.69 Median:5747.05 stddev:2333.39 (40.6%) stddev-sans-first:2326.22 08:15:48 INFO - 2015-12-18 08:15:48,468 DEBUG : BROWSER_OUTPUT: Values: 1395.5 1465.7 1570.3 1608.5 1715.9 6336.0 6516.6 6568.7 6614.3 6814.1 6765.8 6769.0 6843.0 6854.4 6855.6 2132.4 2176.0 2179.1 2181.0 2182.5 2111.9 2142.8 2172.0 2172.1 2173.4 2106.1 2109.4 2130.8 2141.6 2145.7 2095.8 2132.3 2134.0 2137.0 2140.9 2120.5 2126.8 2133.3 2139.6 2140.6 6742.9 6755.1 6760.8 6768.8 6805.4 4064.4 4068.9 4117.4 4118.1 4123.2 2861.4 2915.9 2918.2 2929.3 2930.1 1010.1 1048.5 1054.0 1070.9 1076.4 8794.4 8885.0 8889.1 8906.6 8929.6 3666.8 3677.2 3705.8 3722.5 3729.4 7477.5 7481.2 7496.4 7502.5 7509.9 4100.0 4103.9 4116.8 4142.5 4146.8 3712.0 3799.8 3828.5 3837.4 3866.3 3184.2 3204.6 3206.4 3216.3 3221.8 1013.1 1014.5 1018.8 1019.2 1021.2 1013.7 1018.7 1130.8 1263.5 1276.8 6144.5 6307.1 6459.5 6496.0 6497.1 1095.2 1097.8 1098.2 1110.4 1115.5 5148.6 5212.1 5226.8 5266.4 5269.6 7727.5 7744.4 7952.1 8015.6 8153.0 7934.5 7944.9 7947.5 7990.9 8004.3 6306.1 6362.7 6389.8 6422.2 6450.5 6133.7 6171.7 6174.4 6197.1 6199.5 7075.6 7149.4 7186.4 7258.6 7403.7 7322.9 7336.3 7343.4 7373.1 7389.7 5749.0 5811.8 5853.6 5893.4 5911.9 6517.8 6532.3 6540.9 6542.8 6557.2 6025.2 6037.5 6068.7 6085.9 6139.0 5690.1 5756.7 5764.8 5768.9 5809.2 6072.9 6079.9 6110.3 6112.3 6162.6 5698.2 5745.1 5754.9 5771.0 5776.9 5735.1 5735.9 5737.1 5758.0 5768.2 08:15:48 INFO - 2015-12-18 08:15:48,468 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,468 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5586.24 Median:6385.92 stddev:1745.01 (27.3%) stddev-sans-first:1704.59 08:15:48 INFO - 2015-12-18 08:15:48,469 DEBUG : BROWSER_OUTPUT: Values: 322.4 328.9 331.6 331.7 331.8 6387.0 6425.1 6433.2 6443.5 6549.1 6312.9 6344.4 6379.3 6390.7 6390.7 4748.6 4755.6 4760.0 4762.5 4765.2 4746.8 4754.5 4755.3 4765.4 4773.2 4625.5 4674.7 4678.3 4695.4 4705.0 4675.8 4679.9 4680.8 4683.9 4707.7 3995.7 3997.7 3998.1 4000.9 4009.0 6047.8 6053.5 6054.2 6064.7 6066.9 5357.5 5414.8 5444.3 5458.8 5461.0 6715.1 6729.9 6735.6 6745.4 6761.7 4413.7 4430.9 4433.9 4439.0 4444.0 6834.8 6924.6 6979.7 6987.4 7005.8 4580.3 4591.7 4596.7 4599.7 4600.4 4638.9 4642.3 4657.1 4657.3 4660.2 5472.8 5473.1 5482.4 5491.8 5492.3 6667.5 6674.5 6752.3 6769.4 6782.2 1581.6 1620.4 1636.9 1639.3 1642.1 1043.4 1046.3 1051.4 1055.8 1068.2 6375.0 6382.3 6384.8 6395.8 6395.9 6843.5 6881.6 6902.8 6919.9 6924.1 6201.4 6306.2 6332.7 6342.3 6370.0 3766.7 3767.5 3792.4 3796.3 3808.1 6948.5 7012.8 7026.0 7048.8 7052.2 7077.2 7091.3 7106.4 7107.2 7117.2 7200.9 7201.9 7204.8 7210.6 7218.4 7040.5 7042.6 7045.5 7045.6 7093.4 6880.3 6971.5 6981.7 6985.7 7005.0 7151.9 7155.5 7157.4 7177.0 7182.9 4445.2 4451.7 4469.7 4471.6 4490.4 6925.0 6991.9 7039.3 7049.8 7055.5 6873.5 6877.9 6881.4 6895.6 6903.9 6852.5 6859.6 6863.1 6871.2 6881.8 6571.1 6689.2 6690.0 6705.6 6709.6 6865.1 6867.9 6870.7 6873.0 6883.1 6632.8 6676.3 6705.0 6710.0 6721.5 08:15:48 INFO - 2015-12-18 08:15:48,469 DEBUG : BROWSER_OUTPUT: 08:15:48 INFO - 2015-12-18 08:15:48,469 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13835.87 Median:46221.89 stddev:16239.95 (35.1%) stddev-sans-first:16255.59 08:15:48 INFO - 2015-12-18 08:15:48,470 DEBUG : BROWSER_OUTPUT: Values: 709.0 709.3 739.5 830.5 840.7 12918.2 13201.7 13207.1 13226.3 13233.3 12652.9 13628.5 13642.0 13651.2 13670.1 803.7 809.9 819.1 821.7 823.1 811.3 811.9 815.9 816.8 817.4 862.6 865.1 871.2 871.7 874.4 852.5 853.6 858.5 862.0 863.0 631.4 633.4 633.6 634.4 636.2 13129.5 13355.9 13424.7 13427.0 13476.9 11300.7 11933.8 12316.4 12378.5 12983.0 9845.4 10199.2 10227.6 10617.5 10972.5 3602.7 3641.1 3643.1 3647.3 3752.4 48245.5 49261.3 49356.5 49373.8 49467.0 9595.0 9946.5 10032.2 10094.1 10501.7 17297.5 17335.2 17336.9 17343.1 17365.4 7248.5 7338.6 7485.8 7507.2 7509.0 3797.2 3942.3 3952.4 3959.2 3963.0 2974.8 2991.3 3002.2 3003.0 3004.2 533.0 537.7 538.1 552.8 560.9 495.9 571.3 607.0 633.4 669.0 7995.0 8237.2 8250.0 8371.4 8391.0 580.4 619.5 651.0 655.6 671.5 3662.8 3965.1 4098.4 4139.4 4356.8 58099.0 59182.1 59974.0 60022.0 60077.7 50791.2 50797.0 51201.5 51326.0 51426.5 45276.0 47232.5 47255.0 47519.0 48207.8 8025.6 8124.8 8247.1 8260.4 8277.2 34977.8 35071.6 35443.0 35866.8 35959.0 40964.6 45314.0 45818.3 45967.8 46476.0 10891.1 11180.4 11367.8 11373.3 11617.1 12092.4 12103.5 12172.7 12545.5 12627.4 10386.3 10600.9 10720.8 10875.3 11125.5 11293.2 11366.3 11499.7 11565.4 12020.6 9919.6 10113.2 10138.4 10494.2 10506.6 10312.9 10363.1 10422.9 10824.7 11024.3 8871.6 9223.9 9270.0 9511.1 9588.1 08:15:48 INFO - 2015-12-18 08:15:48,470 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:15:48 INFO - 2015-12-18 08:15:48,470 DEBUG : BROWSER_OUTPUT: 08:15:49 INFO - 2015-12-18 08:15:49,185 INFO : Browser exited with error code: 0 08:15:49 INFO - 2015-12-18 08:15:49,202 INFO : Completed test dromaeo_css (00:18:29) 08:15:49 INFO - 2015-12-18 08:15:49,202 INFO : Starting test kraken 08:15:49 INFO - 2015-12-18 08:15:49,202 DEBUG : operating with platform_type : linux_ 08:15:49 INFO - 2015-12-18 08:15:49,202 INFO : Initialising browser for kraken test... 08:15:49 INFO - 2015-12-18 08:15:49,208 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvLq5m2/profile http://localhost:56821/getInfo.html 08:15:53 INFO - 2015-12-18 08:15:53,964 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:15:53 INFO - 2015-12-18 08:15:53,964 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:15:53 INFO - 2015-12-18 08:15:53,964 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:15:53 INFO - 2015-12-18 08:15:53,964 DEBUG : BROWSER_OUTPUT: __metrics 08:15:54 INFO - 2015-12-18 08:15:54,072 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] 08:15:54 INFO - 2015-12-18 08:15:54,953 INFO : Browser initialized. 08:15:54 INFO - 2015-12-18 08:15:54,954 INFO : Running cycle 1/1 for kraken test... 08:15:54 INFO - 2015-12-18 08:15:54,954 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvLq5m2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:16:06 INFO - 2015-12-18 08:16:06,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 150953984 08:16:06 INFO - 2015-12-18 08:16:06,800 DEBUG : BROWSER_OUTPUT: 08:16:09 INFO - 2015-12-18 08:16:09,295 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:09 INFO - 2015-12-18 08:16:09,917 DEBUG : BROWSER_OUTPUT: RSS: Main: 152080384 08:16:09 INFO - 2015-12-18 08:16:09,917 DEBUG : BROWSER_OUTPUT: 08:16:12 INFO - 2015-12-18 08:16:12,539 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-dft) 08:16:13 INFO - 2015-12-18 08:16:13,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 150904832 08:16:13 INFO - 2015-12-18 08:16:13,142 DEBUG : BROWSER_OUTPUT: 08:16:15 INFO - 2015-12-18 08:16:15,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-fft) 08:16:16 INFO - 2015-12-18 08:16:16,563 DEBUG : BROWSER_OUTPUT: RSS: Main: 147509248 08:16:16 INFO - 2015-12-18 08:16:16,563 DEBUG : BROWSER_OUTPUT: 08:16:18 INFO - 2015-12-18 08:16:18,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-oscillator) 08:16:19 INFO - 2015-12-18 08:16:19,169 DEBUG : BROWSER_OUTPUT: RSS: Main: 146964480 08:16:19 INFO - 2015-12-18 08:16:19,170 DEBUG : BROWSER_OUTPUT: 08:16:21 INFO - 2015-12-18 08:16:21,389 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:21 INFO - 2015-12-18 08:16:21,970 DEBUG : BROWSER_OUTPUT: RSS: Main: 147439616 08:16:21 INFO - 2015-12-18 08:16:21,971 DEBUG : BROWSER_OUTPUT: 08:16:24 INFO - 2015-12-18 08:16:24,909 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-darkroom) 08:16:25 INFO - 2015-12-18 08:16:25,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 148537344 08:16:25 INFO - 2015-12-18 08:16:25,510 DEBUG : BROWSER_OUTPUT: 08:16:28 INFO - 2015-12-18 08:16:28,852 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-desaturate) 08:16:29 INFO - 2015-12-18 08:16:29,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 149872640 08:16:29 INFO - 2015-12-18 08:16:29,438 DEBUG : BROWSER_OUTPUT: 08:16:32 INFO - 2015-12-18 08:16:32,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56821/tests/kraken/driver.html?testName=json-parse-financial) 08:16:32 INFO - 2015-12-18 08:16:32,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 147783680 08:16:32 INFO - 2015-12-18 08:16:32,833 DEBUG : BROWSER_OUTPUT: 08:16:34 INFO - 2015-12-18 08:16:34,401 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56821/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:16:34 INFO - 2015-12-18 08:16:34,983 DEBUG : BROWSER_OUTPUT: RSS: Main: 148430848 08:16:34 INFO - 2015-12-18 08:16:34,983 DEBUG : BROWSER_OUTPUT: 08:16:36 INFO - 2015-12-18 08:16:36,781 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:16:37 INFO - 2015-12-18 08:16:37,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 149372928 08:16:37 INFO - 2015-12-18 08:16:37,387 DEBUG : BROWSER_OUTPUT: 08:16:39 INFO - 2015-12-18 08:16:39,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:16:40 INFO - 2015-12-18 08:16:40,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 149786624 08:16:40 INFO - 2015-12-18 08:16:40,081 DEBUG : BROWSER_OUTPUT: 08:16:42 INFO - 2015-12-18 08:16:42,537 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:16:43 INFO - 2015-12-18 08:16:43,138 DEBUG : BROWSER_OUTPUT: RSS: Main: 150306816 08:16:43 INFO - 2015-12-18 08:16:43,138 DEBUG : BROWSER_OUTPUT: 08:16:45 INFO - 2015-12-18 08:16:45,746 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:16:46 INFO - 2015-12-18 08:16:46,339 DEBUG : BROWSER_OUTPUT: RSS: Main: 149413888 08:16:46 INFO - 2015-12-18 08:16:46,339 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56821/tests/kraken/driver.html?testName=ai-astar) 08:16:48 INFO - 2015-12-18 08:16:48,292 DEBUG : BROWSER_OUTPUT: RSS: Main: 149389312 08:16:48 INFO - 2015-12-18 08:16:48,292 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,296 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:16:48 INFO - 2015-12-18 08:16:48,296 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:16:48 INFO - 2015-12-18 08:16:48,296 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:16:48 INFO - 2015-12-18 08:16:48,296 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;107;111;113;114;124;112;112;113;115 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;130;156;161;124;149;145;131;163;128 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;163;162;160;160;161;163;172;164 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |3;audio-fft;79;74;76;71;73;73;152;70;73;72 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;142;93;95;93;137;94;94;137 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;111;110;110;110;110;109;110;110 08:16:48 INFO - 2015-12-18 08:16:48,297 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;147;148;147;147;148;145;145 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;96;97;97;96;98;97;95;95;96 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;59;61;60;60;59;58;60;57;57 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;72;67;67;68;67;68;68;68;68 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;85;85;91;94;87;84;94;84 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;122;118;127;125;126;118;128;119;117 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;152;150;158;150;158;152;152;150;150;150 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;70;70;67;68;68;68;70;67;68 08:16:48 INFO - 2015-12-18 08:16:48,298 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:16:48 INFO - 2015-12-18 08:16:48,299 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:16:48 INFO - 2015-12-18 08:16:48,299 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,738 08:16:48 INFO - 2015-12-18 08:16:48,299 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:16:48 INFO - 2015-12-18 08:16:48,299 DEBUG : BROWSER_OUTPUT: __startTimestamp1450455408294__endTimestamp 08:16:48 INFO - 2015-12-18 08:16:48,299 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:4.30 (3.8%) stddev-sans-first:4.56 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: Values: 113.0 107.0 111.0 113.0 114.0 124.0 112.0 112.0 113.0 115.0 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:149.50 stddev:14.44 (9.7%) stddev-sans-first:15.13 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: Values: 150.0 130.0 156.0 161.0 124.0 149.0 145.0 131.0 163.0 128.0 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,307 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:3.48 (2.1%) stddev-sans-first:3.69 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 163.0 162.0 160.0 160.0 161.0 163.0 172.0 164.0 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.30 Median:73.00 stddev:24.97 (34.2%) stddev-sans-first:26.47 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: Values: 79.0 74.0 76.0 71.0 73.0 73.0 152.0 70.0 73.0 72.0 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.20 Median:93.50 stddev:21.77 (23.3%) stddev-sans-first:22.47 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 142.0 93.0 95.0 93.0 137.0 94.0 94.0 137.0 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 111.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 08:16:48 INFO - 2015-12-18 08:16:48,308 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 147.0 148.0 147.0 147.0 148.0 145.0 145.0 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:97.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 97.0 97.0 96.0 98.0 97.0 95.0 95.0 96.0 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,309 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.00 Median:59.50 stddev:1.33 (2.2%) stddev-sans-first:1.41 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: Values: 59.0 59.0 61.0 60.0 60.0 59.0 58.0 60.0 57.0 57.0 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.54 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: Values: 68.0 72.0 67.0 67.0 68.0 67.0 68.0 68.0 68.0 68.0 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,310 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.00 stddev:4.11 (4.8%) stddev-sans-first:4.18 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 85.0 85.0 91.0 94.0 87.0 84.0 94.0 84.0 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:123.50 stddev:4.12 (3.3%) stddev-sans-first:4.35 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: Values: 121.0 122.0 118.0 127.0 125.0 126.0 118.0 128.0 119.0 117.0 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.00 stddev:3.19 (2.1%) stddev-sans-first:3.38 08:16:48 INFO - 2015-12-18 08:16:48,311 DEBUG : BROWSER_OUTPUT: Values: 152.0 150.0 158.0 150.0 158.0 152.0 152.0 150.0 150.0 150.0 08:16:48 INFO - 2015-12-18 08:16:48,312 DEBUG : BROWSER_OUTPUT: 08:16:48 INFO - 2015-12-18 08:16:48,312 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.24 08:16:48 INFO - 2015-12-18 08:16:48,312 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 70.0 67.0 68.0 68.0 68.0 70.0 67.0 68.0 08:16:48 INFO - 2015-12-18 08:16:48,312 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:16:48 INFO - 2015-12-18 08:16:48,312 DEBUG : BROWSER_OUTPUT: 08:16:49 INFO - 2015-12-18 08:16:49,149 INFO : Browser exited with error code: 0 08:16:49 INFO - 2015-12-18 08:16:49,198 INFO : Completed test kraken (00:00:59) 08:16:49 INFO - 2015-12-18 08:16:49,198 INFO : Starting test v8_7 08:16:49 INFO - 2015-12-18 08:16:49,198 DEBUG : operating with platform_type : linux_ 08:16:49 INFO - 2015-12-18 08:16:49,198 INFO : Initialising browser for v8_7 test... 08:16:49 INFO - 2015-12-18 08:16:49,204 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNNbdxa/profile http://localhost:56821/getInfo.html 08:16:53 INFO - 2015-12-18 08:16:53,950 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:16:53 INFO - 2015-12-18 08:16:53,950 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:16:53 INFO - 2015-12-18 08:16:53,950 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:16:53 INFO - 2015-12-18 08:16:53,950 DEBUG : BROWSER_OUTPUT: __metrics 08:16:54 INFO - 2015-12-18 08:16:54,079 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] 08:16:55 INFO - 2015-12-18 08:16:55,021 INFO : Browser initialized. 08:16:55 INFO - 2015-12-18 08:16:55,022 INFO : Running cycle 1/1 for v8_7 test... 08:16:55 INFO - 2015-12-18 08:16:55,022 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNNbdxa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:17:06 INFO - 2015-12-18 08:17:06,822 DEBUG : BROWSER_OUTPUT: RSS: Main: 150990848 08:17:06 INFO - 2015-12-18 08:17:06,822 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56821/tests/v8_7/run.html (next: http://localhost:56821/tests/v8_7/run.html) 08:17:28 INFO - 2015-12-18 08:17:28,482 DEBUG : BROWSER_OUTPUT: RSS: Main: 147279872 08:17:28 INFO - 2015-12-18 08:17:28,483 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,484 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:17:28 INFO - 2015-12-18 08:17:28,484 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:17:28 INFO - 2015-12-18 08:17:28,484 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:17:28 INFO - 2015-12-18 08:17:28,484 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:17:28 INFO - 2015-12-18 08:17:28,484 DEBUG : BROWSER_OUTPUT: |0;Richards;169.92353440951572 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.70404984423675 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.2058906825262 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1053.740779768177 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |5;Earley;708.2152974504249 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |6;Boyer;12216.867469879518 08:17:28 INFO - 2015-12-18 08:17:28,485 DEBUG : BROWSER_OUTPUT: |7;RegExp;24926.829268292684 08:17:28 INFO - 2015-12-18 08:17:28,486 DEBUG : BROWSER_OUTPUT: |8;Splay;567.5368898978434 08:17:28 INFO - 2015-12-18 08:17:28,486 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 08:17:28 INFO - 2015-12-18 08:17:28,486 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:17:28 INFO - 2015-12-18 08:17:28,486 DEBUG : BROWSER_OUTPUT: __startTimestamp1450455448483__endTimestamp 08:17:28 INFO - 2015-12-18 08:17:28,487 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,487 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:17:28 INFO - 2015-12-18 08:17:28,487 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:17:28 INFO - 2015-12-18 08:17:28,487 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,487 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:169.92 Median:169.92 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: Values: 169.9 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.70 Median:194.70 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: Values: 194.7 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,489 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.21 Median:283.21 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,490 DEBUG : BROWSER_OUTPUT: Values: 283.2 08:17:28 INFO - 2015-12-18 08:17:28,490 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,490 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,490 DEBUG : BROWSER_OUTPUT: Values: 5186.5 08:17:28 INFO - 2015-12-18 08:17:28,490 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1053.74 Median:1053.74 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: Values: 1053.7 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:708.22 Median:708.22 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: Values: 708.2 08:17:28 INFO - 2015-12-18 08:17:28,491 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12216.87 Median:12216.87 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: Values: 12216.9 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24926.83 Median:24926.83 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: Values: 24926.8 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:567.54 Median:567.54 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,492 DEBUG : BROWSER_OUTPUT: Values: 567.5 08:17:28 INFO - 2015-12-18 08:17:28,493 DEBUG : BROWSER_OUTPUT: 08:17:28 INFO - 2015-12-18 08:17:28,493 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 08:17:28 INFO - 2015-12-18 08:17:28,493 DEBUG : BROWSER_OUTPUT: Values: 4850.2 08:17:28 INFO - 2015-12-18 08:17:28,493 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:17:28 INFO - 2015-12-18 08:17:28,493 DEBUG : BROWSER_OUTPUT: 08:17:29 INFO - 2015-12-18 08:17:29,389 INFO : Browser exited with error code: 0 08:17:29 INFO - 2015-12-18 08:17:29,406 INFO : Completed test v8_7 (00:00:40) 08:17:29 INFO - 2015-12-18 08:17:29,519 INFO : Completed test suite (00:20:10) 08:17:29 INFO - 2015-12-18 08:17:29,519 DEBUG : Working with test: dromaeo_css 08:17:29 INFO - 2015-12-18 08:17:29,520 DEBUG : Generating results file: dromaeo_css 08:17:29 INFO - 2015-12-18 08:17:29,521 DEBUG : Working with test: kraken 08:17:29 INFO - 2015-12-18 08:17:29,521 DEBUG : Generating results file: kraken 08:17:29 INFO - 2015-12-18 08:17:29,521 INFO : javascript benchmark 08:17:29 INFO - 2015-12-18 08:17:29,521 DEBUG : Working with test: v8_7 08:17:29 INFO - 2015-12-18 08:17:29,521 DEBUG : Generating results file: v8_7 08:17:29 INFO - 2015-12-18 08:17:29,522 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:17:29 INFO - 2015-12-18 08:17:29,575 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 08:17:29 INFO - 2015-12-18 08:17:29,575 DEBUG : process_Request line: dromaeo_css 6860.06 graph.html#tests=[[72,64,43]] 08:17:29 INFO - 2015-12-18 08:17:29,575 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:17:29 INFO - 2015-12-18 08:17:29,610 DEBUG : process_Request line: kraken 1518.80 graph.html#tests=[[232,64,43]] 08:17:29 INFO - 2015-12-18 08:17:29,610 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:17:29 INFO - 2015-12-18 08:17:29,721 DEBUG : process_Request line: v8_7 21436.46 graph.html#tests=[[230,64,43]] 08:17:29 INFO - 2015-12-18 08:17:29,722 INFO : javascript benchmark 08:17:29 INFO - 2015-12-18 08:17:29,726 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3156.6369867465237, 3166.683331666832, 3175.0633563098913, 3203.7276831469317, 3216.147720853974, 5207.192885102811, 5230.660007099541, 5256.474352564742, 5275.55157811586, 5279.208118782185, 5395.81114660987, 5408.134698448249, 5413.53984911282, 5414.7834086636585, 5436.972815135929, 4888.486708895568, 4915.016996600685, 4937.654364194508, 4942.332785074005, 4942.505749425047, 453.2226249627427, 4962.5533701966815, 5031.698098114125, 5034.496550344973, 5078.289039534468, 4965.329680493123, 4968.180250258721, 4983.72589507577, 4989.90020199594, 4992.126377883874, 4969.478204788481, 4988.925166122511, 4993.700377977297, 5008.123578373807, 5043.773030213649, 4807.447143578498, 4988.226824842155, 5001.7499125043605, 5013.824516141917, 5046.697198168122, 5170.224466330043, 5219.295395121648, 5255.6583822051825, 5273.419923808378, 5332.173513105475, 5753.367129615739, 5759.884802303931, 5773.971130144364, 5799.3620701722775, 5813.6221145625395, 5659.236003139565, 5758.1362795581, 5817.680027598481, 5839.503642190419, 5866.6480011199465, 5463.371712253102, 5512.641678290898, 5514.6139770216105, 5530.474604912567, 5571.220029195916, 5891.9705401473575, 5904.911426328523, 5915.733791979371, 5920.437558431942, 5944.0, 5497.312835895513, 5684.317881854204, 5699.0, 5748.655080695172, 5757.366689664133, 5779.971100144472, 5781.103928891029, 5785.681787501644, 5865.4427829357, 5903.26209223847, 5754.942450575524, 5756.079027355687, 5777.335606405317, 5781.0, 5797.188393624896, 5639.520640745583, 5745.482906538432, 5754.539636829044, 5809.970950145223, 5814.3313518945015, 4446.3552784846015, 4463.486699029589, 4479.059397526491, 4483.282674772021, 4502.077074199781, 3991.5808840071954, 4006.6193711597934, 4017.678585713136, 4041.1917616476235, 4052.77709725962, 5356.7857285708915, 5440.80302333486, 5462.153366228241, 5515.531179849758, 5556.499915007668, 5840.941590584125, 5952.732127054206, 5954.106883967353, 5966.164736936832, 5970.641761494324, 5569.359523654751, 5575.163725441135, 5589.776408943597, 5602.803901863496, 5605.047141985872, 4959.256111583291, 4995.575376093073, 5033.79864805411, 5079.949200508022, 5138.434772175057, 5903.527717782567, 5937.465628093492, 5939.227900372924, 5952.077427998667, 5957.255343082114, 5774.538036956949, 5885.499732522612, 5887.116932460165, 5888.17565540816, 5900.76396944126, 5833.4749872511675, 5883.882322353444, 5917.852053698692, 5924.022536281465, 5987.9401205989125, 5787.594868359343, 5796.42035796417, 5824.796132135355, 5852.5317974561085, 5864.530837532901, 5704.657720536781, 5804.419558044162, 5923.756011237689, 5934.43622855828, 5936.406359364029, 5694.829155125353, 5731.19763233156, 5732.57005724564, 5747.74135163927, 5800.071988481992, 5684.459976302244, 5750.0512415450985, 5779.393163717749, 5785.710714464344, 5789.073748200268, 5434.10337294342, 5578.916316255179, 5579.218909352613, 5599.80400685974, 5684.857878553069, 5666.263385759825, 5667.4615911488345, 5680.772769089355, 5817.70911454434, 5874.0, 5699.715014249354, 5725.770969161187, 5727.284089488814, 5747.166660834239, 5757.798477053283], "unit": "score", "name": "dojo.html", "value": 5337.031534722289}, {"lowerIsBetter": false, "replicates": [567.5629765080888, 597.554821657865, 619.6808643548574, 627.5168120547179, 628.1488582970078, 37315.44026839596, 38362.23275534488, 38411.61588384115, 38498.0, 38536.0, 42193.0, 42322.36516452248, 42732.57267427325, 42794.0, 42808.35787463183, 8389.622466988985, 8469.280111190556, 8470.534120623362, 8488.74533763988, 8539.871901921477, 8426.452280601741, 8528.189821966935, 8532.658693652247, 8533.487990720545, 8544.444611100289, 8473.957630211871, 8560.272376847975, 8595.742127736177, 8596.398252122353, 8597.355198360148, 8564.100769419214, 8649.827003459959, 8683.218510334069, 8686.218240358397, 8690.869636955451, 8501.19238672325, 8646.783830404227, 8649.437786543918, 8653.783655408602, 8666.133386661284, 39300.0, 39354.803225983975, 39666.0, 39808.80095599532, 39863.20273594541, 3102.2399512119673, 3183.6338821035647, 3245.042712399848, 3289.851956661956, 3640.7087433005295, 29615.555766663518, 29725.40549189026, 30652.8467357664, 31195.532067019012, 31631.84184079088, 3919.6080391960577, 3925.548561915388, 3925.7448265862627, 4034.616711412411, 4092.877213683594, 275621.0, 276307.0, 278173.0, 278293.0, 278818.0, 53403.46596534063, 53732.194017088994, 66797.33202668009, 67749.0, 67808.6609566959, 61433.0, 61434.69282653558, 61528.0, 61564.0, 61766.0, 14312.499062532761, 14534.418623255162, 14594.41622335116, 14695.485658002133, 14724.0, 10536.736581585521, 10612.204084693525, 10632.043116119587, 10685.83971240432, 10703.678889633324, 20159.19363225484, 20729.786883033154, 20761.89619051895, 20762.169513219604, 20772.272970445963, 7912.406569507195, 7983.960080199678, 8055.63749631268, 8187.549684767395, 8227.382946279053, 911.5988964855575, 947.8530827721714, 953.1897886796168, 1068.8984546468073, 1080.670395529371, 28225.294367640974, 28503.429931401257, 32874.342513149604, 33423.65492485961, 33675.326493470486, 1005.9236616819933, 1019.454591793387, 1045.3727763341933, 1056.9894301057045, 1114.9386783726973, 14422.639434014023, 14548.63628409277, 14619.415223390944, 14749.852501475063, 14808.111513309235, 377117.0, 377200.0, 378707.0, 382265.0, 383554.0, 53226.73386633042, 53489.1976620351, 53960.73019634877, 54186.45813541893, 54249.0, 1274.3564499927663, 1274.5475356248398, 1288.1884412819863, 1308.483149156097, 1311.4229738914846, 26757.0, 27597.58603620907, 27775.44449111047, 27867.303317416416, 28012.579811303254, 43885.34171987359, 44236.55763442325, 44353.0, 44395.77802110969, 44614.0, 309384.0, 309694.0, 310080.0, 310725.0, 311833.0, 32773.50839737358, 43599.34600981051, 43842.342364863915, 44463.55536444594, 44624.55375446334, 43466.0, 44134.11731765411, 44356.556434435246, 44402.77798610986, 44575.0, 36592.0, 36972.26055478929, 37156.0, 37426.43860342151, 37905.81047094747, 36671.0, 36907.0, 37161.62838371582, 37238.44142337921, 37512.812435937645, 34763.0, 40798.59201407948, 40991.590084098774, 41256.174876502904, 41448.3782743253, 28500.85749571239, 30269.789208431415, 32412.0, 33468.66531334753, 33750.66249337475, 25222.0, 25548.23355299344, 25628.359291017874, 25635.743642563335, 25950.0], "unit": "score", "name": "ext.html", "value": 18828.232360778587}, {"lowerIsBetter": false, "replicates": [357.6298531020394, 361.11166530335373, 361.8299399282337, 362.0983750461349, 366.97064234861216, 16769.49691509255, 16835.15824208789, 17359.218835152416, 17428.0, 18014.81985180148, 10690.839637405426, 11364.431778411088, 15715.528534143994, 15754.842451575481, 15813.920930395332, 4834.250691142878, 5189.247559103927, 5283.286756287909, 5321.228421878825, 5323.866903327409, 5309.30978972733, 5314.601404894629, 5321.680699158043, 5322.787088516474, 5338.733063346836, 3715.2569486102752, 3820.7707537547835, 5308.761105750251, 5641.323041235058, 5900.675462849542, 5373.543248823835, 5379.919301210485, 5398.8920221595745, 5401.594880383967, 5405.945940540583, 5240.868978275574, 5279.86800329991, 5314.681119132826, 5437.755301011464, 5463.562914966793, 12181.695457613541, 12292.508299668003, 12321.876781232162, 16611.584710382336, 21366.679499807517, 1959.3925882976323, 2015.3699422253953, 2069.6791997240452, 2129.627315219846, 2256.4810093678384, 9336.953315233448, 9482.146606805421, 9552.0, 9568.234541236754, 9748.220142388593, 1799.8560115190885, 1843.907804609764, 1857.9535511612182, 1889.7070954002152, 1912.2924517928316, 58784.0, 60706.0, 63486.0, 64512.67743661298, 64799.0, 10302.33034852732, 10329.586816527255, 10391.584336626602, 10394.116500097425, 10430.582776689, 13708.0, 13716.451341946211, 13820.654483637789, 13821.654458638413, 13822.93088534551, 6830.965845170742, 7130.92869071313, 7276.636168191675, 7335.633218339169, 7465.85068298631, 7074.858502829915, 7078.752243671447, 7103.431725461951, 7127.8574428512175, 7143.857122857514, 4287.228298906227, 4431.8448854289945, 4496.123255965055, 4575.725456472623, 4583.908321833612, 1872.8220819022172, 1935.5935253596622, 1939.776925653568, 1979.604079184169, 1991.6116357310186, 402.5431135660987, 418.52915470096144, 423.9109786944776, 865.7705707987388, 923.224491427191, 10195.337303075275, 10545.894541054644, 10578.57685692579, 10756.623518176826, 10763.3541987481, 430.29431731959613, 437.0625009354916, 439.387055058192, 441.785733919049, 446.5913688974552, 9516.809663806685, 10087.092161705335, 10150.238732095122, 10212.08091271789, 10231.488425578691, 546229.0, 546308.0, 550153.0, 566098.0, 567948.0, 78502.6074869622, 78815.0, 81686.0, 82896.58551707203, 83522.58238708768, 1101.0126721268189, 1101.8126918423886, 1103.2553026706942, 1123.017359810166, 1127.3517727306833, 15716.371345146523, 15760.92119539395, 15850.920745396199, 15916.0450372978, 15926.68146637084, 34702.0, 34999.0, 35416.114597135274, 35625.1093722659, 37051.258974819815, 47466.525334747595, 49196.26205606991, 49242.26136608025, 49744.75127624339, 50035.0, 9064.50145242018, 9132.634694612407, 9229.0, 9265.351425400157, 9268.95365523168, 11028.669139925814, 11047.779044419227, 11063.391513466837, 11206.159538034843, 12231.449584768528, 2888.6822449530114, 14123.833469126426, 14286.071405358618, 14317.713645727235, 14436.566902992927, 14006.229657368942, 14045.929770351082, 14365.425382984564, 14452.855471445151, 14929.402823886923, 14446.277686115862, 14787.482438114614, 14930.925345373204, 15122.395104196148, 15504.069755814688, 9456.952715236379, 9613.711588652352, 9853.310268281151, 10108.140808031105, 10138.898611013796, 13905.72188556203, 14282.214478203383, 14589.85410145885, 14871.774386834113, 15162.545123646309], "unit": "score", "name": "jquery.html", "value": 8280.824914242203}, {"lowerIsBetter": false, "replicates": [1395.4697215058277, 1465.6995315960226, 1570.2933679844066, 1608.541565653789, 1715.9227834747435, 6336.0, 6516.576422532538, 6568.73725050998, 6614.305497922709, 6814.1482314710665, 6765.7970260892125, 6769.0, 6842.96578517108, 6854.417374523159, 6855.554388964726, 2132.3816093332953, 2175.977290673389, 2179.0521123311332, 2180.9890950545223, 2182.4980254541465, 2111.915523379063, 2142.817860481861, 2172.0334451169238, 2172.0877231562704, 2173.3805865328386, 2106.0838535237144, 2109.4409981354875, 2130.7762684918093, 2141.582391433671, 2145.6566949288117, 2095.779943105975, 2132.3389749177727, 2134.039682143034, 2137.0, 2140.9357719268446, 2120.4804822818355, 2126.766055733868, 2133.28534940795, 2139.5506943541873, 2140.636091864387, 6742.898856517156, 6755.088063111466, 6760.76337328196, 6768.796936091925, 6805.421539169177, 4064.390341448777, 4068.9389659155136, 4117.402976568381, 4118.135191609766, 4123.2165888481395, 2861.3991061876823, 2915.8687859046395, 2918.1537354167267, 2929.282325830164, 2930.1356099950567, 1010.0858722855827, 1048.5176818663347, 1053.9947300263527, 1070.9410982395966, 1076.4187338837019, 8794.42836215644, 8885.0, 8889.111088891059, 8906.643734250621, 8929.553522323858, 3666.7716315034227, 3677.209399978964, 3705.7776533408082, 3722.4788529605876, 3729.4032954727113, 7477.476576639583, 7481.21447248042, 7496.400287976949, 7502.474826762074, 7509.924900751032, 4100.0, 4103.8974025649595, 4116.8147433364975, 4142.46148000764, 4146.771927544014, 3712.0348709335767, 3799.848006079781, 3828.5405751310022, 3837.3860182370686, 3866.3040652682225, 3184.1561986073707, 3204.5834041574913, 3206.422843888076, 3216.260260140181, 3221.7744757866726, 1013.083159740436, 1014.5434554450527, 1018.7707765752647, 1019.2101121630648, 1021.2085633633844, 1013.7364285285879, 1018.6587493189865, 1130.8077626803463, 1263.4693428759942, 1276.8020956751718, 6144.5084393248435, 6307.117003619497, 6459.547831651833, 6496.0255961606135, 6497.057926600616, 1095.2497539185683, 1097.7530055737554, 1098.1873413673825, 1110.3615421132881, 1115.4924509348227, 5148.562372198405, 5212.139996900545, 5226.790928362823, 5266.447023062523, 5269.604779641542, 7727.459077864495, 7744.380449564022, 7952.125266220711, 8015.559144247006, 8153.0, 7934.484258523177, 7944.880826787603, 7947.483413578099, 7990.880136797953, 8004.319632831158, 6306.148669929593, 6362.713677884506, 6389.7763578274535, 6422.165118534654, 6450.516211284173, 6133.74258277061, 6171.660558669137, 6174.413430724254, 6197.1324014638885, 6199.535034872493, 7075.646217689198, 7149.392301654418, 7186.389156921511, 7258.564486130849, 7403.666834992546, 7322.890156647761, 7336.339729424378, 7343.375813055736, 7373.115226172894, 7389.704411823682, 5749.0, 5811.82564523065, 5853.560982926212, 5893.410658934073, 5911.940880591139, 6517.771877984248, 6532.281449040506, 6540.934590654033, 6542.8364290893105, 6557.2131344239, 6025.246844144482, 6037.547183961133, 6068.696565171812, 6085.908711369421, 6139.048447490646, 5690.089585666274, 5756.65460072397, 5764.827055188351, 5768.855778605568, 5809.215755872903, 6072.8785424292155, 6079.939200607937, 6110.297315808739, 6112.266528016667, 6162.630242185304, 5698.202251684685, 5745.109508026263, 5754.9136762947755, 5770.971145144247, 5776.942230577641, 5735.11105778605, 5735.913961290667, 5737.139429085671, 5758.0, 5768.221290125779], "unit": "score", "name": "mootools.html", "value": 4006.860712028698}, {"lowerIsBetter": false, "replicates": [322.3874638187444, 328.8700963119568, 331.6318886036499, 331.6964977046003, 331.77439341247947, 6387.0, 6425.13260709804, 6433.163688720466, 6443.516736244777, 6549.115869357635, 6312.873742525147, 6344.429001389874, 6379.330170332118, 6390.680465976706, 6390.712417941192, 4748.596369308613, 4755.619550435957, 4759.952400475986, 4762.547557982003, 4765.237561990074, 4746.833860814872, 4754.453237877637, 4755.334253204537, 4765.42814862217, 4773.236282194849, 4625.4911959684405, 4674.719516829001, 4678.298255261704, 4695.413073365829, 4705.0, 4675.789589468465, 4679.906401871961, 4680.812767489313, 4683.906321873577, 4707.670463067587, 3995.700322475826, 3997.6801855851754, 3998.1204135090234, 4000.9399859002137, 4008.959910400888, 6047.78832740852, 6053.485453736438, 6054.182685337467, 6064.727087281083, 6066.939330606681, 5357.491038351351, 5414.756335964852, 5444.265024221719, 5458.754356053987, 5461.0, 6715.093462382565, 6729.865402691968, 6735.595864248161, 6745.359190876908, 6761.695723692445, 4413.7351758894565, 4430.933535996963, 4433.866983990485, 4439.0, 4443.955560444387, 6834.760783372608, 6924.58452492852, 6979.685914133876, 6987.406070484015, 7005.789826305219, 4580.267157254823, 4591.701539399928, 4596.747178905125, 4599.747013914267, 4600.401947746838, 4638.863478447802, 4642.280446530725, 4657.115148121846, 4657.3246879202325, 4660.207764679944, 5472.753726082336, 5473.124300111963, 5482.424345443751, 5491.835244942578, 5492.286002819608, 6667.533272670867, 6674.499412544079, 6752.291009444036, 6769.356911093439, 6782.2200446948245, 1581.6204111013503, 1620.3842539835023, 1636.9345226191058, 1639.3360688921007, 1642.1050527462562, 1043.3687618990552, 1046.2937517175876, 1051.4427353502656, 1055.8205105132147, 1068.220199254533, 6375.0, 6382.329855365213, 6384.84037899047, 6395.808125756142, 6395.904061439082, 6843.452523798084, 6881.587104773729, 6902.827429314207, 6919.930800691928, 6924.099867017256, 6201.410865967726, 6306.243250809932, 6332.715027823759, 6342.334054924167, 6370.0, 3766.6798322142927, 3767.453719210699, 3792.4311353296675, 3796.3166630006313, 3808.143167787281, 6948.5483443576, 7012.824679382954, 7026.0, 7048.823779405453, 7052.188998265266, 7077.1507419109, 7091.326323999212, 7106.360427561441, 7107.218205997337, 7117.217106118323, 7200.8919866201, 7201.89197162043, 7204.819879503054, 7210.603416812126, 7218.422526197892, 7040.542364746275, 7042.647867606702, 7045.471589630695, 7045.612491313027, 7093.361597456254, 6880.31196880328, 6971.51199416036, 6981.685824138028, 6985.685644145924, 7005.0, 7151.8569628608175, 7155.499115061895, 7157.427405807627, 7176.96411517939, 7182.928170718435, 4445.222086134849, 4451.732896026248, 4469.687121901436, 4471.597556219956, 4490.393796837385, 6925.0, 6991.860162796817, 7039.296070392919, 7049.7885063448175, 7055.470839686941, 6873.48448866347, 6877.862442751017, 6881.380675739208, 6895.586264824126, 6903.930960690329, 6852.451803855779, 6859.622720750406, 6863.07348507965, 6871.209810871814, 6881.759138430331, 6571.145751052332, 6689.163854518185, 6689.966550167218, 6705.631190284581, 6709.563878347892, 6865.141857267841, 6867.931320686929, 6870.690818913261, 6873.0, 6883.105196324646, 6632.767853125118, 6676.332366763285, 6705.0, 6709.966450167718, 6721.462283017445], "unit": "score", "name": "prototype.html", "value": 5003.601108054019}, {"lowerIsBetter": false, "replicates": [709.035711432452, 709.3332267668393, 739.5082270290254, 830.5473516933271, 840.7309660908504, 12918.160319579221, 13201.669958251048, 13207.141535800167, 13226.338683065844, 13233.338333083344, 12652.873471265284, 13628.454861805541, 13642.045056846026, 13651.249181295025, 13670.111442756213, 803.7387848949086, 809.8582748019088, 819.115355416152, 821.7329367955408, 823.074041703084, 811.3184924663282, 811.8944537210155, 815.8898548695939, 816.8039670479102, 817.4114637461018, 862.6161358195574, 865.1478293880523, 871.1941454154882, 871.6818361298112, 874.4010352908247, 852.4672079950032, 853.5561508015859, 858.4505916213628, 862.0431321233426, 862.9741107766777, 631.356016862802, 633.4488994574749, 633.6008314761717, 634.3878157577959, 636.2492259134219, 13129.540466083641, 13355.866441335656, 13424.664383390396, 13427.0, 13476.86523134766, 11300.660980170607, 11933.820992685116, 12316.384180790925, 12378.504859805596, 12983.02627302956, 9845.351331710657, 10199.18406527476, 10227.6420325289, 10617.522211500502, 10972.506237219264, 3602.7297952653657, 3641.0715267606593, 3643.107438677541, 3647.325244829715, 3752.3808571585655, 48245.517544824455, 49261.261081083816, 49356.50643493519, 49373.75313123447, 49467.0, 9595.0, 9946.45294508809, 10032.197424206046, 10094.14199793026, 10501.684949451528, 17297.481075567754, 17335.21991510385, 17336.913315433343, 17343.13284335778, 17365.392211272545, 7248.528845625017, 7338.559686418832, 7485.7754267372065, 7507.1742108368035, 7508.962455187689, 3797.1646237827636, 3942.290387730236, 3952.446657467957, 3959.208158368338, 3962.980185099056, 2974.7768917331287, 2991.326951435953, 3002.1744020394567, 3002.9549556756665, 3004.2038859702197, 532.9760160792773, 537.7015756255238, 538.0879409401027, 552.7512619321321, 560.9326880774333, 495.86859482237236, 571.2545128607208, 607.0388782159242, 633.4204203154154, 669.0432681265739, 7995.0, 8237.217464340878, 8250.0, 8371.372147088992, 8390.958045209856, 580.4456743809652, 619.5322531488697, 651.04621729167, 655.632845606462, 671.5030877150873, 3662.8351724171944, 3965.147493288957, 4098.446709694213, 4139.3583994480905, 4356.825726970886, 58099.0, 59182.11226831601, 59974.0, 60022.0, 60077.69961150254, 50791.23813142806, 50797.0, 51201.487985119675, 51326.0, 51426.48573514292, 45276.0, 47232.5276747235, 47255.0, 47519.0, 48207.75896120497, 8025.638846251816, 8124.796880078045, 8247.134050924567, 8260.421770476249, 8277.172282771675, 34977.825110874284, 35071.64928350786, 35443.0, 35866.82066589651, 35959.0, 40964.59035409727, 45314.0, 45818.312725309814, 45967.77016114898, 46476.0, 10891.128709703364, 11180.385078820744, 11367.772644547229, 11373.260738052, 11617.128715346551, 12092.395380230777, 12103.515859365527, 12172.695682608006, 12545.463554828944, 12627.431765570755, 10386.272960892666, 10600.946995264974, 10720.785584288114, 10875.293105948089, 11125.499352528994, 11293.153013523854, 11366.318020918772, 11499.712507187387, 11565.421728913689, 12020.579279725167, 9919.603215871573, 10113.241506886865, 10138.391696498235, 10494.212934029825, 10506.579736810441, 10312.948435257777, 10363.097943060637, 10422.947885260524, 10824.729381765519, 11024.283421577571, 8871.600777964877, 9223.861642075508, 9269.953650231706, 9511.143997040022, 9588.089131532493], "unit": "score", "name": "yui.html", "value": 6246.76198619663}], "name": "dromaeo_css", "value": 6860.05751224136}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 107.0, 111.0, 113.0, 114.0, 124.0, 112.0, 112.0, 113.0, 115.0], "unit": "score", "name": "ai-astar", "value": 113.4}, {"lowerIsBetter": true, "replicates": [150.0, 130.0, 156.0, 161.0, 124.0, 149.0, 145.0, 131.0, 163.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 163.0, 162.0, 160.0, 160.0, 161.0, 163.0, 172.0, 164.0], "unit": "score", "name": "audio-dft", "value": 162.9}, {"lowerIsBetter": true, "replicates": [79.0, 74.0, 76.0, 71.0, 73.0, 73.0, 152.0, 70.0, 73.0, 72.0], "unit": "score", "name": "audio-fft", "value": 81.3}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 142.0, 93.0, 95.0, 93.0, 137.0, 94.0, 94.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 107.2}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 147.0, 148.0, 147.0, 147.0, 148.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 97.0, 97.0, 96.0, 98.0, 97.0, 95.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [59.0, 59.0, 61.0, 60.0, 60.0, 59.0, 58.0, 60.0, 57.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 59.0}, {"lowerIsBetter": true, "replicates": [68.0, 72.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 85.0, 85.0, 91.0, 94.0, 87.0, 84.0, 94.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.3}, {"lowerIsBetter": true, "replicates": [121.0, 122.0, 118.0, 127.0, 125.0, 126.0, 118.0, 128.0, 119.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.1}, {"lowerIsBetter": true, "replicates": [152.0, 150.0, 158.0, 150.0, 158.0, 152.0, 152.0, 150.0, 150.0, 150.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.2}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 70.0, 67.0, 68.0, 68.0, 68.0, 70.0, 67.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1518.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [169.92353440951572], "unit": "score", "name": "Richards", "value": 207.75227000000007}, {"lowerIsBetter": false, "replicates": [194.70404984423675], "unit": "score", "name": "DeltaBlue", "value": 339.58204800000004}, {"lowerIsBetter": false, "replicates": [283.2058906825262], "unit": "score", "name": "Encrypt", "value": 939.8851109999996}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [1053.740779768177], "unit": "score", "name": "RayTrace", "value": 702.2495610000001}, {"lowerIsBetter": false, "replicates": [708.2152974504249], "unit": "score", "name": "Earley", "value": 941.0457559999996}, {"lowerIsBetter": false, "replicates": [12216.867469879518], "unit": "score", "name": "Boyer", "value": 54.55269132149902}, {"lowerIsBetter": false, "replicates": [24926.829268292684], "unit": "score", "name": "RegExp", "value": 36.54636497064577}, {"lowerIsBetter": false, "replicates": [567.5368898978434], "unit": "score", "name": "Splay", "value": 143.58714199999997}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 21436.45637160266}]} 08:17:29 INFO - RETURN: dromaeo_css: 6860.06 08:17:29 INFO - RETURN: kraken: 1518.80 08:17:29 INFO - RETURN: v8_7: 21436.46 08:17:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21436.46"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1518.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "6860.06"}}} 08:17:29 INFO - Return code: 0 08:17:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:17:29 INFO - # TBPL SUCCESS # 08:17:29 INFO - Running post-action listener: _resource_record_post_action 08:17:29 INFO - Running post-run listener: _resource_record_post_run 08:17:30 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 94208; Write bytes: 559009792; Read time: 100; Write time: 574544 08:17:30 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 234524672; Read time: 0; Write time: 367960 08:17:30 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 94208; Write bytes: 320286720; Read time: 100; Write time: 205748 08:17:30 INFO - Running post-run listener: _upload_blobber_files 08:17:30 INFO - Blob upload gear active. 08:17:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:17:30 INFO - Copying logs to upload dir... 08:17:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1261.672398 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-18 08:17:30.843981) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 08:17:30.847819) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010103 build_url: 'https://queue.taskcluster.net/v1/task/Pbf7VlsCQAycTDX3t7rx9A/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-18 08:17:30.898196) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 08:17:30.898527) ========= 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=70a720fcaf18edea6683c2dc000001ad-1450454184.868783-1524835585 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 08:17:30.948222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 08:17:30.948543) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 08:17:30.948823) ========= ========= Total master_lag: 0.34 =========