builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-027 starttime: 1450323880.32 results: success (0) buildid: 20151216173017 builduid: 6c8aa2c12a4a4e9fb83fe453fdb3766b revision: a8f8ec75a2a9b81aa3963ff6c330133adbd095d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.317137) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.317685) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.318039) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006374 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.339525) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.339859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.360766) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.361144) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.030408 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.410351) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.410694) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.411146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.411472) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 19:44:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 83.6M=0s 2015-12-16 19:44:40 (83.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165410 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.589963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.590556) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.064154 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:40.670579) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 19:44:40.670957) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a8f8ec75a2a9b81aa3963ff6c330133adbd095d5 --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 a8f8ec75a2a9b81aa3963ff6c330133adbd095d5 --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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 19:44:40,794 truncating revision to first 12 chars 2015-12-16 19:44:40,794 Setting DEBUG logging. 2015-12-16 19:44:40,794 attempt 1/10 2015-12-16 19:44:40,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a8f8ec75a2a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 19:44:42,080 unpacking tar archive at: fx-team-a8f8ec75a2a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.573850 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 19:44:42.258640) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:42.259012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:42.267261) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:42.267696) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 19:44:42.268205) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2015-12-16 19:44:42.268572) ========= /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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:44:42 INFO - MultiFileLogger online at 20151216 19:44:42 in /builds/slave/test-pgo 19:44:42 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 19:44:42 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:44:42 INFO - {'append_to_log': False, 19:44:42 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:44:42 INFO - 'blob_upload_branch': 'Fx-Team', 19:44:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:44:42 INFO - 'branch': 'Fx-Team', 19:44:42 INFO - 'buildbot_json_path': 'buildprops.json', 19:44:42 INFO - 'config_files': ('talos/linux_config.py',), 19:44:42 INFO - 'default_actions': ('clobber', 19:44:42 INFO - 'read-buildbot-config', 19:44:42 INFO - 'download-and-extract', 19:44:42 INFO - 'populate-webroot', 19:44:42 INFO - 'create-virtualenv', 19:44:42 INFO - 'install', 19:44:42 INFO - 'run-tests'), 19:44:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:44:42 INFO - 'download_minidump_stackwalk': True, 19:44:42 INFO - 'download_symbols': 'ondemand', 19:44:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:44:42 INFO - 'tooltool.py': '/tools/tooltool.py', 19:44:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:44:42 INFO - '/tools/misc-python/virtualenv.py')}, 19:44:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:44:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:44:42 INFO - 'installer_path': 'installer.exe', 19:44:42 INFO - 'log_level': 'info', 19:44:42 INFO - 'log_name': 'talos', 19:44:42 INFO - 'log_to_console': True, 19:44:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:44:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:44:42 INFO - 'opt_config_files': (), 19:44:42 INFO - 'pip_index': False, 19:44:42 INFO - 'sps_profile': False, 19:44:42 INFO - 'sps_profile_interval': 0, 19:44:42 INFO - 'suite': 'dromaeojs-e10s', 19:44:42 INFO - 'system_bits': '64', 19:44:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:44:42 INFO - 'title': 'talos-linux64-ix-027', 19:44:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:44:42 INFO - 'use_talos_json': True, 19:44:42 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:44:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:44:42 INFO - 'work_dir': 'build'} 19:44:42 INFO - ##### 19:44:42 INFO - ##### Running clobber step. 19:44:42 INFO - ##### 19:44:42 INFO - Running pre-action listener: _resource_record_pre_action 19:44:42 INFO - Running main action method: clobber 19:44:42 INFO - rmtree: /builds/slave/test-pgo/build 19:44:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:44:43 INFO - Running post-action listener: _resource_record_post_action 19:44:43 INFO - ##### 19:44:43 INFO - ##### Running read-buildbot-config step. 19:44:43 INFO - ##### 19:44:43 INFO - Running pre-action listener: _resource_record_pre_action 19:44:43 INFO - Running main action method: read_buildbot_config 19:44:43 INFO - Using buildbot properties: 19:44:43 INFO - { 19:44:43 INFO - "project": "", 19:44:43 INFO - "product": "firefox", 19:44:43 INFO - "script_repo_revision": "production", 19:44:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 19:44:43 INFO - "repository": "", 19:44:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 19:44:43 INFO - "buildid": "20151216173017", 19:44:43 INFO - "pgo_build": "True", 19:44:43 INFO - "basedir": "/builds/slave/test-pgo", 19:44:43 INFO - "buildnumber": 388, 19:44:43 INFO - "slavename": "talos-linux64-ix-027", 19:44:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:44:43 INFO - "platform": "ubuntu64_hw", 19:44:43 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 19:44:43 INFO - "branch": "fx-team", 19:44:43 INFO - "repo_path": "integration/fx-team", 19:44:43 INFO - "revision": "a8f8ec75a2a9b81aa3963ff6c330133adbd095d5", 19:44:43 INFO - "stage_platform": "linux64-pgo", 19:44:43 INFO - "builduid": "6c8aa2c12a4a4e9fb83fe453fdb3766b", 19:44:43 INFO - "slavebuilddir": "test" 19:44:43 INFO - } 19:44:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 19:44:43 INFO - Running post-action listener: _resource_record_post_action 19:44:43 INFO - ##### 19:44:43 INFO - ##### Running download-and-extract step. 19:44:43 INFO - ##### 19:44:43 INFO - Running pre-action listener: _resource_record_pre_action 19:44:43 INFO - Running main action method: download_and_extract 19:44:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:44:43 INFO - https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:44:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/test_packages.json 19:44:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/test_packages.json 19:44:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:44:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:44:51 INFO - Downloaded 1302 bytes. 19:44:51 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:44:51 INFO - Using the following test package requirements: 19:44:51 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:44:51 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:44:51 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'jsshell-linux-x86_64.zip'], 19:44:51 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:44:51 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:44:51 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:44:51 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:44:51 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:44:51 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:44:51 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:44:51 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:44:51 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:44:51 INFO - https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:44:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:44:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:44:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:44:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:04 INFO - Downloaded 23118060 bytes. 19:45:04 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 19:45:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:45:04 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 19:45:05 INFO - Return code: 0 19:45:05 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 19:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:05 INFO - https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:45:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:45:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:45:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:06 INFO - Downloaded 23118060 bytes. 19:45:06 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 19:45:06 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:45:06 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 19:45:08 INFO - Return code: 0 19:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:08 INFO - https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:09 INFO - Downloaded 11132052 bytes. 19:45:09 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 19:45:09 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:45:09 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 19:45:09 INFO - Return code: 0 19:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:09 INFO - https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:45:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:45:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:45:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dcBldwPyTMKLVrza36ZxRw/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 19:45:11 INFO - Downloaded 59285384 bytes. 19:45:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:45:11 INFO - mkdir: /builds/slave/test-pgo/properties 19:45:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:45:11 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:45:11 INFO - Contents: 19:45:11 INFO - build_url:https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:45:11 INFO - Running post-action listener: _resource_record_post_action 19:45:11 INFO - Running post-action listener: set_extra_try_arguments 19:45:11 INFO - ##### 19:45:11 INFO - ##### Running populate-webroot step. 19:45:11 INFO - ##### 19:45:11 INFO - Running pre-action listener: _resource_record_pre_action 19:45:11 INFO - Running main action method: populate_webroot 19:45:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:45:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:45:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:45:11 INFO - '--tppagecycles', 19:45:11 INFO - '7'], 19:45:11 INFO - 'tests': ['tsvgm']}}, 19:45:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:45:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:45:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:45:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:45:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:45:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:45:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'tests': ['damp', 'tps']}, 19:45:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['damp', 'tps']}, 19:45:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:45:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:45:11 INFO - 'other': {'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'other_l64': {'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'other_nol64': {'tests': ['a11yr', 19:45:11 INFO - 'ts_paint', 19:45:11 INFO - 'tpaint', 19:45:11 INFO - 'sessionrestore', 19:45:11 INFO - 'sessionrestore_no_auto_restore']}, 19:45:11 INFO - 'svgr': {'tests': ['tsvgx', 19:45:11 INFO - 'tsvgr_opacity', 19:45:11 INFO - 'tart', 19:45:11 INFO - 'tscrollx', 19:45:11 INFO - 'cart']}, 19:45:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['tsvgx', 19:45:11 INFO - 'tsvgr_opacity', 19:45:11 INFO - 'tart', 19:45:11 INFO - 'tscrollx', 19:45:11 INFO - 'cart']}, 19:45:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'tests': ['tp5o']}, 19:45:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'talos_options': ['--e10s'], 19:45:11 INFO - 'tests': ['tp5o']}, 19:45:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'talos_options': ['--xperf_path', 19:45:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:45:11 INFO - 'tests': ['tp5n']}, 19:45:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:45:11 INFO - 'talos_options': ['--e10s', 19:45:11 INFO - '--xperf_path', 19:45:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:45:11 INFO - 'tests': ['tp5n']}}, 19:45:11 INFO - 'talos.zip': {'path': '', 19:45:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:45:11 INFO - Running post-action listener: _resource_record_post_action 19:45:11 INFO - ##### 19:45:11 INFO - ##### Running create-virtualenv step. 19:45:11 INFO - ##### 19:45:11 INFO - Running pre-action listener: _resource_record_pre_action 19:45:11 INFO - Running main action method: create_virtualenv 19:45:11 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:45:11 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 19:45:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:45:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:45:11 INFO - Using real prefix '/usr' 19:45:11 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:45:12 INFO - Installing distribute.............................................................................................................................................................................................done. 19:45:14 INFO - Installing pip.................done. 19:45:14 INFO - Return code: 0 19:45:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 19:45:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:45:14 INFO - Using env: {'DISPLAY': ':0', 19:45:14 INFO - 'HOME': '/home/cltbld', 19:45:14 INFO - 'LANG': 'en_US.UTF-8', 19:45:14 INFO - 'LANGUAGE': 'en_US:en', 19:45:14 INFO - 'LOGNAME': 'cltbld', 19:45:14 INFO - 'MAIL': '/var/mail/cltbld', 19:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:14 INFO - 'MOZ_NO_REMOTE': '1', 19:45:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:14 INFO - 'NO_EM_RESTART': '1', 19:45:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:14 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:14 INFO - 'SHELL': '/bin/bash', 19:45:14 INFO - 'SHLVL': '1', 19:45:14 INFO - 'TERM': 'linux', 19:45:14 INFO - 'TMOUT': '86400', 19:45:14 INFO - 'USER': 'cltbld', 19:45:14 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:14 INFO - '_': '/tools/buildbot/bin/python'} 19:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:14 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 19:45:14 INFO - Cleaning up... 19:45:14 INFO - Return code: 0 19:45:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 19:45:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:45:14 INFO - Using env: {'DISPLAY': ':0', 19:45:14 INFO - 'HOME': '/home/cltbld', 19:45:14 INFO - 'LANG': 'en_US.UTF-8', 19:45:14 INFO - 'LANGUAGE': 'en_US:en', 19:45:14 INFO - 'LOGNAME': 'cltbld', 19:45:14 INFO - 'MAIL': '/var/mail/cltbld', 19:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:14 INFO - 'MOZ_NO_REMOTE': '1', 19:45:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:14 INFO - 'NO_EM_RESTART': '1', 19:45:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:14 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:14 INFO - 'SHELL': '/bin/bash', 19:45:14 INFO - 'SHLVL': '1', 19:45:14 INFO - 'TERM': 'linux', 19:45:14 INFO - 'TMOUT': '86400', 19:45:14 INFO - 'USER': 'cltbld', 19:45:14 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:14 INFO - '_': '/tools/buildbot/bin/python'} 19:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:14 INFO - Downloading/unpacking psutil>=0.7.1 19:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:16 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 19:45:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 19:45:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:45:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:45:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:45:16 INFO - Installing collected packages: psutil 19:45:16 INFO - Running setup.py install for psutil 19:45:16 INFO - building 'psutil._psutil_linux' extension 19:45:16 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 19:45:16 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 19:45:16 INFO - building 'psutil._psutil_posix' extension 19:45:16 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 19:45:16 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 19:45:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:45:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:45:16 INFO - Successfully installed psutil 19:45:16 INFO - Cleaning up... 19:45:16 INFO - Return code: 0 19:45:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:16 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:16 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 19:45:16 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 19:45:16 INFO - Using env: {'DISPLAY': ':0', 19:45:16 INFO - 'HOME': '/home/cltbld', 19:45:16 INFO - 'LANG': 'en_US.UTF-8', 19:45:16 INFO - 'LANGUAGE': 'en_US:en', 19:45:16 INFO - 'LOGNAME': 'cltbld', 19:45:16 INFO - 'MAIL': '/var/mail/cltbld', 19:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:16 INFO - 'MOZ_NO_REMOTE': '1', 19:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:16 INFO - 'NO_EM_RESTART': '1', 19:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:16 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:16 INFO - 'SHELL': '/bin/bash', 19:45:16 INFO - 'SHLVL': '1', 19:45:16 INFO - 'TERM': 'linux', 19:45:16 INFO - 'TMOUT': '86400', 19:45:16 INFO - 'USER': 'cltbld', 19:45:16 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:16 INFO - '_': '/tools/buildbot/bin/python'} 19:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:45:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 19:45:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:45:18 INFO - Installing collected packages: mozsystemmonitor 19:45:18 INFO - Running setup.py install for mozsystemmonitor 19:45:18 INFO - Successfully installed mozsystemmonitor 19:45:18 INFO - Cleaning up... 19:45:18 INFO - Return code: 0 19:45:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:45: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')]} 19:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45: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')]} 19:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:18 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:18 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 19:45:18 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 19:45:18 INFO - Using env: {'DISPLAY': ':0', 19:45:18 INFO - 'HOME': '/home/cltbld', 19:45:18 INFO - 'LANG': 'en_US.UTF-8', 19:45:18 INFO - 'LANGUAGE': 'en_US:en', 19:45:18 INFO - 'LOGNAME': 'cltbld', 19:45:18 INFO - 'MAIL': '/var/mail/cltbld', 19:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:18 INFO - 'MOZ_NO_REMOTE': '1', 19:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:18 INFO - 'NO_EM_RESTART': '1', 19:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:18 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:18 INFO - 'SHELL': '/bin/bash', 19:45:18 INFO - 'SHLVL': '1', 19:45:18 INFO - 'TERM': 'linux', 19:45:18 INFO - 'TMOUT': '86400', 19:45:18 INFO - 'USER': 'cltbld', 19:45:18 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:18 INFO - '_': '/tools/buildbot/bin/python'} 19:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:20 INFO - Downloading blobuploader-1.2.4.tar.gz 19:45:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 19:45:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:45:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 19:45:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:45:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:21 INFO - Downloading docopt-0.6.1.tar.gz 19:45:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 19:45:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:45:21 INFO - Installing collected packages: blobuploader, requests, docopt 19:45:21 INFO - Running setup.py install for blobuploader 19:45:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:45:21 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:45:21 INFO - Running setup.py install for requests 19:45:21 INFO - Running setup.py install for docopt 19:45:21 INFO - Successfully installed blobuploader requests docopt 19:45:21 INFO - Cleaning up... 19:45:22 INFO - Return code: 0 19:45:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:22 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:22 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 19:45:22 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 19:45:22 INFO - Using env: {'DISPLAY': ':0', 19:45:22 INFO - 'HOME': '/home/cltbld', 19:45:22 INFO - 'LANG': 'en_US.UTF-8', 19:45:22 INFO - 'LANGUAGE': 'en_US:en', 19:45:22 INFO - 'LOGNAME': 'cltbld', 19:45:22 INFO - 'MAIL': '/var/mail/cltbld', 19:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:22 INFO - 'MOZ_NO_REMOTE': '1', 19:45:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:22 INFO - 'NO_EM_RESTART': '1', 19:45:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:22 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:22 INFO - 'SHELL': '/bin/bash', 19:45:22 INFO - 'SHLVL': '1', 19:45:22 INFO - 'TERM': 'linux', 19:45:22 INFO - 'TMOUT': '86400', 19:45:22 INFO - 'USER': 'cltbld', 19:45:22 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:22 INFO - '_': '/tools/buildbot/bin/python'} 19:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:45:22 INFO - Running setup.py (path:/tmp/pip-JWMuDE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:45:22 INFO - Running setup.py (path:/tmp/pip-9aO3AE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:45:22 INFO - Running setup.py (path:/tmp/pip-byEZ4m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:45:22 INFO - Running setup.py (path:/tmp/pip-m6VoDi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:45:22 INFO - Running setup.py (path:/tmp/pip-GHVhm4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:45:22 INFO - Running setup.py (path:/tmp/pip-FWXfNM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:45:22 INFO - Running setup.py (path:/tmp/pip-QNh6GN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:45:22 INFO - Running setup.py (path:/tmp/pip-tVY99F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:45:22 INFO - Running setup.py (path:/tmp/pip-RXQcnF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:45:22 INFO - Running setup.py (path:/tmp/pip-s2JSFb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:45:22 INFO - Running setup.py (path:/tmp/pip-69tuoF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:45:23 INFO - Running setup.py (path:/tmp/pip-3EjUod-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:45:23 INFO - Running setup.py (path:/tmp/pip-_BEdet-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:45:23 INFO - Running setup.py (path:/tmp/pip-eETDiH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:45:23 INFO - Running setup.py (path:/tmp/pip-Rbr023-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:45:23 INFO - Running setup.py (path:/tmp/pip-pGmifM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:45:23 INFO - Running setup.py (path:/tmp/pip-uGMUoJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:45:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:45:23 INFO - Running setup.py install for manifestparser 19:45:23 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:45:23 INFO - Running setup.py install for mozcrash 19:45:23 INFO - Running setup.py install for mozdebug 19:45:23 INFO - Running setup.py install for mozdevice 19:45:23 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:45:23 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:45:23 INFO - Running setup.py install for mozfile 19:45:23 INFO - Running setup.py install for mozhttpd 19:45:24 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozinfo 19:45:24 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozInstall 19:45:24 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozleak 19:45:24 INFO - Running setup.py install for mozlog 19:45:24 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for moznetwork 19:45:24 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozprocess 19:45:24 INFO - Running setup.py install for mozprofile 19:45:24 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozrunner 19:45:24 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:45:24 INFO - Running setup.py install for mozscreenshot 19:45:25 INFO - Running setup.py install for moztest 19:45:25 INFO - Running setup.py install for mozversion 19:45:25 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:45:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:45:25 INFO - Cleaning up... 19:45:25 INFO - Return code: 0 19:45:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:45:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:25 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:25 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 19:45:25 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 19:45:25 INFO - Using env: {'DISPLAY': ':0', 19:45:25 INFO - 'HOME': '/home/cltbld', 19:45:25 INFO - 'LANG': 'en_US.UTF-8', 19:45:25 INFO - 'LANGUAGE': 'en_US:en', 19:45:25 INFO - 'LOGNAME': 'cltbld', 19:45:25 INFO - 'MAIL': '/var/mail/cltbld', 19:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:25 INFO - 'MOZ_NO_REMOTE': '1', 19:45:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:25 INFO - 'NO_EM_RESTART': '1', 19:45:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:25 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:25 INFO - 'SHELL': '/bin/bash', 19:45:25 INFO - 'SHLVL': '1', 19:45:25 INFO - 'TERM': 'linux', 19:45:25 INFO - 'TMOUT': '86400', 19:45:25 INFO - 'USER': 'cltbld', 19:45:25 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:25 INFO - '_': '/tools/buildbot/bin/python'} 19:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:45:25 INFO - Running setup.py (path:/tmp/pip-6L_fgR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:45:25 INFO - Running setup.py (path:/tmp/pip-H5Gf2O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:45:25 INFO - Running setup.py (path:/tmp/pip-8IdyYl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:45:25 INFO - Running setup.py (path:/tmp/pip-Rgq1xf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:45:25 INFO - Running setup.py (path:/tmp/pip-qNO_Lm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:45:25 INFO - Running setup.py (path:/tmp/pip-TWpUfN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:45:25 INFO - Running setup.py (path:/tmp/pip-Cohong-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:45:25 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)) 19:45:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:45:25 INFO - Running setup.py (path:/tmp/pip-_UlEdD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:45:26 INFO - Running setup.py (path:/tmp/pip-Hm_ZBQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:45:26 INFO - Running setup.py (path:/tmp/pip-vF4QEs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:45:26 INFO - Running setup.py (path:/tmp/pip-RMPKsn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:45:26 INFO - Running setup.py (path:/tmp/pip-bs9_oi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:45:26 INFO - Running setup.py (path:/tmp/pip-Ssv_Cv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:45:26 INFO - Running setup.py (path:/tmp/pip-D2XwNs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:45:26 INFO - Running setup.py (path:/tmp/pip-f3znDg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:45:26 INFO - Running setup.py (path:/tmp/pip-3fmwsk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:45:26 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)) 19:45:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:45:26 INFO - Running setup.py (path:/tmp/pip-eyYapc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:45:26 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)) 19:45:26 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)) 19:45:26 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)) 19:45:26 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)) 19:45:26 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)) 19:45:26 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)) 19:45:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:45:26 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 19:45:26 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 19:45:26 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 19:45:26 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 19:45:26 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 19:45:26 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 19:45:28 INFO - Downloading blessings-1.5.1.tar.gz 19:45:28 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 19:45:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:45:28 INFO - Installing collected packages: blessings 19:45:28 INFO - Running setup.py install for blessings 19:45:28 INFO - Successfully installed blessings 19:45:28 INFO - Cleaning up... 19:45:28 INFO - Return code: 0 19:45:28 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:45:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:45:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:45:28 INFO - Reading from file tmpfile_stdout 19:45:28 INFO - Current package versions: 19:45:28 INFO - argparse == 1.2.1 19:45:28 INFO - blessings == 1.5.1 19:45:28 INFO - blobuploader == 1.2.4 19:45:28 INFO - docopt == 0.6.1 19:45:28 INFO - manifestparser == 1.1 19:45:28 INFO - mozInstall == 1.12 19:45:28 INFO - mozcrash == 0.16 19:45:28 INFO - mozdebug == 0.1 19:45:28 INFO - mozdevice == 0.47 19:45:28 INFO - mozfile == 1.2 19:45:28 INFO - mozhttpd == 0.7 19:45:28 INFO - mozinfo == 0.9 19:45:28 INFO - mozleak == 0.1 19:45:28 INFO - mozlog == 3.1 19:45:28 INFO - moznetwork == 0.27 19:45:28 INFO - mozprocess == 0.22 19:45:28 INFO - mozprofile == 0.27 19:45:28 INFO - mozrunner == 6.11 19:45:28 INFO - mozscreenshot == 0.1 19:45:28 INFO - mozsystemmonitor == 0.0 19:45:28 INFO - moztest == 0.7 19:45:28 INFO - mozversion == 1.4 19:45:28 INFO - psutil == 3.1.1 19:45:28 INFO - requests == 1.2.3 19:45:28 INFO - wsgiref == 0.1.2 19:45:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:28 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:28 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 19:45:28 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 19:45:28 INFO - Using env: {'DISPLAY': ':0', 19:45:28 INFO - 'HOME': '/home/cltbld', 19:45:28 INFO - 'LANG': 'en_US.UTF-8', 19:45:28 INFO - 'LANGUAGE': 'en_US:en', 19:45:28 INFO - 'LOGNAME': 'cltbld', 19:45:28 INFO - 'MAIL': '/var/mail/cltbld', 19:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:28 INFO - 'MOZ_NO_REMOTE': '1', 19:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:28 INFO - 'NO_EM_RESTART': '1', 19:45:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:28 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:28 INFO - 'SHELL': '/bin/bash', 19:45:28 INFO - 'SHLVL': '1', 19:45:28 INFO - 'TERM': 'linux', 19:45:28 INFO - 'TMOUT': '86400', 19:45:28 INFO - 'USER': 'cltbld', 19:45:28 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:28 INFO - '_': '/tools/buildbot/bin/python'} 19:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 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)) 19:45:29 INFO - Cleaning up... 19:45:29 INFO - Return code: 0 19:45:29 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:45:29 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')]} 19:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:29 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')]} 19:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:29 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:29 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 19:45:29 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 19:45:29 INFO - Using env: {'DISPLAY': ':0', 19:45:29 INFO - 'HOME': '/home/cltbld', 19:45:29 INFO - 'LANG': 'en_US.UTF-8', 19:45:29 INFO - 'LANGUAGE': 'en_US:en', 19:45:29 INFO - 'LOGNAME': 'cltbld', 19:45:29 INFO - 'MAIL': '/var/mail/cltbld', 19:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:29 INFO - 'MOZ_NO_REMOTE': '1', 19:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:29 INFO - 'NO_EM_RESTART': '1', 19:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:29 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:29 INFO - 'SHELL': '/bin/bash', 19:45:29 INFO - 'SHLVL': '1', 19:45:29 INFO - 'TERM': 'linux', 19:45:29 INFO - 'TMOUT': '86400', 19:45:29 INFO - 'USER': 'cltbld', 19:45:29 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:29 INFO - '_': '/tools/buildbot/bin/python'} 19:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:29 INFO - Downloading/unpacking jsonschema 19:45:29 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 19:45:29 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 19:45:29 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 19:45:29 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 19:45:29 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 19:45:29 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 19:45:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:45:30 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 19:45:30 INFO - Downloading/unpacking functools32 (from jsonschema) 19:45:30 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 19:45:30 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 19:45:30 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 19:45:30 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 19:45:30 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 19:45:30 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 19:45:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:45:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 19:45:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:45:31 INFO - warning: no files found matching '*.txt' 19:45:31 INFO - no previously-included directories found matching 'build' 19:45:31 INFO - no previously-included directories found matching 'dist' 19:45:31 INFO - no previously-included directories found matching '.git*' 19:45:31 INFO - Installing collected packages: jsonschema, functools32 19:45:31 INFO - Running setup.py install for functools32 19:45:31 INFO - warning: no files found matching '*.txt' 19:45:31 INFO - no previously-included directories found matching 'build' 19:45:31 INFO - no previously-included directories found matching 'dist' 19:45:31 INFO - no previously-included directories found matching '.git*' 19:45:31 INFO - Successfully installed jsonschema functools32 19:45:31 INFO - Cleaning up... 19:45:31 INFO - Return code: 0 19:45:31 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 19:45: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')]} 19:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45: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')]} 19:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:45:31 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 0x7f538d6adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, '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': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', '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 19:45:31 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 19:45:31 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 19:45:31 INFO - Using env: {'DISPLAY': ':0', 19:45:31 INFO - 'HOME': '/home/cltbld', 19:45:31 INFO - 'LANG': 'en_US.UTF-8', 19:45:31 INFO - 'LANGUAGE': 'en_US:en', 19:45:31 INFO - 'LOGNAME': 'cltbld', 19:45:31 INFO - 'MAIL': '/var/mail/cltbld', 19:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:31 INFO - 'MOZ_NO_REMOTE': '1', 19:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:31 INFO - 'NO_EM_RESTART': '1', 19:45:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:31 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:31 INFO - 'SHELL': '/bin/bash', 19:45:31 INFO - 'SHLVL': '1', 19:45:31 INFO - 'TERM': 'linux', 19:45:31 INFO - 'TMOUT': '86400', 19:45:31 INFO - 'USER': 'cltbld', 19:45:31 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:31 INFO - '_': '/tools/buildbot/bin/python'} 19:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:31 INFO - Downloading/unpacking flake8 19:45:31 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 19:45:31 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 19:45:31 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 19:45:31 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 19:45:31 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 19:45:31 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 19:45:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:45:33 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 19:45:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:45:33 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 19:45:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 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 19:45:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 19:45:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 19:45:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:45:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:45:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:45:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:45:33 INFO - no previously-included directories found matching 'docs/_build' 19:45:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:45:34 INFO - Running setup.py install for pep8 19:45:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:45:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:45:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:45:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:45:34 INFO - no previously-included directories found matching 'docs/_build' 19:45:34 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 19:45:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:45:34 INFO - Cleaning up... 19:45:34 INFO - Return code: 0 19:45:34 INFO - Running post-action listener: _resource_record_post_action 19:45:34 INFO - Running post-action listener: _start_resource_monitoring 19:45:34 INFO - Starting resource monitoring. 19:45:34 INFO - ##### 19:45:34 INFO - ##### Running install step. 19:45:34 INFO - ##### 19:45:34 INFO - Running pre-action listener: _resource_record_pre_action 19:45:34 INFO - Running main action method: install 19:45:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:45:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:45:34 INFO - Reading from file tmpfile_stdout 19:45:34 INFO - Detecting whether we're running mozinstall >=1.0... 19:45:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:45:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:45:34 INFO - Reading from file tmpfile_stdout 19:45:34 INFO - Output received: 19:45:34 INFO - Usage: mozinstall [options] installer 19:45:34 INFO - Options: 19:45:34 INFO - -h, --help show this help message and exit 19:45:34 INFO - -d DEST, --destination=DEST 19:45:34 INFO - Directory to install application into. [default: 19:45:34 INFO - "/builds/slave/test-pgo"] 19:45:34 INFO - --app=APP Application being installed. [default: firefox] 19:45:34 INFO - mkdir: /builds/slave/test-pgo/build/application 19:45:34 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'] 19:45:34 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 19:45:49 INFO - Reading from file tmpfile_stdout 19:45:49 INFO - Output received: 19:45:49 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:45:49 INFO - Running post-action listener: _resource_record_post_action 19:45:49 INFO - ##### 19:45:49 INFO - ##### Running run-tests step. 19:45:49 INFO - ##### 19:45:49 INFO - Running pre-action listener: _resource_record_pre_action 19:45:49 INFO - Running main action method: run_tests 19:45:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:45:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:45:49 INFO - Python 2.7.3 19:45:49 INFO - Return code: 0 19:45:49 INFO - grabbing minidump binary from tooltool 19:45: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')]} 19:45:49 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 0x29c9350>, '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 0x29ca250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29ca6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:45:49 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 19:45:49 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 19:45:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:45:49 INFO - Return code: 0 19:45:49 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:45:49 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:45:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:45:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:45:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:45:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 19:45:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 19:45:50 INFO - Return code: 0 19:45:50 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/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:45:50 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/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost 19:45:50 INFO - Using env: {'DISPLAY': ':0', 19:45:50 INFO - 'HOME': '/home/cltbld', 19:45:50 INFO - 'LANG': 'en_US.UTF-8', 19:45:50 INFO - 'LANGUAGE': 'en_US:en', 19:45:50 INFO - 'LOGNAME': 'cltbld', 19:45:50 INFO - 'MAIL': '/var/mail/cltbld', 19:45:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:45:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:50 INFO - 'MOZ_NO_REMOTE': '1', 19:45:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:45:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:50 INFO - 'NO_EM_RESTART': '1', 19:45:50 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:45:50 INFO - 'PWD': '/builds/slave/test-pgo', 19:45:50 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:45:50 INFO - 'SHELL': '/bin/bash', 19:45:50 INFO - 'SHLVL': '1', 19:45:50 INFO - 'TERM': 'linux', 19:45:50 INFO - 'TMOUT': '86400', 19:45:50 INFO - 'USER': 'cltbld', 19:45:50 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632', 19:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:50 INFO - '_': '/tools/buildbot/bin/python'} 19:45:50 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/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost'] with output_timeout 3600 19:45:50 INFO - mozversion INFO | application_buildid: 20151216173017 19:45:50 INFO - mozversion INFO | application_changeset: a8f8ec75a2a9b81aa3963ff6c330133adbd095d5 19:45:50 INFO - mozversion INFO | application_display_name: Nightly 19:45:50 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:45:50 INFO - mozversion INFO | application_name: Firefox 19:45:50 INFO - mozversion INFO | application_remotingname: firefox 19:45:50 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 19:45:50 INFO - mozversion INFO | application_vendor: Mozilla 19:45:50 INFO - mozversion INFO | application_version: 46.0a1 19:45:50 INFO - mozversion INFO | platform_buildid: 20151216173017 19:45:50 INFO - mozversion INFO | platform_changeset: a8f8ec75a2a9b81aa3963ff6c330133adbd095d5 19:45:50 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 19:45:50 INFO - mozversion INFO | platform_version: 46.0a1 19:45:50 INFO - 2015-12-16 19:45:50,596 DEBUG : using testdate: 1450323950 19:45:50 INFO - 2015-12-16 19:45:50,596 DEBUG : actual date: 1450323950 19:45:50 INFO - 2015-12-16 19:45:50,605 INFO : starting webserver on 'localhost:39587' 19:45:50 INFO - 2015-12-16 19:45:50,606 INFO : Starting test suite talos-linux64-ix-027.e 19:45:50 INFO - 2015-12-16 19:45:50,606 INFO : Starting test dromaeo_css 19:45:50 INFO - 2015-12-16 19:45:50,606 DEBUG : operating with platform_type : linux_ 19:45:50 INFO - 2015-12-16 19:45:50,607 INFO : Initialising browser for dromaeo_css test... 19:45:50 INFO - 2015-12-16 19:45:50,617 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_JXyde/profile http://localhost:39587/getInfo.html 19:45:55 INFO - 2015-12-16 19:45:55,281 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:45:55 INFO - 2015-12-16 19:45:55,282 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:45:55 INFO - 2015-12-16 19:45:55,282 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:45:55 INFO - 2015-12-16 19:45:55,282 DEBUG : BROWSER_OUTPUT: __metrics 19:45:55 INFO - 2015-12-16 19:45:55,406 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] 19:45:56 INFO - 2015-12-16 19:45:56,317 INFO : Browser initialized. 19:45:56 INFO - 2015-12-16 19:45:56,318 INFO : Running cycle 1/1 for dromaeo_css test... 19:45:56 INFO - 2015-12-16 19:45:56,318 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_JXyde/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:46:08 INFO - 2015-12-16 19:46:08,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 143659008 19:46:08 INFO - 2015-12-16 19:46:08,208 DEBUG : BROWSER_OUTPUT: 19:46:57 INFO - 2015-12-16 19:46:57,799 DEBUG : BROWSER_OUTPUT: 1450324017797 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] 19:49:07 INFO - 2015-12-16 19:49:07,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39587/tests/dromaeo/cssquery-ext.html) 19:49:08 INFO - 2015-12-16 19:49:08,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 150511616 19:49:08 INFO - 2015-12-16 19:49:08,511 DEBUG : BROWSER_OUTPUT: 19:52:10 INFO - 2015-12-16 19:52:10,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-ext.html (next: http://localhost:39587/tests/dromaeo/cssquery-jquery.html) 19:52:11 INFO - 2015-12-16 19:52:11,199 DEBUG : BROWSER_OUTPUT: RSS: Main: 146931712 19:52:11 INFO - 2015-12-16 19:52:11,199 DEBUG : BROWSER_OUTPUT: 19:55:12 INFO - 2015-12-16 19:55:12,847 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39587/tests/dromaeo/cssquery-mootools.html) 19:55:13 INFO - 2015-12-16 19:55:13,441 DEBUG : BROWSER_OUTPUT: RSS: Main: 146890752 19:55:13 INFO - 2015-12-16 19:55:13,441 DEBUG : BROWSER_OUTPUT: 19:58:15 INFO - 2015-12-16 19:58:15,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39587/tests/dromaeo/cssquery-prototype.html) 19:58:15 INFO - 2015-12-16 19:58:15,728 DEBUG : BROWSER_OUTPUT: RSS: Main: 145113088 19:58:15 INFO - 2015-12-16 19:58:15,728 DEBUG : BROWSER_OUTPUT: 20:01:18 INFO - 2015-12-16 20:01:18,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39587/tests/dromaeo/cssquery-yui.html) 20:01:18 INFO - 2015-12-16 20:01:18,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 146161664 20:01:18 INFO - 2015-12-16 20:01:18,594 DEBUG : BROWSER_OUTPUT: 20:04:20 INFO - 2015-12-16 20:04:20,997 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/dromaeo/cssquery-yui.html (next: http://localhost:39587/tests/dromaeo/cssquery-dojo.html) 20:04:21 INFO - 2015-12-16 20:04:21,247 DEBUG : BROWSER_OUTPUT: RSS: Main: 148291584 20:04:21 INFO - 2015-12-16 20:04:21,248 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,248 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:04:21 INFO - 2015-12-16 20:04:21,248 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:04:21 INFO - 2015-12-16 20:04:21,248 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:04:21 INFO - 2015-12-16 20:04:21,248 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:04:21 INFO - 2015-12-16 20:04:21,249 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3216.0673404712634;3266.4120458317507;3291;3310.8344582770865;3320.3525312564043;5234.79060837567;5493.862653433666;5516.14499752538;5547.112462006075;5566.944330556693;5354.464553544643;5410.8917821643545;5421.674699518022;5431.592630552705;5460.426655201206;4989.2017277235645;5021.447640759514;5109.821156259532;5199.688018718889;5244.318238628973;4956.578690811285;5093.516115968995;5130.9743451282875;5147.613928955325;5189.403218629868;4905.999999999982;4942.036302920932;4951.678140920829;5003.47463516331;5009.849704508871;5009.824656137054;5042.142835717937;5067.189249720027;5096.490350964911;5119.744012799345;4918.532739389752;4968.7018778873025;5036.571891389236;5038.345015148044;5093.541581257705;5317.627766056377;5337.146056630959;5353.170258609883;5368.677879327214;5382.300300960889;5940.049592065248;5997.310309314441;6025.186599809013;6037.818865434;6046.939530604725;6020.187274717945;6076.878462430726;6077.604955677867;6081.3006504252135;6082.817515474543;5616.859578510529;5665.603407761459;5665.858353541112;5666.036773748473;5723.942760572382;6043.456088952015;6048.758049678052;6084.787032453843;6101.664408457577;6114.144019837227;5725.255716756795;5820.738066787005;5838.620489668158;5943.524518038546;5949.524038076944;5984.162217289583;5984.640921544721;5986.191864098291;5988.9101663475085;5999.490043346277;5953.196318497034;6012.939870601325;6017.1275165100815;6042.516598672096;6069.423404776539;6054.455099041019;6055.909161362583;6098.69506524672;6111.755529778759;6118;4451.933221001687;4538.977305113453;4590.081983603292;4646.86059418218;4664.277037059262;4027.194561087794;4079.265732168238;4099.590040995936;4128.876133715993;4135.214309281227;5617;5627.71861406928;5640.97179514108;5655.830325090254;5656.575756818255;5825.650460972355;5918.496927761188;5961.552883533753;6015.518758499397;6037.275526936708;5688.004599195125;5707.800226992118;5720.170575266647;5720.1991721159075;5720.48515633595;5051.393832740095;5088.92366614501;5100.821471248489;5109.08036553424;5129.076766182048;5949.9107513387335;5981.760729570856;6030.216071910536;6088.056351265561;6159.3224745277985;5945.34601193877;6007.219061521974;6011.218541589566;6021.036634138517;6118.2658081028785;6021.8558473889825;6052.66710330936;6058.878822423439;6078.361772014052;6093.207882975362;5723.141528770718;5824.446677565625;5904.350521442552;6001.669908155093;6040.728167232573;6087.543434242538;6101.969490152521;6119.418655227746;6133.509319254538;6137.723802428991;5736.397678243891;5915;5930.703464826655;5965;5986.970065149646;5820.359760426265;5867.5305975521005;5955.315138759098;5962.761489540369;5997.880042399215;5741.827745167651;5788.044972579477;5794.015017447128;5794.420557944172;5805.622634528742;5760.164776107525;5854.4730974212525;5855.824325270249;5872.236609240771;5887.793927212527;5646.407127251744;5735.426457354231;5746.655200687972;5751.9424805751405;5800.361960184461 20:04:21 INFO - 2015-12-16 20:04:21,250 DEBUG : BROWSER_OUTPUT: |1;ext.html;546.8644509172278;595.9761609535619;598.7814796888332;615.0221148374085;629.4052120745897;36792;37362.25275494492;37415;37493.81253093734;37550.24899502008;41424.58575414245;41881.79059104708;41918.69447180404;41956.58043419565;42014.36978445326;8681.696140635064;8859.11408859112;8875.73372798817;8877.733667989938;8887.289016878634;8575.828483430327;8693.69572064978;8698.826023479527;8711.869321960176;8717.302615790753;8626.439281446686;8727.127287271285;8732.825343493127;8755.124487551258;8772.342074344419;8624.827503449897;8659.177378149066;8691.47851128928;8732.345074119467;8752.299816014704;8578.785530361729;8640.351973602004;8686.218240358336;8710.303175745988;8724.214820666171;38012.80993595014;38741.41887871656;38798.80600597007;38838.41742373867;38856.805715971524;3267.039815807235;3316.513327322632;3416.214270717724;3527.012436517779;3585.2470981094;29140;30121.397572048438;30924.226894327596;30951.226219344473;30961.535576966362;3717.9814100929593;3785.4511095890944;3790.469334293201;3857.112864041258;3949.9605003949882;248260;254756;255024;255419;255539.99999999814;58302.41697583055;59002.70498647479;59801.401985980454;66918;67800;65576.34423655798;66007.33992660012;66311;66334.33665663282;66683.66658166776;14032.158070515801;14228.715425691635;14308;14381.92809035948;14409.711805763825;10021.448820314807;10260.281780275309;10266.076053155102;10305.587776488857;10352.430616316024;20797.19237902894;21168;21321.68017479739;21346.679799803016;21404.78595214028;8382.748517544484;8385.95807020961;8548;8553.101924297984;8723.738287851374;879.9912000879909;948.0566835998226;952.1145334838528;990.301837204769;1028.1466382902174;32229.35541289161;33717.494237586456;33845.49231761526;33944.83027584896;34045.31909361799;1025.8666373371414;1043.1498328861953;1079.519613771864;1080.897314755097;1113.6714669172452;13858.307084645727;14197.148171109766;14310.57068287953;14442;14480.493182738554;384072;384490;385735;387203;390014;51168.23247651288;51240.74379628078;51404.742976285626;51543.96912061738;51608.74195628998;1295.5206573567652;1354.6003928840819;1355.200431745275;1357.8845798107272;1364.740699267136;25919.740802591732;26613;26852.46295074127;27047.1885843425;27256;40933.38599921063;41352.37971430371;41369.37945930753;41402.378964316165;41425.1714965705;305640;307176;309429;309879;310435;31172.688273117885;45841.77079114583;45854.54145458636;45886.541134588224;46261.30608040815;42226.788866056704;43016.569834301255;43179.78410108055;43247;43436;36642.633573664985;36739;37481;41121.794391027855;42497.5750242506;40241;41405;41653;41873.581264188186;42494;35230.29539409146;35529.644703552636;35564.466533001505;37312.067198320256;38090;29388;32673.836630816695;32776.836115820224;32944.34111317808;33144.33711325712;24873.378165546008;25060;25096.24711258665;25292.873535632203;25357.87321063383 20:04:21 INFO - 2015-12-16 20:04:21,250 DEBUG : BROWSER_OUTPUT: |2;jquery.html;342.9588449386072;346.63949492527786;361.6564263949248;362.0189287032144;363.184650459718;17222.3111075557;17404.912975435105;17833.910830445846;17978.640427191447;18785.154668039937;14080.36638351274;14128.505502307422;14249.857501424982;14252.073615215004;14413.63965900853;4762.380890484233;5008.1486147355035;5036.546710796028;5040.747962601874;5043.319151914481;4994.875128121808;5021.949780502185;5027.09512287788;5047.621428392867;5048.066107770056;5046.343975283208;5121.155009423441;5145.922811157836;5289;5355.464453554652;5233.738313084331;5235.816746413857;5245.423003469616;5262.473752624726;5268.762905669235;5083.847484575469;5311.681299122065;5313.8937221255355;5315.521603055705;5336.146216605363;11997.520099196021;12352.691182720504;14263.714725705428;19596;19665.11506982189;2189.748178959424;2192.8574642648177;2222.1889010511045;2225.922092726763;2252.2342403582697;9429.764255893657;11766.294022358601;12864.807027894589;13070.281134537692;13503.864961350457;1803.6482885837268;1818.5453636590853;1819.8362147406797;1881.5014021284371;1896.3741965151467;58202.41797582012;59050;62299;62596.68701656508;63056.68471657612;9893.901060989441;10062.144717698931;10108.342957707873;10129.645462408927;10177.084062434416;13074;13103.803442948363;13119.803202951964;13147.737045259042;13196.340182990813;7049.89425158623;7147.53541019832;7169.211386747454;7181.533200341961;7241.674124664402;6785.321467853175;6812.284710105368;7180.102487189101;7200.351968322876;7216.134063912364;4224.978875105605;4308;4311.935320970188;4321.762303073361;4330;1766.132170546499;1768.619746754454;1769.2127003483583;1789.7404876292865;1799.2623024559869;390.2273498472983;396.4212250114807;405.4404921208713;405.48705887052904;962.0379620379621;10431.478426078666;10487.580496780196;10520.842187367196;10530.578776848994;10583.206259530412;405.68559366491206;405.8924385037967;417.96656267498525;427.890887823603;435.3991491741377;5809.535237181015;5983.9401605983385;5986.551008674367;6019.398060194033;6075.33171351151;545050;546617;548623;557425;559834;82273.76589351165;82845.58577207197;83244.58377708073;84238;84728.99999999876;468.67427138139345;471.790053426222;1072.7264547540328;1137.4881303413497;1142.451623220842;14939.77590336124;15160.620984475476;15184.924075379553;15303.846961530242;15536.61158471002;32231.355372891943;33974.980750577524;34318.656813431546;36239.27521449609;36289.45565816462;46312.073758525315;46659;46846.53153468558;47277.2908406381;47442.76278618585;9119.772005699911;9485.241180705389;9678.661246856607;9678.854817177607;9732.659356922473;16582.75125873137;16679;16817.76926682865;17109.401170958958;17297.827021729623;13705;14452.277386130861;14591.197484138474;14615.34230959588;15506.069635821887;14000.089994150349;14053.297335133408;14158.929205353907;14237.50168744089;14358.928205358907;14986.775198372252;15045.548633541011;15141.394344226108;15192.468263611163;15743.68512629764;9191.540422978958;9329.813403732023;9421.481818500048;9774.951125244614;9777.022297770167;14440.277986100864;14517.201553914636;14847;15126;15306.464273750365 20:04:21 INFO - 2015-12-16 20:04:21,252 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1502.7896094546759;1679.6220850308678;1699.5496193508723;1730.1781653714488;1735.852452541534;7488.588127652977;7627.885581716272;7734.7679569612865;7747.728829490969;7837.568933708644;7575.053118360205;7580.77257682269;7649.235076492348;7665.118511371194;7668.4248681348845;2233.8659680419146;2261.208576998046;2279.4985103277254;2279.7378301495373;2282.611955967481;2232.944176395595;2256.808171305441;2261.694671219389;2264.750877403484;2279.247848210087;2074.553970896257;2099.1183702844764;2185.3771675072608;2191.824654027682;2211.9115235390564;2228.699125618037;2239.585676649825;2244.2145249162827;2254.9436264093365;2256.751757306695;2184.9235276765394;2193.122750899637;2199.186301068599;2202.0971401725214;2203.5813195492806;7554.660040298199;7636.31273185416;7649.273319034683;7651.23487651236;7668.654910528984;4457;4481.574250446202;4498.640108791322;4533.455985281755;4570.6343492520855;3031.711987361219;3058.113147187313;3097.6127984002;3113.2995076107923;3121.672224416449;980.7450062983604;985.8422652375657;991.5984026469291;1009.9495025248708;1012.0739523336139;10344.482775861177;10385.376877387382;10402.063814256753;10413.271071024956;10450.843237351446;4100.1184745279315;4146.606072423115;4147.730397524151;4159.667226621863;4172.269852775752;8435.915640843514;8436.367272454469;8474.661013559511;8489.36329775257;8492.872606910902;4402.581754733295;4437.201303765289;4471.888202794956;4475.351074094238;4476.4180656514445;4067.572904844949;4084.387341898739;4086.4483294755423;4097.8975525612095;4102.220578090153;3385.96727997963;3441.3117376524797;3445.4659527772733;3454.101933497309;3478.3217272631596;1051.5688567687212;1059.2680966619632;1064.669952314785;1065.2436769893307;1073.0181883576597;955.7610597350663;1004.1916257412706;1056.0706578211125;1185.3836005277208;1300.596814987357;7101.538400003983;7109.324614161646;7134.393576545948;7144.821379465555;7193.136823581203;614.6061092046453;629.568745409396;635.3931994944817;641.8299150725062;779.8089468080357;5431.293931788921;5530.198121272393;5563.443655634404;5581.832545023656;5586.720663966867;8706.303495720455;8902.643894244287;8948.373613846969;9162.587683554257;9218.953905230432;8987.19115279628;9021.142991415805;9031.096890310917;9116;9164.312676549285;6999.30006999296;7177.3899218567;7194.676239569232;7205.207427183006;7231.710731570678;7193.4604904631315;7260.419166466776;7267.527610705287;7268;7282.5994570297025;7957.721479748181;8132.105468398589;8217.178282171973;8220.506769593843;8234.588270586566;8344.290735287568;8442.704505342284;8487.405881588229;8512.361572882182;8545.658173672984;6524.67376631176;6783.3555812197765;6796.72813087471;6824.897626535507;6852.657367131723;7326.084239470067;7339.302766237199;7382;7388.852222955618;7395.963020184865;6731.293214212633;6734.764283250062;6847;6903.792886213421;7017.578945263301;6566.80299591013;6801.489888258301;6853.383195512428;6874.621895795579;6908.171019477695;6918.654067296515;6924.826879328057;6928.93071069303;6952.826179345557;6981.685824137824;5913.5564832636865;6319.905201422074;6342.904856427065;6368.044793281045;6487.708053137526;6421.293657697743;6441.259255185713;6451.290358060516;6455.160829092188;6473.77341793035 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.9703628155325;320.365675961596;324.9967500324996;326.6325383943064;330.6082292483407;6289.685515724213;6291.15069465622;6311.337309582496;6330;6405.711742971565;6202.78290259841;6240.219972503437;6258.561900666944;6274.090256912745;6277.717502712378;4772.212584923501;4773.856784296468;4775.331453596499;4776.355192049064;4779.091972525209;4772.141014617368;4777.450593181777;4779.402574678165;4784.64115191362;4786.282057691356;4628.09752098341;4705.835295764649;4718.4337879454515;4726.2201736713405;4732.384789977298;4718.490083173386;4725.291206319045;4730.408698912636;4730.716157030589;4735.644826638016;4038.45480860086;4056.5132184137797;4059.167870586523;4062.7765472898977;4063.837446502166;5952.136940143655;6020.4581587656885;6025.186599809013;6033.728482218311;6046.153538504613;5378.8924221515745;5457.181422786613;5470.9452905471235;5479.1781232815;5484.232207490954;6691.498137639696;6691.665416729144;6697.832554186136;6699.966500167517;6703.865922681568;4428.800703968329;4436.889077773081;4473.7315761054115;4485.170243504962;4488.05750792334;6769.830754231184;6847.315268473113;6924.896126558106;6926.965365173192;6935.861282774366;4466.9106617867465;4472.284434490531;4475.5748203920575;4484.05834774701;4493.348464472699;4484.775761211927;4522.5929666330185;4526.02690421561;4533.637309015271;4537.77311134442;5343.599230057684;5358.223057656618;5366.248725178478;5367.845913128618;5389.595780316492;6537.01944708288;6698.095757072831;6706.5640733352175;6706.631135287606;6726.596404215763;1602.5913392085176;1619.8947068440514;1638.4101723379813;1638.983610163883;1639.8524132828102;1048.6591857646142;1068.0547715271884;1081.405227125078;1081.9883409012598;1091.9727006824894;6369.5222858285815;6377.330380310001;6379.904301435389;6393.776217832354;6405;6867.07294515244;6870.862582748317;6871.106756121772;6884.105066341343;6885.621290828953;6322.36776322364;6371.299157092716;6388;6388.520860935449;6399.328070552525;3782.8108594570162;3797.1266608680157;3804.543454785388;3807.2575847709986;3808.923821523554;7060.964695176492;7080.822979425556;7093.503454758221;7133.678984445713;7139.250378710264;7005.474589405815;7099.680514376762;7161.92838071623;7175.497715159889;7216.530925489827;7094.858102838018;7119.4660400470175;7119.537230080028;7119.679614417364;7134.500584959003;6915.827104322432;6953.687084081127;6954.582725036514;6958.686859091455;6967.442604591722;6780.762673306411;6906.758263460755;6910.930890691029;6921.169459664872;6923.411510021503;7120.145582529923;7121.608311542915;7131.286871312827;7131.714731410685;7134.429245660231;4565.931511027404;4578.130155270488;4579.198640237879;4585.472670642919;4591;6819.659017049227;6975.337342952412;6982.476314276551;6995.825104372431;7001.579905205704;6714.563553369016;6769.661516924232;6782.0505129280955;6786.830329241611;6800.183977922483;6772.322767723188;6772.898406523807;6777.695003724745;6781.762638307833;6787.762428314985;6525.2169739631545;6644.069830223869;6647.434968027578;6651.900221496779;6665.900011499734;6749.5612785168805;6796.830079248059;6797.252302246848;6797.4901882358035;6806.727730890709;6500.906884912186;6540.247871494839;6588.275289718032;6590.340965903371;6607.636579988147 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: |5;yui.html;856.7858035491126;857.9828403431932;861.4228466927158;863.9136086391358;898.5327629632593;13464.192148471086;13604.659883502918;13699.794503082461;13710.657233569142;13773.104748191361;13595.456181752743;13798.586042418718;13802.309884505785;13807.792883106762;13854.445822167127;806.0086094104255;816.1593558634586;818.0265484073963;820.2125959079291;839.4543546694651;817.7669364231195;824.8185399212164;824.884516167737;829.3282441222628;830.0578843013193;858.0604238358987;859.072202021818;863.546638015044;864.2912811494577;865.420168487112;853.6116067189445;857.2456238510102;860.3332417376521;862.3833958719483;862.969796057135;628.8239292997922;631.9368063193643;633.4109278371113;635.2504045226631;635.7933671556716;13161.144525605805;13278.53525126616;13302.667433314244;13334.399952002183;13342.266175360352;12002.399880005965;12014.69963250917;12184.695382615417;12232.327222002787;12292.877071229263;10131.696049118538;10147;10221.284510084297;10291.897081029168;10308.69073927776;3591.910202244939;3593.2274560969518;3696.353138200804;3748.5689145748315;3759.9436008459893;45629.31556026629;45648.315275270565;45670.08659826818;45977;46139;9857.112859842506;9900.702978910644;9979.650712225037;9979.900200997898;10096.848547271797;17292.481225563253;17350.566235844206;17379.47861564155;17381.217845196996;17423.303067877143;7405.1484079330485;7448.739294124788;7491.6254187290415;7706.961465192639;7836.294733474015;4269.91460170801;4307.181635489247;4313.202057619288;4331.211457137614;4333.523312435629;3013.517837146046;3050.923726906845;3052.236940764809;3054.266975925762;3058.801177923428;536.6779932040822;541.6614615865084;547.4388751529634;555.4556534596119;565.7086600400797;541.6289841458613;678.3453966921944;699.4923200326783;705.4215543254483;731.5903094267227;7936.444448888637;8410.453320534269;8686.86969695455;8746.86879696805;8827.911720882708;678.6945874356461;686.2382755141789;689.6586189835963;740.5630677900065;751.1324420294459;3872.02706736991;4205.053862880827;4234.491860976641;4286.635635970915;4349.151915376471;55672;55808;56008.439915601135;56399.154012689025;56799.43200568024;50183.74908125436;50402;50797;50848.23727644089;50960.49039509558;46783.76608116938;47035.764821175675;49682;49934;50683.98632027339;8708.303335732999;9030.413023153475;9126.817463650823;9181.586828592863;9220.216281616138;33173;33310.333793323516;33979.49030764491;34022.31955360928;34126;45862.31206531838;45925.31112033255;46068.76965615284;46560.53439465562;46729;10674.83987740169;11158.665240042812;11282.887171128184;11359;11472.369019703996;12049.638510844688;12220;12282;12747.61757147287;12768.170068945488;11458.484368203617;11489.770204595694;11541.307521548733;11960.940195298968;12074.27554346742;10763.138948884227;11336.829947550628;11537.25007874519;11579.594714184963;11967.222130561486;11210.215284930302;11409.714757131138;11444.484998174936;11728.882711172779;11764.647060588197;9801.852972205565;10024.699259022242;10052.045055719695;10103.696889093339;10429;9218.677346292847;9316.767080823034;9364.391314564531;9582.712518624452;9997.55011024491 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,312 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:04:21 INFO - 2015-12-16 20:04:21,253 DEBUG : BROWSER_OUTPUT: __startTimestamp1450325061246__endTimestamp 20:04:21 INFO - 2015-12-16 20:04:21,254 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,254 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:04:21 INFO - 2015-12-16 20:04:21,254 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:04:21 INFO - 2015-12-16 20:04:21,254 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,254 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5544.12 Median:5751.94 stddev:634.86 (11.0%) stddev-sans-first:609.98 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: Values: 3216.1 3266.4 3291.0 3310.8 3320.4 5234.8 5493.9 5516.1 5547.1 5566.9 5354.5 5410.9 5421.7 5431.6 5460.4 4989.2 5021.4 5109.8 5199.7 5244.3 4956.6 5093.5 5131.0 5147.6 5189.4 4906.0 4942.0 4951.7 5003.5 5009.8 5009.8 5042.1 5067.2 5096.5 5119.7 4918.5 4968.7 5036.6 5038.3 5093.5 5317.6 5337.1 5353.2 5368.7 5382.3 5940.0 5997.3 6025.2 6037.8 6046.9 6020.2 6076.9 6077.6 6081.3 6082.8 5616.9 5665.6 5665.9 5666.0 5723.9 6043.5 6048.8 6084.8 6101.7 6114.1 5725.3 5820.7 5838.6 5943.5 5949.5 5984.2 5984.6 5986.2 5988.9 5999.5 5953.2 6012.9 6017.1 6042.5 6069.4 6054.5 6055.9 6098.7 6111.8 6118.0 4451.9 4539.0 4590.1 4646.9 4664.3 4027.2 4079.3 4099.6 4128.9 4135.2 5617.0 5627.7 5641.0 5655.8 5656.6 5825.7 5918.5 5961.6 6015.5 6037.3 5688.0 5707.8 5720.2 5720.2 5720.5 5051.4 5088.9 5100.8 5109.1 5129.1 5949.9 5981.8 6030.2 6088.1 6159.3 5945.3 6007.2 6011.2 6021.0 6118.3 6021.9 6052.7 6058.9 6078.4 6093.2 5723.1 5824.4 5904.4 6001.7 6040.7 6087.5 6102.0 6119.4 6133.5 6137.7 5736.4 5915.0 5930.7 5965.0 5987.0 5820.4 5867.5 5955.3 5962.8 5997.9 5741.8 5788.0 5794.0 5794.4 5805.6 5760.2 5854.5 5855.8 5872.2 5887.8 5646.4 5735.4 5746.7 5751.9 5800.4 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48939.11 Median:194173.56 stddev:84243.80 (43.4%) stddev-sans-first:84401.77 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: Values: 546.9 596.0 598.8 615.0 629.4 36792.0 37362.3 37415.0 37493.8 37550.2 41424.6 41881.8 41918.7 41956.6 42014.4 8681.7 8859.1 8875.7 8877.7 8887.3 8575.8 8693.7 8698.8 8711.9 8717.3 8626.4 8727.1 8732.8 8755.1 8772.3 8624.8 8659.2 8691.5 8732.3 8752.3 8578.8 8640.4 8686.2 8710.3 8724.2 38012.8 38741.4 38798.8 38838.4 38856.8 3267.0 3316.5 3416.2 3527.0 3585.2 29140.0 30121.4 30924.2 30951.2 30961.5 3718.0 3785.5 3790.5 3857.1 3950.0 248260.0 254756.0 255024.0 255419.0 255540.0 58302.4 59002.7 59801.4 66918.0 67800.0 65576.3 66007.3 66311.0 66334.3 66683.7 14032.2 14228.7 14308.0 14381.9 14409.7 10021.4 10260.3 10266.1 10305.6 10352.4 20797.2 21168.0 21321.7 21346.7 21404.8 8382.7 8386.0 8548.0 8553.1 8723.7 880.0 948.1 952.1 990.3 1028.1 32229.4 33717.5 33845.5 33944.8 34045.3 1025.9 1043.1 1079.5 1080.9 1113.7 13858.3 14197.1 14310.6 14442.0 14480.5 384072.0 384490.0 385735.0 387203.0 390014.0 51168.2 51240.7 51404.7 51544.0 51608.7 1295.5 1354.6 1355.2 1357.9 1364.7 25919.7 26613.0 26852.5 27047.2 27256.0 40933.4 41352.4 41369.4 41402.4 41425.2 305640.0 307176.0 309429.0 309879.0 310435.0 31172.7 45841.8 45854.5 45886.5 46261.3 42226.8 43016.6 43179.8 43247.0 43436.0 36642.6 36739.0 37481.0 41121.8 42497.6 40241.0 41405.0 41653.0 41873.6 42494.0 35230.3 35529.6 35564.5 37312.1 38090.0 29388.0 32673.8 32776.8 32944.3 33144.3 24873.4 25060.0 25096.2 25292.9 25357.9 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28865.55 Median:376.71 stddev:90231.62 (23952.8%) stddev-sans-first:90459.32 20:04:21 INFO - 2015-12-16 20:04:21,255 DEBUG : BROWSER_OUTPUT: Values: 343.0 346.6 361.7 362.0 363.2 17222.3 17404.9 17833.9 17978.6 18785.2 14080.4 14128.5 14249.9 14252.1 14413.6 4762.4 5008.1 5036.5 5040.7 5043.3 4994.9 5021.9 5027.1 5047.6 5048.1 5046.3 5121.2 5145.9 5289.0 5355.5 5233.7 5235.8 5245.4 5262.5 5268.8 5083.8 5311.7 5313.9 5315.5 5336.1 11997.5 12352.7 14263.7 19596.0 19665.1 2189.7 2192.9 2222.2 2225.9 2252.2 9429.8 11766.3 12864.8 13070.3 13503.9 1803.6 1818.5 1819.8 1881.5 1896.4 58202.4 59050.0 62299.0 62596.7 63056.7 9893.9 10062.1 10108.3 10129.6 10177.1 13074.0 13103.8 13119.8 13147.7 13196.3 7049.9 7147.5 7169.2 7181.5 7241.7 6785.3 6812.3 7180.1 7200.4 7216.1 4225.0 4308.0 4311.9 4321.8 4330.0 1766.1 1768.6 1769.2 1789.7 1799.3 390.2 396.4 405.4 405.5 962.0 10431.5 10487.6 10520.8 10530.6 10583.2 405.7 405.9 418.0 427.9 435.4 5809.5 5983.9 5986.6 6019.4 6075.3 545050.0 546617.0 548623.0 557425.0 559834.0 82273.8 82845.6 83244.6 84238.0 84729.0 468.7 471.8 1072.7 1137.5 1142.5 14939.8 15160.6 15184.9 15303.8 15536.6 32231.4 33975.0 34318.7 36239.3 36289.5 46312.1 46659.0 46846.5 47277.3 47442.8 9119.8 9485.2 9678.7 9678.9 9732.7 16582.8 16679.0 16817.8 17109.4 17297.8 13705.0 14452.3 14591.2 14615.3 15506.1 14000.1 14053.3 14158.9 14237.5 14358.9 14986.8 15045.5 15141.4 15192.5 15743.7 9191.5 9329.8 9421.5 9775.0 9777.0 14440.3 14517.2 14847.0 15126.0 15306.5 20:04:21 INFO - 2015-12-16 20:04:21,256 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,256 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5315.28 Median:6446.27 stddev:2766.99 (42.9%) stddev-sans-first:2759.91 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: Values: 1502.8 1679.6 1699.5 1730.2 1735.9 7488.6 7627.9 7734.8 7747.7 7837.6 7575.1 7580.8 7649.2 7665.1 7668.4 2233.9 2261.2 2279.5 2279.7 2282.6 2232.9 2256.8 2261.7 2264.8 2279.2 2074.6 2099.1 2185.4 2191.8 2211.9 2228.7 2239.6 2244.2 2254.9 2256.8 2184.9 2193.1 2199.2 2202.1 2203.6 7554.7 7636.3 7649.3 7651.2 7668.7 4457.0 4481.6 4498.6 4533.5 4570.6 3031.7 3058.1 3097.6 3113.3 3121.7 980.7 985.8 991.6 1009.9 1012.1 10344.5 10385.4 10402.1 10413.3 10450.8 4100.1 4146.6 4147.7 4159.7 4172.3 8435.9 8436.4 8474.7 8489.4 8492.9 4402.6 4437.2 4471.9 4475.4 4476.4 4067.6 4084.4 4086.4 4097.9 4102.2 3386.0 3441.3 3445.5 3454.1 3478.3 1051.6 1059.3 1064.7 1065.2 1073.0 955.8 1004.2 1056.1 1185.4 1300.6 7101.5 7109.3 7134.4 7144.8 7193.1 614.6 629.6 635.4 641.8 779.8 5431.3 5530.2 5563.4 5581.8 5586.7 8706.3 8902.6 8948.4 9162.6 9219.0 8987.2 9021.1 9031.1 9116.0 9164.3 6999.3 7177.4 7194.7 7205.2 7231.7 7193.5 7260.4 7267.5 7268.0 7282.6 7957.7 8132.1 8217.2 8220.5 8234.6 8344.3 8442.7 8487.4 8512.4 8545.7 6524.7 6783.4 6796.7 6824.9 6852.7 7326.1 7339.3 7382.0 7388.9 7396.0 6731.3 6734.8 6847.0 6903.8 7017.6 6566.8 6801.5 6853.4 6874.6 6908.2 6918.7 6924.8 6928.9 6952.8 6981.7 5913.6 6319.9 6342.9 6368.0 6487.7 6421.3 6441.3 6451.3 6455.2 6473.8 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5555.87 Median:6370.41 stddev:1721.80 (27.0%) stddev-sans-first:1681.05 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: Values: 312.0 320.4 325.0 326.6 330.6 6289.7 6291.2 6311.3 6330.0 6405.7 6202.8 6240.2 6258.6 6274.1 6277.7 4772.2 4773.9 4775.3 4776.4 4779.1 4772.1 4777.5 4779.4 4784.6 4786.3 4628.1 4705.8 4718.4 4726.2 4732.4 4718.5 4725.3 4730.4 4730.7 4735.6 4038.5 4056.5 4059.2 4062.8 4063.8 5952.1 6020.5 6025.2 6033.7 6046.2 5378.9 5457.2 5470.9 5479.2 5484.2 6691.5 6691.7 6697.8 6700.0 6703.9 4428.8 4436.9 4473.7 4485.2 4488.1 6769.8 6847.3 6924.9 6927.0 6935.9 4466.9 4472.3 4475.6 4484.1 4493.3 4484.8 4522.6 4526.0 4533.6 4537.8 5343.6 5358.2 5366.2 5367.8 5389.6 6537.0 6698.1 6706.6 6706.6 6726.6 1602.6 1619.9 1638.4 1639.0 1639.9 1048.7 1068.1 1081.4 1082.0 1092.0 6369.5 6377.3 6379.9 6393.8 6405.0 6867.1 6870.9 6871.1 6884.1 6885.6 6322.4 6371.3 6388.0 6388.5 6399.3 3782.8 3797.1 3804.5 3807.3 3808.9 7061.0 7080.8 7093.5 7133.7 7139.3 7005.5 7099.7 7161.9 7175.5 7216.5 7094.9 7119.5 7119.5 7119.7 7134.5 6915.8 6953.7 6954.6 6958.7 6967.4 6780.8 6906.8 6910.9 6921.2 6923.4 7120.1 7121.6 7131.3 7131.7 7134.4 4565.9 4578.1 4579.2 4585.5 4591.0 6819.7 6975.3 6982.5 6995.8 7001.6 6714.6 6769.7 6782.1 6786.8 6800.2 6772.3 6772.9 6777.7 6781.8 6787.8 6525.2 6644.1 6647.4 6651.9 6665.9 6749.6 6796.8 6797.3 6797.5 6806.7 6500.9 6540.2 6588.3 6590.3 6607.6 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,257 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13799.25 Median:46022.88 stddev:15832.82 (34.4%) stddev-sans-first:15847.40 20:04:21 INFO - 2015-12-16 20:04:21,258 DEBUG : BROWSER_OUTPUT: Values: 856.8 858.0 861.4 863.9 898.5 13464.2 13604.7 13699.8 13710.7 13773.1 13595.5 13798.6 13802.3 13807.8 13854.4 806.0 816.2 818.0 820.2 839.5 817.8 824.8 824.9 829.3 830.1 858.1 859.1 863.5 864.3 865.4 853.6 857.2 860.3 862.4 863.0 628.8 631.9 633.4 635.3 635.8 13161.1 13278.5 13302.7 13334.4 13342.3 12002.4 12014.7 12184.7 12232.3 12292.9 10131.7 10147.0 10221.3 10291.9 10308.7 3591.9 3593.2 3696.4 3748.6 3759.9 45629.3 45648.3 45670.1 45977.0 46139.0 9857.1 9900.7 9979.7 9979.9 10096.8 17292.5 17350.6 17379.5 17381.2 17423.3 7405.1 7448.7 7491.6 7707.0 7836.3 4269.9 4307.2 4313.2 4331.2 4333.5 3013.5 3050.9 3052.2 3054.3 3058.8 536.7 541.7 547.4 555.5 565.7 541.6 678.3 699.5 705.4 731.6 7936.4 8410.5 8686.9 8746.9 8827.9 678.7 686.2 689.7 740.6 751.1 3872.0 4205.1 4234.5 4286.6 4349.2 55672.0 55808.0 56008.4 56399.2 56799.4 50183.7 50402.0 50797.0 50848.2 50960.5 46783.8 47035.8 49682.0 49934.0 50684.0 8708.3 9030.4 9126.8 9181.6 9220.2 33173.0 33310.3 33979.5 34022.3 34126.0 45862.3 45925.3 46068.8 46560.5 46729.0 10674.8 11158.7 11282.9 11359.0 11472.4 12049.6 12220.0 12282.0 12747.6 12768.2 11458.5 11489.8 11541.3 11960.9 12074.3 10763.1 11336.8 11537.3 11579.6 11967.2 11210.2 11409.7 11444.5 11728.9 11764.6 9801.9 10024.7 10052.0 10103.7 10429.0 9218.7 9316.8 9364.4 9582.7 9997.6 20:04:21 INFO - 2015-12-16 20:04:21,258 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:04:21 INFO - 2015-12-16 20:04:21,258 DEBUG : BROWSER_OUTPUT: 20:04:21 INFO - 2015-12-16 20:04:21,897 INFO : Browser exited with error code: 0 20:04:21 INFO - 2015-12-16 20:04:21,916 INFO : Completed test dromaeo_css (00:18:31) 20:04:21 INFO - 2015-12-16 20:04:21,916 INFO : Starting test kraken 20:04:21 INFO - 2015-12-16 20:04:21,916 DEBUG : operating with platform_type : linux_ 20:04:21 INFO - 2015-12-16 20:04:21,916 INFO : Initialising browser for kraken test... 20:04:21 INFO - 2015-12-16 20:04:21,922 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHMKTxo/profile http://localhost:39587/getInfo.html 20:04:26 INFO - 2015-12-16 20:04:26,756 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:04:26 INFO - 2015-12-16 20:04:26,756 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:04:26 INFO - 2015-12-16 20:04:26,756 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:04:26 INFO - 2015-12-16 20:04:26,756 DEBUG : BROWSER_OUTPUT: __metrics 20:04:26 INFO - 2015-12-16 20:04:26,877 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] 20:04:27 INFO - 2015-12-16 20:04:27,725 INFO : Browser initialized. 20:04:27 INFO - 2015-12-16 20:04:27,726 INFO : Running cycle 1/1 for kraken test... 20:04:27 INFO - 2015-12-16 20:04:27,726 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHMKTxo/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:04:39 INFO - 2015-12-16 20:04:39,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 143937536 20:04:39 INFO - 2015-12-16 20:04:39,525 DEBUG : BROWSER_OUTPUT: 20:04:41 INFO - 2015-12-16 20:04:41,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39587/tests/kraken/driver.html?testName=audio-beat-detection) 20:04:42 INFO - 2015-12-16 20:04:42,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 145694720 20:04:42 INFO - 2015-12-16 20:04:42,613 DEBUG : BROWSER_OUTPUT: 20:04:45 INFO - 2015-12-16 20:04:45,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39587/tests/kraken/driver.html?testName=audio-dft) 20:04:45 INFO - 2015-12-16 20:04:45,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 144351232 20:04:45 INFO - 2015-12-16 20:04:45,852 DEBUG : BROWSER_OUTPUT: 20:04:48 INFO - 2015-12-16 20:04:48,702 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39587/tests/kraken/driver.html?testName=audio-fft) 20:04:49 INFO - 2015-12-16 20:04:49,283 DEBUG : BROWSER_OUTPUT: RSS: Main: 139276288 20:04:49 INFO - 2015-12-16 20:04:49,283 DEBUG : BROWSER_OUTPUT: 20:04:51 INFO - 2015-12-16 20:04:51,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39587/tests/kraken/driver.html?testName=audio-oscillator) 20:04:51 INFO - 2015-12-16 20:04:51,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 138829824 20:04:51 INFO - 2015-12-16 20:04:51,837 DEBUG : BROWSER_OUTPUT: 20:04:54 INFO - 2015-12-16 20:04:54,050 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39587/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:04:54 INFO - 2015-12-16 20:04:54,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 139583488 20:04:54 INFO - 2015-12-16 20:04:54,631 DEBUG : BROWSER_OUTPUT: 20:04:57 INFO - 2015-12-16 20:04:57,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39587/tests/kraken/driver.html?testName=imaging-darkroom) 20:04:58 INFO - 2015-12-16 20:04:58,188 DEBUG : BROWSER_OUTPUT: RSS: Main: 140570624 20:04:58 INFO - 2015-12-16 20:04:58,188 DEBUG : BROWSER_OUTPUT: 20:05:01 INFO - 2015-12-16 20:05:01,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39587/tests/kraken/driver.html?testName=imaging-desaturate) 20:05:02 INFO - 2015-12-16 20:05:02,108 DEBUG : BROWSER_OUTPUT: RSS: Main: 141459456 20:05:02 INFO - 2015-12-16 20:05:02,108 DEBUG : BROWSER_OUTPUT: 20:05:04 INFO - 2015-12-16 20:05:04,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39587/tests/kraken/driver.html?testName=json-parse-financial) 20:05:05 INFO - 2015-12-16 20:05:05,464 DEBUG : BROWSER_OUTPUT: RSS: Main: 142118912 20:05:05 INFO - 2015-12-16 20:05:05,464 DEBUG : BROWSER_OUTPUT: 20:05:07 INFO - 2015-12-16 20:05:07,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39587/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:05:07 INFO - 2015-12-16 20:05:07,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 141656064 20:05:07 INFO - 2015-12-16 20:05:07,644 DEBUG : BROWSER_OUTPUT: 20:05:09 INFO - 2015-12-16 20:05:09,466 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:05:10 INFO - 2015-12-16 20:05:10,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 140570624 20:05:10 INFO - 2015-12-16 20:05:10,080 DEBUG : BROWSER_OUTPUT: 20:05:12 INFO - 2015-12-16 20:05:12,197 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:05:12 INFO - 2015-12-16 20:05:12,783 DEBUG : BROWSER_OUTPUT: RSS: Main: 141099008 20:05:12 INFO - 2015-12-16 20:05:12,783 DEBUG : BROWSER_OUTPUT: 20:05:15 INFO - 2015-12-16 20:05:15,258 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:05:15 INFO - 2015-12-16 20:05:15,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 141635584 20:05:15 INFO - 2015-12-16 20:05:15,848 DEBUG : BROWSER_OUTPUT: 20:05:18 INFO - 2015-12-16 20:05:18,468 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:05:19 INFO - 2015-12-16 20:05:19,050 DEBUG : BROWSER_OUTPUT: RSS: Main: 142139392 20:05:19 INFO - 2015-12-16 20:05:19,050 DEBUG : BROWSER_OUTPUT: 20:05:20 INFO - 2015-12-16 20:05:20,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39587/tests/kraken/driver.html?testName=ai-astar) 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: RSS: Main: 141303808 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:05:21 INFO - 2015-12-16 20:05:21,005 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;106;111;111;111;123;112;110;111;110 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;127;151;171;124;146;155;131;165;125 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;163;163;163;161;160;166;162;168;163 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;73;78;72;141;74;78;71;74 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;133;94;94;95;141;92;93;140 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;111;110;109;110;110;111;110 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;147;147;147;149;148;147;146;147;146 20:05:21 INFO - 2015-12-16 20:05:21,006 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;95;97;97;95;96;96 20:05:21 INFO - 2015-12-16 20:05:21,007 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;66;61;61;62;64;61;61;58;59 20:05:21 INFO - 2015-12-16 20:05:21,007 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;66;67;67;67;68;68;66;67;68 20:05:21 INFO - 2015-12-16 20:05:21,007 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;93;85;86;94;86;86;86;85;84;87 20:05:21 INFO - 2015-12-16 20:05:21,007 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;124;122;124;118;125;123;123;119;133 20:05:21 INFO - 2015-12-16 20:05:21,007 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;151;154;146;150;158;152;156;151;140 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;68;73;68;69;68;67;70;66;68 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,703 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:05:21 INFO - 2015-12-16 20:05:21,008 DEBUG : BROWSER_OUTPUT: __startTimestamp1450325120999__endTimestamp 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: Number of tests: 14 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:4.35 (3.9%) stddev-sans-first:4.58 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: Values: 110.0 106.0 111.0 111.0 111.0 123.0 112.0 110.0 111.0 110.0 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.10 Median:153.00 stddev:17.30 (11.3%) stddev-sans-first:17.90 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: Values: 156.0 127.0 151.0 171.0 124.0 146.0 155.0 131.0 165.0 125.0 20:05:21 INFO - 2015-12-16 20:05:21,009 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.31 (1.4%) stddev-sans-first:2.44 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: Values: 164.0 163.0 163.0 163.0 161.0 160.0 166.0 162.0 168.0 163.0 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.40 Median:73.50 stddev:21.44 (29.2%) stddev-sans-first:22.47 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 73.0 78.0 72.0 141.0 74.0 78.0 71.0 74.0 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.90 Median:93.50 stddev:21.57 (23.1%) stddev-sans-first:22.37 20:05:21 INFO - 2015-12-16 20:05:21,010 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 133.0 94.0 94.0 95.0 141.0 92.0 93.0 140.0 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 111.0 110.0 109.0 110.0 110.0 111.0 110.0 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 20:05:21 INFO - 2015-12-16 20:05:21,011 DEBUG : BROWSER_OUTPUT: Values: 149.0 147.0 147.0 147.0 149.0 148.0 147.0 146.0 147.0 146.0 20:05:21 INFO - 2015-12-16 20:05:21,012 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,012 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 20:05:21 INFO - 2015-12-16 20:05:21,012 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 95.0 97.0 97.0 95.0 96.0 96.0 20:05:21 INFO - 2015-12-16 20:05:21,012 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,012 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.60 Median:61.50 stddev:2.32 (3.8%) stddev-sans-first:2.40 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: Values: 63.0 66.0 61.0 61.0 62.0 64.0 61.0 61.0 58.0 59.0 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: Values: 67.0 66.0 67.0 67.0 67.0 68.0 68.0 66.0 67.0 68.0 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,013 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:86.00 stddev:3.43 (4.0%) stddev-sans-first:2.92 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: Values: 93.0 85.0 86.0 94.0 86.0 86.0 86.0 85.0 84.0 87.0 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.50 stddev:4.27 (3.5%) stddev-sans-first:4.28 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: Values: 119.0 124.0 122.0 124.0 118.0 125.0 123.0 123.0 119.0 133.0 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,014 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.80 Median:151.50 stddev:6.12 (4.0%) stddev-sans-first:5.37 20:05:21 INFO - 2015-12-16 20:05:21,015 DEBUG : BROWSER_OUTPUT: Values: 140.0 151.0 154.0 146.0 150.0 158.0 152.0 156.0 151.0 140.0 20:05:21 INFO - 2015-12-16 20:05:21,015 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,015 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:1.95 (2.8%) stddev-sans-first:2.01 20:05:21 INFO - 2015-12-16 20:05:21,015 DEBUG : BROWSER_OUTPUT: Values: 70.0 68.0 73.0 68.0 69.0 68.0 67.0 70.0 66.0 68.0 20:05:21 INFO - 2015-12-16 20:05:21,016 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:05:21 INFO - 2015-12-16 20:05:21,016 DEBUG : BROWSER_OUTPUT: 20:05:21 INFO - 2015-12-16 20:05:21,877 INFO : Browser exited with error code: 0 20:05:21 INFO - 2015-12-16 20:05:21,924 INFO : Completed test kraken (00:01:00) 20:05:21 INFO - 2015-12-16 20:05:21,924 INFO : Starting test v8_7 20:05:21 INFO - 2015-12-16 20:05:21,924 DEBUG : operating with platform_type : linux_ 20:05:21 INFO - 2015-12-16 20:05:21,925 INFO : Initialising browser for v8_7 test... 20:05:21 INFO - 2015-12-16 20:05:21,930 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpT7FjD6/profile http://localhost:39587/getInfo.html 20:05:26 INFO - 2015-12-16 20:05:26,572 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:05:26 INFO - 2015-12-16 20:05:26,572 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:05:26 INFO - 2015-12-16 20:05:26,572 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:05:26 INFO - 2015-12-16 20:05:26,572 DEBUG : BROWSER_OUTPUT: __metrics 20:05:26 INFO - 2015-12-16 20:05:26,689 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] 20:05:27 INFO - 2015-12-16 20:05:27,649 INFO : Browser initialized. 20:05:27 INFO - 2015-12-16 20:05:27,650 INFO : Running cycle 1/1 for v8_7 test... 20:05:27 INFO - 2015-12-16 20:05:27,650 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpT7FjD6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:05:39 INFO - 2015-12-16 20:05:39,469 DEBUG : BROWSER_OUTPUT: RSS: Main: 143646720 20:05:39 INFO - 2015-12-16 20:05:39,469 DEBUG : BROWSER_OUTPUT: 20:06:00 INFO - 2015-12-16 20:06:00,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39587/tests/v8_7/run.html (next: http://localhost:39587/tests/v8_7/run.html) 20:06:01 INFO - 2015-12-16 20:06:01,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 138899456 20:06:01 INFO - 2015-12-16 20:06:01,148 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,148 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:06:01 INFO - 2015-12-16 20:06:01,148 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:06:01 INFO - 2015-12-16 20:06:01,148 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:06:01 INFO - 2015-12-16 20:06:01,149 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:06:01 INFO - 2015-12-16 20:06:01,149 DEBUG : BROWSER_OUTPUT: |0;Richards;166.47244880972198 20:06:01 INFO - 2015-12-16 20:06:01,149 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.7153594141453 20:06:01 INFO - 2015-12-16 20:06:01,149 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.60748723766307 20:06:01 INFO - 2015-12-16 20:06:01,149 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5218.75 20:06:01 INFO - 2015-12-16 20:06:01,150 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1052.6315789473683 20:06:01 INFO - 2015-12-16 20:06:01,150 DEBUG : BROWSER_OUTPUT: |5;Earley;719.1091954022988 20:06:01 INFO - 2015-12-16 20:06:01,150 DEBUG : BROWSER_OUTPUT: |6;Boyer;12243.90243902439 20:06:01 INFO - 2015-12-16 20:06:01,150 DEBUG : BROWSER_OUTPUT: |7;RegExp;25025 20:06:01 INFO - 2015-12-16 20:06:01,150 DEBUG : BROWSER_OUTPUT: |8;Splay;550.05500550055 20:06:01 INFO - 2015-12-16 20:06:01,151 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 20:06:01 INFO - 2015-12-16 20:06:01,151 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:06:01 INFO - 2015-12-16 20:06:01,151 DEBUG : BROWSER_OUTPUT: __startTimestamp1450325161146__endTimestamp 20:06:01 INFO - 2015-12-16 20:06:01,151 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,151 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: Number of tests: 10 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.47 Median:166.47 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: Values: 166.5 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.72 Median:192.72 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,152 DEBUG : BROWSER_OUTPUT: Values: 192.7 20:06:01 INFO - 2015-12-16 20:06:01,153 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,153 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.61 Median:283.61 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,153 DEBUG : BROWSER_OUTPUT: Values: 283.6 20:06:01 INFO - 2015-12-16 20:06:01,153 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,154 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5218.75 Median:5218.75 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,154 DEBUG : BROWSER_OUTPUT: Values: 5218.8 20:06:01 INFO - 2015-12-16 20:06:01,154 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,154 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1052.63 Median:1052.63 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,154 DEBUG : BROWSER_OUTPUT: Values: 1052.6 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:719.11 Median:719.11 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: Values: 719.1 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,155 DEBUG : BROWSER_OUTPUT: Values: 12243.9 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25025.00 Median:25025.00 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: Values: 25025.0 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:550.06 Median:550.06 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,157 DEBUG : BROWSER_OUTPUT: Values: 550.1 20:06:01 INFO - 2015-12-16 20:06:01,158 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,158 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 20:06:01 INFO - 2015-12-16 20:06:01,158 DEBUG : BROWSER_OUTPUT: Values: 4840.6 20:06:01 INFO - 2015-12-16 20:06:01,158 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:06:01 INFO - 2015-12-16 20:06:01,158 DEBUG : BROWSER_OUTPUT: 20:06:01 INFO - 2015-12-16 20:06:01,993 INFO : Browser exited with error code: 0 20:06:02 INFO - 2015-12-16 20:06:02,005 INFO : Completed test v8_7 (00:00:40) 20:06:02 INFO - 2015-12-16 20:06:02,179 INFO : Completed test suite (00:20:11) 20:06:02 INFO - 2015-12-16 20:06:02,179 DEBUG : Working with test: dromaeo_css 20:06:02 INFO - 2015-12-16 20:06:02,179 DEBUG : Generating results file: dromaeo_css 20:06:02 INFO - 2015-12-16 20:06:02,180 DEBUG : Working with test: kraken 20:06:02 INFO - 2015-12-16 20:06:02,180 DEBUG : Generating results file: kraken 20:06:02 INFO - 2015-12-16 20:06:02,181 INFO : javascript benchmark 20:06:02 INFO - 2015-12-16 20:06:02,181 DEBUG : Working with test: v8_7 20:06:02 INFO - 2015-12-16 20:06:02,181 DEBUG : Generating results file: v8_7 20:06:02 INFO - 2015-12-16 20:06:02,181 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:06:02 INFO - 2015-12-16 20:06:02,252 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 20:06:02 INFO - 2015-12-16 20:06:02,253 DEBUG : process_Request line: dromaeo_css 6987.62 graph.html#tests=[[72,64,43]] 20:06:02 INFO - 2015-12-16 20:06:02,253 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:06:02 INFO - 2015-12-16 20:06:02,288 DEBUG : process_Request line: kraken 1517.90 graph.html#tests=[[232,64,43]] 20:06:02 INFO - 2015-12-16 20:06:02,288 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:06:02 INFO - 2015-12-16 20:06:02,326 DEBUG : process_Request line: v8_7 21514.21 graph.html#tests=[[230,64,43]] 20:06:02 INFO - 2015-12-16 20:06:02,328 INFO : javascript benchmark 20:06:02 INFO - 2015-12-16 20:06:02,331 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3216.0673404712634, 3266.4120458317507, 3291.0, 3310.8344582770865, 3320.3525312564043, 5234.79060837567, 5493.862653433666, 5516.14499752538, 5547.112462006075, 5566.944330556693, 5354.464553544643, 5410.8917821643545, 5421.674699518022, 5431.592630552705, 5460.426655201206, 4989.2017277235645, 5021.447640759514, 5109.821156259532, 5199.688018718889, 5244.318238628973, 4956.578690811285, 5093.516115968995, 5130.9743451282875, 5147.613928955325, 5189.403218629868, 4905.999999999982, 4942.036302920932, 4951.678140920829, 5003.47463516331, 5009.849704508871, 5009.824656137054, 5042.142835717937, 5067.189249720027, 5096.490350964911, 5119.744012799345, 4918.532739389752, 4968.7018778873025, 5036.571891389236, 5038.345015148044, 5093.541581257705, 5317.627766056377, 5337.146056630959, 5353.170258609883, 5368.677879327214, 5382.300300960889, 5940.049592065248, 5997.310309314441, 6025.186599809013, 6037.818865434, 6046.939530604725, 6020.187274717945, 6076.878462430726, 6077.604955677867, 6081.3006504252135, 6082.817515474543, 5616.859578510529, 5665.603407761459, 5665.858353541112, 5666.036773748473, 5723.942760572382, 6043.456088952015, 6048.758049678052, 6084.787032453843, 6101.664408457577, 6114.144019837227, 5725.255716756795, 5820.738066787005, 5838.620489668158, 5943.524518038546, 5949.524038076944, 5984.162217289583, 5984.640921544721, 5986.191864098291, 5988.9101663475085, 5999.490043346277, 5953.196318497034, 6012.939870601325, 6017.1275165100815, 6042.516598672096, 6069.423404776539, 6054.455099041019, 6055.909161362583, 6098.69506524672, 6111.755529778759, 6118.0, 4451.933221001687, 4538.977305113453, 4590.081983603292, 4646.86059418218, 4664.277037059262, 4027.194561087794, 4079.265732168238, 4099.590040995936, 4128.876133715993, 4135.214309281227, 5617.0, 5627.71861406928, 5640.97179514108, 5655.830325090254, 5656.575756818255, 5825.650460972355, 5918.496927761188, 5961.552883533753, 6015.518758499397, 6037.275526936708, 5688.004599195125, 5707.800226992118, 5720.170575266647, 5720.1991721159075, 5720.48515633595, 5051.393832740095, 5088.92366614501, 5100.821471248489, 5109.08036553424, 5129.076766182048, 5949.9107513387335, 5981.760729570856, 6030.216071910536, 6088.056351265561, 6159.3224745277985, 5945.34601193877, 6007.219061521974, 6011.218541589566, 6021.036634138517, 6118.2658081028785, 6021.8558473889825, 6052.66710330936, 6058.878822423439, 6078.361772014052, 6093.207882975362, 5723.141528770718, 5824.446677565625, 5904.350521442552, 6001.669908155093, 6040.728167232573, 6087.543434242538, 6101.969490152521, 6119.418655227746, 6133.509319254538, 6137.723802428991, 5736.397678243891, 5915.0, 5930.703464826655, 5965.0, 5986.970065149646, 5820.359760426265, 5867.5305975521005, 5955.315138759098, 5962.761489540369, 5997.880042399215, 5741.827745167651, 5788.044972579477, 5794.015017447128, 5794.420557944172, 5805.622634528742, 5760.164776107525, 5854.4730974212525, 5855.824325270249, 5872.236609240771, 5887.793927212527, 5646.407127251744, 5735.426457354231, 5746.655200687972, 5751.9424805751405, 5800.361960184461], "unit": "score", "name": "dojo.html", "value": 5501.556417195846}, {"lowerIsBetter": false, "replicates": [546.8644509172278, 595.9761609535619, 598.7814796888332, 615.0221148374085, 629.4052120745897, 36792.0, 37362.25275494492, 37415.0, 37493.81253093734, 37550.24899502008, 41424.58575414245, 41881.79059104708, 41918.69447180404, 41956.58043419565, 42014.36978445326, 8681.696140635064, 8859.11408859112, 8875.73372798817, 8877.733667989938, 8887.289016878634, 8575.828483430327, 8693.69572064978, 8698.826023479527, 8711.869321960176, 8717.302615790753, 8626.439281446686, 8727.127287271285, 8732.825343493127, 8755.124487551258, 8772.342074344419, 8624.827503449897, 8659.177378149066, 8691.47851128928, 8732.345074119467, 8752.299816014704, 8578.785530361729, 8640.351973602004, 8686.218240358336, 8710.303175745988, 8724.214820666171, 38012.80993595014, 38741.41887871656, 38798.80600597007, 38838.41742373867, 38856.805715971524, 3267.039815807235, 3316.513327322632, 3416.214270717724, 3527.012436517779, 3585.2470981094, 29140.0, 30121.397572048438, 30924.226894327596, 30951.226219344473, 30961.535576966362, 3717.9814100929593, 3785.4511095890944, 3790.469334293201, 3857.112864041258, 3949.9605003949882, 248260.0, 254756.0, 255024.0, 255419.0, 255539.99999999814, 58302.41697583055, 59002.70498647479, 59801.401985980454, 66918.0, 67800.0, 65576.34423655798, 66007.33992660012, 66311.0, 66334.33665663282, 66683.66658166776, 14032.158070515801, 14228.715425691635, 14308.0, 14381.92809035948, 14409.711805763825, 10021.448820314807, 10260.281780275309, 10266.076053155102, 10305.587776488857, 10352.430616316024, 20797.19237902894, 21168.0, 21321.68017479739, 21346.679799803016, 21404.78595214028, 8382.748517544484, 8385.95807020961, 8548.0, 8553.101924297984, 8723.738287851374, 879.9912000879909, 948.0566835998226, 952.1145334838528, 990.301837204769, 1028.1466382902174, 32229.35541289161, 33717.494237586456, 33845.49231761526, 33944.83027584896, 34045.31909361799, 1025.8666373371414, 1043.1498328861953, 1079.519613771864, 1080.897314755097, 1113.6714669172452, 13858.307084645727, 14197.148171109766, 14310.57068287953, 14442.0, 14480.493182738554, 384072.0, 384490.0, 385735.0, 387203.0, 390014.0, 51168.23247651288, 51240.74379628078, 51404.742976285626, 51543.96912061738, 51608.74195628998, 1295.5206573567652, 1354.6003928840819, 1355.200431745275, 1357.8845798107272, 1364.740699267136, 25919.740802591732, 26613.0, 26852.46295074127, 27047.1885843425, 27256.0, 40933.38599921063, 41352.37971430371, 41369.37945930753, 41402.378964316165, 41425.1714965705, 305640.0, 307176.0, 309429.0, 309879.0, 310435.0, 31172.688273117885, 45841.77079114583, 45854.54145458636, 45886.541134588224, 46261.30608040815, 42226.788866056704, 43016.569834301255, 43179.78410108055, 43247.0, 43436.0, 36642.633573664985, 36739.0, 37481.0, 41121.794391027855, 42497.5750242506, 40241.0, 41405.0, 41653.0, 41873.581264188186, 42494.0, 35230.29539409146, 35529.644703552636, 35564.466533001505, 37312.067198320256, 38090.0, 29388.0, 32673.836630816695, 32776.836115820224, 32944.34111317808, 33144.33711325712, 24873.378165546008, 25060.0, 25096.24711258665, 25292.873535632203, 25357.87321063383], "unit": "score", "name": "ext.html", "value": 18832.185947977145}, {"lowerIsBetter": false, "replicates": [342.9588449386072, 346.63949492527786, 361.6564263949248, 362.0189287032144, 363.184650459718, 17222.3111075557, 17404.912975435105, 17833.910830445846, 17978.640427191447, 18785.154668039937, 14080.36638351274, 14128.505502307422, 14249.857501424982, 14252.073615215004, 14413.63965900853, 4762.380890484233, 5008.1486147355035, 5036.546710796028, 5040.747962601874, 5043.319151914481, 4994.875128121808, 5021.949780502185, 5027.09512287788, 5047.621428392867, 5048.066107770056, 5046.343975283208, 5121.155009423441, 5145.922811157836, 5289.0, 5355.464453554652, 5233.738313084331, 5235.816746413857, 5245.423003469616, 5262.473752624726, 5268.762905669235, 5083.847484575469, 5311.681299122065, 5313.8937221255355, 5315.521603055705, 5336.146216605363, 11997.520099196021, 12352.691182720504, 14263.714725705428, 19596.0, 19665.11506982189, 2189.748178959424, 2192.8574642648177, 2222.1889010511045, 2225.922092726763, 2252.2342403582697, 9429.764255893657, 11766.294022358601, 12864.807027894589, 13070.281134537692, 13503.864961350457, 1803.6482885837268, 1818.5453636590853, 1819.8362147406797, 1881.5014021284371, 1896.3741965151467, 58202.41797582012, 59050.0, 62299.0, 62596.68701656508, 63056.68471657612, 9893.901060989441, 10062.144717698931, 10108.342957707873, 10129.645462408927, 10177.084062434416, 13074.0, 13103.803442948363, 13119.803202951964, 13147.737045259042, 13196.340182990813, 7049.89425158623, 7147.53541019832, 7169.211386747454, 7181.533200341961, 7241.674124664402, 6785.321467853175, 6812.284710105368, 7180.102487189101, 7200.351968322876, 7216.134063912364, 4224.978875105605, 4308.0, 4311.935320970188, 4321.762303073361, 4330.0, 1766.132170546499, 1768.619746754454, 1769.2127003483583, 1789.7404876292865, 1799.2623024559869, 390.2273498472983, 396.4212250114807, 405.4404921208713, 405.48705887052904, 962.0379620379621, 10431.478426078666, 10487.580496780196, 10520.842187367196, 10530.578776848994, 10583.206259530412, 405.68559366491206, 405.8924385037967, 417.96656267498525, 427.890887823603, 435.3991491741377, 5809.535237181015, 5983.9401605983385, 5986.551008674367, 6019.398060194033, 6075.33171351151, 545050.0, 546617.0, 548623.0, 557425.0, 559834.0, 82273.76589351165, 82845.58577207197, 83244.58377708073, 84238.0, 84728.99999999876, 468.67427138139345, 471.790053426222, 1072.7264547540328, 1137.4881303413497, 1142.451623220842, 14939.77590336124, 15160.620984475476, 15184.924075379553, 15303.846961530242, 15536.61158471002, 32231.355372891943, 33974.980750577524, 34318.656813431546, 36239.27521449609, 36289.45565816462, 46312.073758525315, 46659.0, 46846.53153468558, 47277.2908406381, 47442.76278618585, 9119.772005699911, 9485.241180705389, 9678.661246856607, 9678.854817177607, 9732.659356922473, 16582.75125873137, 16679.0, 16817.76926682865, 17109.401170958958, 17297.827021729623, 13705.0, 14452.277386130861, 14591.197484138474, 14615.34230959588, 15506.069635821887, 14000.089994150349, 14053.297335133408, 14158.929205353907, 14237.50168744089, 14358.928205358907, 14986.775198372252, 15045.548633541011, 15141.394344226108, 15192.468263611163, 15743.68512629764, 9191.540422978958, 9329.813403732023, 9421.481818500048, 9774.951125244614, 9777.022297770167, 14440.277986100864, 14517.201553914636, 14847.0, 15126.0, 15306.464273750365], "unit": "score", "name": "jquery.html", "value": 8184.491775693641}, {"lowerIsBetter": false, "replicates": [1502.7896094546759, 1679.6220850308678, 1699.5496193508723, 1730.1781653714488, 1735.852452541534, 7488.588127652977, 7627.885581716272, 7734.7679569612865, 7747.728829490969, 7837.568933708644, 7575.053118360205, 7580.77257682269, 7649.235076492348, 7665.118511371194, 7668.4248681348845, 2233.8659680419146, 2261.208576998046, 2279.4985103277254, 2279.7378301495373, 2282.611955967481, 2232.944176395595, 2256.808171305441, 2261.694671219389, 2264.750877403484, 2279.247848210087, 2074.553970896257, 2099.1183702844764, 2185.3771675072608, 2191.824654027682, 2211.9115235390564, 2228.699125618037, 2239.585676649825, 2244.2145249162827, 2254.9436264093365, 2256.751757306695, 2184.9235276765394, 2193.122750899637, 2199.186301068599, 2202.0971401725214, 2203.5813195492806, 7554.660040298199, 7636.31273185416, 7649.273319034683, 7651.23487651236, 7668.654910528984, 4457.0, 4481.574250446202, 4498.640108791322, 4533.455985281755, 4570.6343492520855, 3031.711987361219, 3058.113147187313, 3097.6127984002, 3113.2995076107923, 3121.672224416449, 980.7450062983604, 985.8422652375657, 991.5984026469291, 1009.9495025248708, 1012.0739523336139, 10344.482775861177, 10385.376877387382, 10402.063814256753, 10413.271071024956, 10450.843237351446, 4100.1184745279315, 4146.606072423115, 4147.730397524151, 4159.667226621863, 4172.269852775752, 8435.915640843514, 8436.367272454469, 8474.661013559511, 8489.36329775257, 8492.872606910902, 4402.581754733295, 4437.201303765289, 4471.888202794956, 4475.351074094238, 4476.4180656514445, 4067.572904844949, 4084.387341898739, 4086.4483294755423, 4097.8975525612095, 4102.220578090153, 3385.96727997963, 3441.3117376524797, 3445.4659527772733, 3454.101933497309, 3478.3217272631596, 1051.5688567687212, 1059.2680966619632, 1064.669952314785, 1065.2436769893307, 1073.0181883576597, 955.7610597350663, 1004.1916257412706, 1056.0706578211125, 1185.3836005277208, 1300.596814987357, 7101.538400003983, 7109.324614161646, 7134.393576545948, 7144.821379465555, 7193.136823581203, 614.6061092046453, 629.568745409396, 635.3931994944817, 641.8299150725062, 779.8089468080357, 5431.293931788921, 5530.198121272393, 5563.443655634404, 5581.832545023656, 5586.720663966867, 8706.303495720455, 8902.643894244287, 8948.373613846969, 9162.587683554257, 9218.953905230432, 8987.19115279628, 9021.142991415805, 9031.096890310917, 9116.0, 9164.312676549285, 6999.30006999296, 7177.3899218567, 7194.676239569232, 7205.207427183006, 7231.710731570678, 7193.4604904631315, 7260.419166466776, 7267.527610705287, 7268.0, 7282.5994570297025, 7957.721479748181, 8132.105468398589, 8217.178282171973, 8220.506769593843, 8234.588270586566, 8344.290735287568, 8442.704505342284, 8487.405881588229, 8512.361572882182, 8545.658173672984, 6524.67376631176, 6783.3555812197765, 6796.72813087471, 6824.897626535507, 6852.657367131723, 7326.084239470067, 7339.302766237199, 7382.0, 7388.852222955618, 7395.963020184865, 6731.293214212633, 6734.764283250062, 6847.0, 6903.792886213421, 7017.578945263301, 6566.80299591013, 6801.489888258301, 6853.383195512428, 6874.621895795579, 6908.171019477695, 6918.654067296515, 6924.826879328057, 6928.93071069303, 6952.826179345557, 6981.685824137824, 5913.5564832636865, 6319.905201422074, 6342.904856427065, 6368.044793281045, 6487.708053137526, 6421.293657697743, 6441.259255185713, 6451.290358060516, 6455.160829092188, 6473.77341793035], "unit": "score", "name": "mootools.html", "value": 4319.365776444546}, {"lowerIsBetter": false, "replicates": [311.9703628155325, 320.365675961596, 324.9967500324996, 326.6325383943064, 330.6082292483407, 6289.685515724213, 6291.15069465622, 6311.337309582496, 6330.0, 6405.711742971565, 6202.78290259841, 6240.219972503437, 6258.561900666944, 6274.090256912745, 6277.717502712378, 4772.212584923501, 4773.856784296468, 4775.331453596499, 4776.355192049064, 4779.091972525209, 4772.141014617368, 4777.450593181777, 4779.402574678165, 4784.64115191362, 4786.282057691356, 4628.09752098341, 4705.835295764649, 4718.4337879454515, 4726.2201736713405, 4732.384789977298, 4718.490083173386, 4725.291206319045, 4730.408698912636, 4730.716157030589, 4735.644826638016, 4038.45480860086, 4056.5132184137797, 4059.167870586523, 4062.7765472898977, 4063.837446502166, 5952.136940143655, 6020.4581587656885, 6025.186599809013, 6033.728482218311, 6046.153538504613, 5378.8924221515745, 5457.181422786613, 5470.9452905471235, 5479.1781232815, 5484.232207490954, 6691.498137639696, 6691.665416729144, 6697.832554186136, 6699.966500167517, 6703.865922681568, 4428.800703968329, 4436.889077773081, 4473.7315761054115, 4485.170243504962, 4488.05750792334, 6769.830754231184, 6847.315268473113, 6924.896126558106, 6926.965365173192, 6935.861282774366, 4466.9106617867465, 4472.284434490531, 4475.5748203920575, 4484.05834774701, 4493.348464472699, 4484.775761211927, 4522.5929666330185, 4526.02690421561, 4533.637309015271, 4537.77311134442, 5343.599230057684, 5358.223057656618, 5366.248725178478, 5367.845913128618, 5389.595780316492, 6537.01944708288, 6698.095757072831, 6706.5640733352175, 6706.631135287606, 6726.596404215763, 1602.5913392085176, 1619.8947068440514, 1638.4101723379813, 1638.983610163883, 1639.8524132828102, 1048.6591857646142, 1068.0547715271884, 1081.405227125078, 1081.9883409012598, 1091.9727006824894, 6369.5222858285815, 6377.330380310001, 6379.904301435389, 6393.776217832354, 6405.0, 6867.07294515244, 6870.862582748317, 6871.106756121772, 6884.105066341343, 6885.621290828953, 6322.36776322364, 6371.299157092716, 6388.0, 6388.520860935449, 6399.328070552525, 3782.8108594570162, 3797.1266608680157, 3804.543454785388, 3807.2575847709986, 3808.923821523554, 7060.964695176492, 7080.822979425556, 7093.503454758221, 7133.678984445713, 7139.250378710264, 7005.474589405815, 7099.680514376762, 7161.92838071623, 7175.497715159889, 7216.530925489827, 7094.858102838018, 7119.4660400470175, 7119.537230080028, 7119.679614417364, 7134.500584959003, 6915.827104322432, 6953.687084081127, 6954.582725036514, 6958.686859091455, 6967.442604591722, 6780.762673306411, 6906.758263460755, 6910.930890691029, 6921.169459664872, 6923.411510021503, 7120.145582529923, 7121.608311542915, 7131.286871312827, 7131.714731410685, 7134.429245660231, 4565.931511027404, 4578.130155270488, 4579.198640237879, 4585.472670642919, 4591.0, 6819.659017049227, 6975.337342952412, 6982.476314276551, 6995.825104372431, 7001.579905205704, 6714.563553369016, 6769.661516924232, 6782.0505129280955, 6786.830329241611, 6800.183977922483, 6772.322767723188, 6772.898406523807, 6777.695003724745, 6781.762638307833, 6787.762428314985, 6525.2169739631545, 6644.069830223869, 6647.434968027578, 6651.900221496779, 6665.900011499734, 6749.5612785168805, 6796.830079248059, 6797.252302246848, 6797.4901882358035, 6806.727730890709, 6500.906884912186, 6540.247871494839, 6588.275289718032, 6590.340965903371, 6607.636579988147], "unit": "score", "name": "prototype.html", "value": 4981.613986304696}, {"lowerIsBetter": false, "replicates": [856.7858035491126, 857.9828403431932, 861.4228466927158, 863.9136086391358, 898.5327629632593, 13464.192148471086, 13604.659883502918, 13699.794503082461, 13710.657233569142, 13773.104748191361, 13595.456181752743, 13798.586042418718, 13802.309884505785, 13807.792883106762, 13854.445822167127, 806.0086094104255, 816.1593558634586, 818.0265484073963, 820.2125959079291, 839.4543546694651, 817.7669364231195, 824.8185399212164, 824.884516167737, 829.3282441222628, 830.0578843013193, 858.0604238358987, 859.072202021818, 863.546638015044, 864.2912811494577, 865.420168487112, 853.6116067189445, 857.2456238510102, 860.3332417376521, 862.3833958719483, 862.969796057135, 628.8239292997922, 631.9368063193643, 633.4109278371113, 635.2504045226631, 635.7933671556716, 13161.144525605805, 13278.53525126616, 13302.667433314244, 13334.399952002183, 13342.266175360352, 12002.399880005965, 12014.69963250917, 12184.695382615417, 12232.327222002787, 12292.877071229263, 10131.696049118538, 10147.0, 10221.284510084297, 10291.897081029168, 10308.69073927776, 3591.910202244939, 3593.2274560969518, 3696.353138200804, 3748.5689145748315, 3759.9436008459893, 45629.31556026629, 45648.315275270565, 45670.08659826818, 45977.0, 46139.0, 9857.112859842506, 9900.702978910644, 9979.650712225037, 9979.900200997898, 10096.848547271797, 17292.481225563253, 17350.566235844206, 17379.47861564155, 17381.217845196996, 17423.303067877143, 7405.1484079330485, 7448.739294124788, 7491.6254187290415, 7706.961465192639, 7836.294733474015, 4269.91460170801, 4307.181635489247, 4313.202057619288, 4331.211457137614, 4333.523312435629, 3013.517837146046, 3050.923726906845, 3052.236940764809, 3054.266975925762, 3058.801177923428, 536.6779932040822, 541.6614615865084, 547.4388751529634, 555.4556534596119, 565.7086600400797, 541.6289841458613, 678.3453966921944, 699.4923200326783, 705.4215543254483, 731.5903094267227, 7936.444448888637, 8410.453320534269, 8686.86969695455, 8746.86879696805, 8827.911720882708, 678.6945874356461, 686.2382755141789, 689.6586189835963, 740.5630677900065, 751.1324420294459, 3872.02706736991, 4205.053862880827, 4234.491860976641, 4286.635635970915, 4349.151915376471, 55672.0, 55808.0, 56008.439915601135, 56399.154012689025, 56799.43200568024, 50183.74908125436, 50402.0, 50797.0, 50848.23727644089, 50960.49039509558, 46783.76608116938, 47035.764821175675, 49682.0, 49934.0, 50683.98632027339, 8708.303335732999, 9030.413023153475, 9126.817463650823, 9181.586828592863, 9220.216281616138, 33173.0, 33310.333793323516, 33979.49030764491, 34022.31955360928, 34126.0, 45862.31206531838, 45925.31112033255, 46068.76965615284, 46560.53439465562, 46729.0, 10674.83987740169, 11158.665240042812, 11282.887171128184, 11359.0, 11472.369019703996, 12049.638510844688, 12220.0, 12282.0, 12747.61757147287, 12768.170068945488, 11458.484368203617, 11489.770204595694, 11541.307521548733, 11960.940195298968, 12074.27554346742, 10763.138948884227, 11336.829947550628, 11537.25007874519, 11579.594714184963, 11967.222130561486, 11210.215284930302, 11409.714757131138, 11444.484998174936, 11728.882711172779, 11764.647060588197, 9801.852972205565, 10024.699259022242, 10052.045055719695, 10103.696889093339, 10429.0, 9218.677346292847, 9316.767080823034, 9364.391314564531, 9582.712518624452, 9997.55011024491], "unit": "score", "name": "yui.html", "value": 6379.267497279058}], "name": "dromaeo_css", "value": 6987.623115444298}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 106.0, 111.0, 111.0, 111.0, 123.0, 112.0, 110.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.5}, {"lowerIsBetter": true, "replicates": [156.0, 127.0, 151.0, 171.0, 124.0, 146.0, 155.0, 131.0, 165.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 145.1}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 163.0, 163.0, 161.0, 160.0, 166.0, 162.0, 168.0, 163.0], "unit": "score", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 73.0, 78.0, 72.0, 141.0, 74.0, 78.0, 71.0, 74.0], "unit": "score", "name": "audio-fft", "value": 80.4}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 133.0, 94.0, 94.0, 95.0, 141.0, 92.0, 93.0, 140.0], "unit": "score", "name": "audio-oscillator", "value": 106.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [149.0, 147.0, 147.0, 147.0, 149.0, 148.0, 147.0, 146.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 147.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 97.0, 97.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [63.0, 66.0, 61.0, 61.0, 62.0, 64.0, 61.0, 61.0, 58.0, 59.0], "unit": "score", "name": "json-parse-financial", "value": 61.6}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 67.0, 67.0, 67.0, 68.0, 68.0, 66.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [93.0, 85.0, 86.0, 94.0, 86.0, 86.0, 86.0, 85.0, 84.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.2}, {"lowerIsBetter": true, "replicates": [119.0, 124.0, 122.0, 124.0, 118.0, 125.0, 123.0, 123.0, 119.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.0}, {"lowerIsBetter": true, "replicates": [140.0, 151.0, 154.0, 146.0, 150.0, 158.0, 152.0, 156.0, 151.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.8}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 73.0, 68.0, 69.0, 68.0, 67.0, 70.0, 66.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1517.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.47244880972198], "unit": "score", "name": "Richards", "value": 212.05911400000002}, {"lowerIsBetter": false, "replicates": [192.7153594141453], "unit": "score", "name": "DeltaBlue", "value": 343.086302}, {"lowerIsBetter": false, "replicates": [283.60748723766307], "unit": "score", "name": "Encrypt", "value": 938.5542060000004}, {"lowerIsBetter": false, "replicates": [5218.75], "unit": "score", "name": "Decrypt", "value": 51.00474251497002}, {"lowerIsBetter": false, "replicates": [1052.6315789473683], "unit": "score", "name": "RayTrace", "value": 702.9895499999999}, {"lowerIsBetter": false, "replicates": [719.1091954022988], "unit": "score", "name": "Earley", "value": 926.7897062937063}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [25025.0], "unit": "score", "name": "RegExp", "value": 36.40299700299697}, {"lowerIsBetter": false, "replicates": [550.05500550055], "unit": "score", "name": "Splay", "value": 148.15063800000001}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21514.205681015123}]} 20:06:02 INFO - RETURN: dromaeo_css: 6987.62 20:06:02 INFO - RETURN: kraken: 1517.90 20:06:02 INFO - RETURN: v8_7: 21514.21 20:06:02 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21514.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1517.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "6987.62"}}} 20:06:02 INFO - Return code: 0 20:06:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:06:02 INFO - # TBPL SUCCESS # 20:06:02 INFO - Running post-action listener: _resource_record_post_action 20:06:02 INFO - Running post-run listener: _resource_record_post_run 20:06:03 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 24576; Write bytes: 518316032; Read time: 360; Write time: 576824 20:06:03 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 239128576; Read time: 0; Write time: 378044 20:06:03 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 24576; Write bytes: 279187456; Read time: 360; Write time: 198780 20:06:03 INFO - Running post-run listener: _upload_blobber_files 20:06:03 INFO - Blob upload gear active. 20:06:03 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:06:03 INFO - Copying logs to upload dir... 20:06:03 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1281.226376 ========= master_lag: 40.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2015-12-16 20:06:44.270313) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 20:06:44.273445) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010019 build_url: 'https://queue.taskcluster.net/v1/task/dcBldwPyTMKLVrza36ZxRw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.18 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 20:06:44.463509) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 20:06:44.463929) ========= 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=447a3abc0420b6a47dd38cba000001bf-1450316261.936998-426202632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 20:06:44.768758) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2015-12-16 20:06:44.769165) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2015-12-16 20:06:48.043645) ========= ========= Total master_lag: 41.33 =========