builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1456768535.59 results: success (0) buildid: 20160229071914 builduid: c21bc5a0fb54422ab53b3562f28e2360 revision: bbe048ab30ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.587347) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.587815) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.588138) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006470 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.611241) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.611534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.624554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.625012) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.674063) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.674373) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.674771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.675045) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-29 09:55:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-02-29 09:55:37 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165561 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.852653) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.852957) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020038 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.901889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:35.902236) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bbe048ab30ad --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bbe048ab30ad --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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-29 09:55:37,851 Setting DEBUG logging. 2016-02-29 09:55:37,851 attempt 1/10 2016-02-29 09:55:37,851 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bbe048ab30ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-29 09:55:38,180 unpacking tar archive at: mozilla-beta-bbe048ab30ad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638182 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:36.552815) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:36.553161) ========= echo bbe048ab30ad in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'bbe048ab30ad'] 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False bbe048ab30ad program finished with exit code 0 elapsedTime=0.004653 script_repo_revision: 'bbe048ab30ad' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:36.608801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:36.609105) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:55:36.622010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-02-29 09:55:36.622398) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:55:38 INFO - MultiFileLogger online at 20160229 09:55:38 in /builds/slave/test-pgo 09:55:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:55:38 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:55:38 INFO - {'append_to_log': False, 09:55:38 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:55:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:55:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:55:38 INFO - 'branch': 'Mozilla-Beta', 09:55:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:38 INFO - 'config_files': ('talos/linux_config.py',), 09:55:38 INFO - 'default_actions': ('clobber', 09:55:38 INFO - 'read-buildbot-config', 09:55:38 INFO - 'download-and-extract', 09:55:38 INFO - 'populate-webroot', 09:55:38 INFO - 'create-virtualenv', 09:55:38 INFO - 'install', 09:55:38 INFO - 'run-tests'), 09:55:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:38 INFO - 'download_minidump_stackwalk': True, 09:55:38 INFO - 'download_symbols': 'ondemand', 09:55:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:38 INFO - 'installer_path': 'installer.exe', 09:55:38 INFO - 'log_level': 'info', 09:55:38 INFO - 'log_name': 'talos', 09:55:38 INFO - 'log_to_console': True, 09:55:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:55:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:55:38 INFO - 'opt_config_files': (), 09:55:38 INFO - 'pip_index': False, 09:55:38 INFO - 'sps_profile': False, 09:55:38 INFO - 'sps_profile_interval': 0, 09:55:38 INFO - 'suite': 'dromaeojs', 09:55:38 INFO - 'system_bits': '32', 09:55:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:38 INFO - 'title': 'talos-linux64-ix-068', 09:55:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:38 INFO - 'use_talos_json': True, 09:55:38 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:55:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:38 INFO - 'work_dir': 'build'} 09:55:38 INFO - ##### 09:55:38 INFO - ##### Running clobber step. 09:55:38 INFO - ##### 09:55:38 INFO - Running pre-action listener: _resource_record_pre_action 09:55:38 INFO - Running main action method: clobber 09:55:38 INFO - rmtree: /builds/slave/test-pgo/build 09:55:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:55:39 INFO - Running post-action listener: _resource_record_post_action 09:55:39 INFO - ##### 09:55:39 INFO - ##### Running read-buildbot-config step. 09:55:39 INFO - ##### 09:55:39 INFO - Running pre-action listener: _resource_record_pre_action 09:55:39 INFO - Running main action method: read_buildbot_config 09:55:39 INFO - Using buildbot properties: 09:55:39 INFO - { 09:55:39 INFO - "properties": { 09:55:39 INFO - "buildnumber": 104, 09:55:39 INFO - "product": "firefox", 09:55:39 INFO - "script_repo_revision": "production", 09:55:39 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:55:39 INFO - "repository": "", 09:55:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:55:39 INFO - "buildid": "20160229071914", 09:55:39 INFO - "slavename": "talos-linux64-ix-068", 09:55:39 INFO - "pgo_build": "True", 09:55:39 INFO - "basedir": "/builds/slave/test-pgo", 09:55:39 INFO - "project": "", 09:55:39 INFO - "platform": "ubuntu64_hw", 09:55:39 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:55:39 INFO - "slavebuilddir": "test", 09:55:39 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:55:39 INFO - "branch": "mozilla-beta", 09:55:39 INFO - "repo_path": "releases/mozilla-beta", 09:55:39 INFO - "stage_platform": "linux64-pgo", 09:55:39 INFO - "builduid": "c21bc5a0fb54422ab53b3562f28e2360", 09:55:39 INFO - "revision": "bbe048ab30ad" 09:55:39 INFO - }, 09:55:39 INFO - "sourcestamp": { 09:55:39 INFO - "repository": "", 09:55:39 INFO - "hasPatch": false, 09:55:39 INFO - "project": "", 09:55:39 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:55:39 INFO - "changes": [ 09:55:39 INFO - { 09:55:39 INFO - "category": null, 09:55:39 INFO - "files": [ 09:55:39 INFO - { 09:55:39 INFO - "url": null, 09:55:39 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:55:39 INFO - } 09:55:39 INFO - ], 09:55:39 INFO - "repository": "", 09:55:39 INFO - "rev": "bbe048ab30ad", 09:55:39 INFO - "who": "sendchange", 09:55:39 INFO - "when": 1456768495, 09:55:39 INFO - "number": 7669624, 09:55:39 INFO - "comments": "Bug 1244062 - Upgrade Firefox 47 to NSPR 4.12, a=sledru", 09:55:39 INFO - "project": "", 09:55:39 INFO - "at": "Mon 29 Feb 2016 09:54:55", 09:55:39 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:55:39 INFO - "revlink": "", 09:55:39 INFO - "properties": [ 09:55:39 INFO - [ 09:55:39 INFO - "buildid", 09:55:39 INFO - "20160229071914", 09:55:39 INFO - "Change" 09:55:39 INFO - ], 09:55:39 INFO - [ 09:55:39 INFO - "builduid", 09:55:39 INFO - "c21bc5a0fb54422ab53b3562f28e2360", 09:55:39 INFO - "Change" 09:55:39 INFO - ], 09:55:39 INFO - [ 09:55:39 INFO - "pgo_build", 09:55:39 INFO - "True", 09:55:39 INFO - "Change" 09:55:39 INFO - ] 09:55:39 INFO - ], 09:55:39 INFO - "revision": "bbe048ab30ad" 09:55:39 INFO - } 09:55:39 INFO - ], 09:55:39 INFO - "revision": "bbe048ab30ad" 09:55:39 INFO - } 09:55:39 INFO - } 09:55:39 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:55:39 INFO - Running post-action listener: _resource_record_post_action 09:55:39 INFO - ##### 09:55:39 INFO - ##### Running download-and-extract step. 09:55:39 INFO - ##### 09:55:39 INFO - Running pre-action listener: _resource_record_pre_action 09:55:39 INFO - Running main action method: download_and_extract 09:55:39 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/test_packages.json 09:55:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:55:39 INFO - Downloaded 1270 bytes. 09:55:39 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:55:39 INFO - Using the following test package requirements: 09:55:39 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:55:39 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:55:39 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'jsshell-linux-x86_64.zip'], 09:55:39 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:55:39 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:55:39 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:55:39 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:55:39 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:55:39 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:55:39 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:55:39 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:55:39 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:55:40 INFO - Downloaded 21584212 bytes. 09:55:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:55:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:55:41 INFO - Return code: 0 09:55:41 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:55:41 INFO - Downloaded 21584212 bytes. 09:55:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:55:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:55:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:55:42 INFO - Return code: 0 09:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:55:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:55:43 INFO - Downloaded 11132038 bytes. 09:55:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:55:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:55:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:55:43 INFO - Return code: 0 09:55: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')]} 09:55:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:55:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:55:44 INFO - Downloaded 57951497 bytes. 09:55:44 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:55:44 INFO - mkdir: /builds/slave/test-pgo/properties 09:55:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:55:44 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:55:44 INFO - Contents: 09:55:44 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:55:44 INFO - Running post-action listener: _resource_record_post_action 09:55:44 INFO - Running post-action listener: set_extra_try_arguments 09:55:44 INFO - ##### 09:55:44 INFO - ##### Running populate-webroot step. 09:55:44 INFO - ##### 09:55:44 INFO - Running pre-action listener: _resource_record_pre_action 09:55:44 INFO - Running main action method: populate_webroot 09:55:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:44 INFO - '--tppagecycles', 09:55:44 INFO - '7'], 09:55:44 INFO - 'tests': ['tsvgm']}}, 09:55:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:55:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:55:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:55:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'tests': ['damp', 'tps']}, 09:55:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['damp', 'tps']}, 09:55:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:55:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:44 INFO - 'other': {'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'other_l64': {'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'other_nol64': {'tests': ['a11yr', 09:55:44 INFO - 'ts_paint', 09:55:44 INFO - 'tpaint', 09:55:44 INFO - 'sessionrestore', 09:55:44 INFO - 'sessionrestore_no_auto_restore']}, 09:55:44 INFO - 'svgr': {'tests': ['tsvgx', 09:55:44 INFO - 'tsvgr_opacity', 09:55:44 INFO - 'tart', 09:55:44 INFO - 'tscrollx', 09:55:44 INFO - 'cart']}, 09:55:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['tsvgx', 09:55:44 INFO - 'tsvgr_opacity', 09:55:44 INFO - 'tart', 09:55:44 INFO - 'tscrollx', 09:55:44 INFO - 'cart']}, 09:55:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'tests': ['tp5o']}, 09:55:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'talos_options': ['--e10s'], 09:55:44 INFO - 'tests': ['tp5o']}, 09:55:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'talos_options': ['--xperf_path', 09:55:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:44 INFO - 'tests': ['tp5n']}, 09:55:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:44 INFO - 'talos_options': ['--e10s', 09:55:44 INFO - '--xperf_path', 09:55:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:44 INFO - 'tests': ['tp5n']}}, 09:55:44 INFO - 'talos.zip': {'path': '', 09:55:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:44 INFO - Running post-action listener: _resource_record_post_action 09:55:44 INFO - ##### 09:55:44 INFO - ##### Running create-virtualenv step. 09:55:44 INFO - ##### 09:55:44 INFO - Running pre-action listener: _resource_record_pre_action 09:55:44 INFO - Running main action method: create_virtualenv 09:55:44 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:55:44 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 09:55:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:55:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:44 INFO - Using real prefix '/usr' 09:55:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:55:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:47 INFO - Installing pip.................done. 09:55:47 INFO - Return code: 0 09:55:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:55:47 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')]} 09:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:47 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')]} 09:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:55:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:55:47 INFO - Using env: {'DISPLAY': ':0', 09:55:47 INFO - 'HOME': '/home/cltbld', 09:55:47 INFO - 'LANG': 'en_US.UTF-8', 09:55:47 INFO - 'LANGUAGE': 'en_US:en', 09:55:47 INFO - 'LOGNAME': 'cltbld', 09:55:47 INFO - 'MAIL': '/var/mail/cltbld', 09:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:47 INFO - 'MOZ_NO_REMOTE': '1', 09:55:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:47 INFO - 'NO_EM_RESTART': '1', 09:55:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:47 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:47 INFO - 'SHELL': '/bin/bash', 09:55:47 INFO - 'SHLVL': '1', 09:55:47 INFO - 'TERM': 'linux', 09:55:47 INFO - 'TMOUT': '86400', 09:55:47 INFO - 'USER': 'cltbld', 09:55:47 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:47 INFO - '_': '/tools/buildbot/bin/python'} 09:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:47 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 09:55:47 INFO - Cleaning up... 09:55:47 INFO - Return code: 0 09:55:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:55:47 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')]} 09:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:47 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')]} 09:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:55:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:55:47 INFO - Using env: {'DISPLAY': ':0', 09:55:47 INFO - 'HOME': '/home/cltbld', 09:55:47 INFO - 'LANG': 'en_US.UTF-8', 09:55:47 INFO - 'LANGUAGE': 'en_US:en', 09:55:47 INFO - 'LOGNAME': 'cltbld', 09:55:47 INFO - 'MAIL': '/var/mail/cltbld', 09:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:47 INFO - 'MOZ_NO_REMOTE': '1', 09:55:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:47 INFO - 'NO_EM_RESTART': '1', 09:55:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:47 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:47 INFO - 'SHELL': '/bin/bash', 09:55:47 INFO - 'SHLVL': '1', 09:55:47 INFO - 'TERM': 'linux', 09:55:47 INFO - 'TMOUT': '86400', 09:55:47 INFO - 'USER': 'cltbld', 09:55:47 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:47 INFO - '_': '/tools/buildbot/bin/python'} 09:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:47 INFO - Downloading/unpacking psutil>=0.7.1 09:55:47 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 09:55:47 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 09:55:47 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 09:55:47 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 09:55:47 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 09:55:47 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 09:55:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:49 INFO - Installing collected packages: psutil 09:55:49 INFO - Running setup.py install for psutil 09:55:49 INFO - building 'psutil._psutil_linux' extension 09:55:49 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 09:55:49 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 09:55:49 INFO - building 'psutil._psutil_posix' extension 09:55:49 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 09:55:50 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 09:55:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:50 INFO - Successfully installed psutil 09:55:50 INFO - Cleaning up... 09:55:50 INFO - Return code: 0 09:55:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:55:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:55:50 INFO - Using env: {'DISPLAY': ':0', 09:55:50 INFO - 'HOME': '/home/cltbld', 09:55:50 INFO - 'LANG': 'en_US.UTF-8', 09:55:50 INFO - 'LANGUAGE': 'en_US:en', 09:55:50 INFO - 'LOGNAME': 'cltbld', 09:55:50 INFO - 'MAIL': '/var/mail/cltbld', 09:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:50 INFO - 'MOZ_NO_REMOTE': '1', 09:55:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:50 INFO - 'NO_EM_RESTART': '1', 09:55:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:50 INFO - 'SHELL': '/bin/bash', 09:55:50 INFO - 'SHLVL': '1', 09:55:50 INFO - 'TERM': 'linux', 09:55:50 INFO - 'TMOUT': '86400', 09:55:50 INFO - 'USER': 'cltbld', 09:55:50 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:50 INFO - '_': '/tools/buildbot/bin/python'} 09:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:55:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:55:52 INFO - Installing collected packages: mozsystemmonitor 09:55:52 INFO - Running setup.py install for mozsystemmonitor 09:55:52 INFO - Successfully installed mozsystemmonitor 09:55:52 INFO - Cleaning up... 09:55:52 INFO - Return code: 0 09:55:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:55:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:55:52 INFO - Using env: {'DISPLAY': ':0', 09:55:52 INFO - 'HOME': '/home/cltbld', 09:55:52 INFO - 'LANG': 'en_US.UTF-8', 09:55:52 INFO - 'LANGUAGE': 'en_US:en', 09:55:52 INFO - 'LOGNAME': 'cltbld', 09:55:52 INFO - 'MAIL': '/var/mail/cltbld', 09:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:52 INFO - 'MOZ_NO_REMOTE': '1', 09:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:52 INFO - 'NO_EM_RESTART': '1', 09:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:52 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:52 INFO - 'SHELL': '/bin/bash', 09:55:52 INFO - 'SHLVL': '1', 09:55:52 INFO - 'TERM': 'linux', 09:55:52 INFO - 'TMOUT': '86400', 09:55:52 INFO - 'USER': 'cltbld', 09:55:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:52 INFO - '_': '/tools/buildbot/bin/python'} 09:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:52 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 09:55:52 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 09:55:52 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 09:55:52 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 09:55:52 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 09:55:52 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 09:55:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:55:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:55:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:55:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:55 INFO - Downloading docopt-0.6.1.tar.gz 09:55:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:55:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:55:55 INFO - Running setup.py install for blobuploader 09:55:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:55:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:55:55 INFO - Running setup.py install for requests 09:55:55 INFO - Running setup.py install for docopt 09:55:55 INFO - Successfully installed blobuploader requests docopt 09:55:55 INFO - Cleaning up... 09:55:55 INFO - Return code: 0 09:55:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 09:55:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 09:55:55 INFO - Using env: {'DISPLAY': ':0', 09:55:55 INFO - 'HOME': '/home/cltbld', 09:55:55 INFO - 'LANG': 'en_US.UTF-8', 09:55:55 INFO - 'LANGUAGE': 'en_US:en', 09:55:55 INFO - 'LOGNAME': 'cltbld', 09:55:55 INFO - 'MAIL': '/var/mail/cltbld', 09:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:55 INFO - 'MOZ_NO_REMOTE': '1', 09:55:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:55 INFO - 'NO_EM_RESTART': '1', 09:55:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:55 INFO - 'SHELL': '/bin/bash', 09:55:55 INFO - 'SHLVL': '1', 09:55:55 INFO - 'TERM': 'linux', 09:55:55 INFO - 'TMOUT': '86400', 09:55:55 INFO - 'USER': 'cltbld', 09:55:55 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:55 INFO - '_': '/tools/buildbot/bin/python'} 09:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:55:56 INFO - Running setup.py (path:/tmp/pip-NTCMvI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:55:56 INFO - Running setup.py (path:/tmp/pip-eHNWYO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:55:56 INFO - Running setup.py (path:/tmp/pip-m5DA_l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:55:56 INFO - Running setup.py (path:/tmp/pip-vBToHW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:55:56 INFO - Running setup.py (path:/tmp/pip-5K1KZR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:55:56 INFO - Running setup.py (path:/tmp/pip-FpUoSw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:55:56 INFO - Running setup.py (path:/tmp/pip-42yACL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:55:56 INFO - Running setup.py (path:/tmp/pip-7sUwon-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:55:56 INFO - Running setup.py (path:/tmp/pip-W5OoLE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:55:56 INFO - Running setup.py (path:/tmp/pip-4U6Jp_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:55:56 INFO - Running setup.py (path:/tmp/pip-gddjQI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:55:56 INFO - Running setup.py (path:/tmp/pip-OUJ4nt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:55:56 INFO - Running setup.py (path:/tmp/pip-UCT28g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:55:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:55:56 INFO - Running setup.py (path:/tmp/pip-hF4Ejy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:55:57 INFO - Running setup.py (path:/tmp/pip-VXBwHx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:55:57 INFO - Running setup.py (path:/tmp/pip-OXF8ii-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:55:57 INFO - Running setup.py (path:/tmp/pip-FaEkwq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:55:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:55:57 INFO - Running setup.py install for manifestparser 09:55:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:55:57 INFO - Running setup.py install for mozcrash 09:55:57 INFO - Running setup.py install for mozdebug 09:55:57 INFO - Running setup.py install for mozdevice 09:55:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:55:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:55:57 INFO - Running setup.py install for mozfile 09:55:57 INFO - Running setup.py install for mozhttpd 09:55:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:55:57 INFO - Running setup.py install for mozinfo 09:55:57 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:55:57 INFO - Running setup.py install for mozInstall 09:55:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Running setup.py install for mozleak 09:55:58 INFO - Running setup.py install for mozlog 09:55:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Running setup.py install for moznetwork 09:55:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Running setup.py install for mozprocess 09:55:58 INFO - Running setup.py install for mozprofile 09:55:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Running setup.py install for mozrunner 09:55:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:55:58 INFO - Running setup.py install for mozscreenshot 09:55:58 INFO - Running setup.py install for moztest 09:55:58 INFO - Running setup.py install for mozversion 09:55:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:55:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:55:59 INFO - Cleaning up... 09:55:59 INFO - Return code: 0 09:55:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:55:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:55:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:55:59 INFO - Using env: {'DISPLAY': ':0', 09:55:59 INFO - 'HOME': '/home/cltbld', 09:55:59 INFO - 'LANG': 'en_US.UTF-8', 09:55:59 INFO - 'LANGUAGE': 'en_US:en', 09:55:59 INFO - 'LOGNAME': 'cltbld', 09:55:59 INFO - 'MAIL': '/var/mail/cltbld', 09:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:59 INFO - 'MOZ_NO_REMOTE': '1', 09:55:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:55:59 INFO - 'NO_EM_RESTART': '1', 09:55:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:55:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:55:59 INFO - 'SHELL': '/bin/bash', 09:55:59 INFO - 'SHLVL': '1', 09:55:59 INFO - 'TERM': 'linux', 09:55:59 INFO - 'TMOUT': '86400', 09:55:59 INFO - 'USER': 'cltbld', 09:55:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:59 INFO - '_': '/tools/buildbot/bin/python'} 09:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:55:59 INFO - Running setup.py (path:/tmp/pip-fH8Yo1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:55:59 INFO - Running setup.py (path:/tmp/pip-6VTcSw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:55:59 INFO - Running setup.py (path:/tmp/pip-BT0fRu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:55:59 INFO - Running setup.py (path:/tmp/pip-g1nm6X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:55:59 INFO - Running setup.py (path:/tmp/pip-yS1mar-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:55:59 INFO - Running setup.py (path:/tmp/pip-Zikd3h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:55:59 INFO - Running setup.py (path:/tmp/pip-6NhLwf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:55:59 INFO - Running setup.py (path:/tmp/pip-lxiMJA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:55:59 INFO - Running setup.py (path:/tmp/pip-OJKfyy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:55:59 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)) 09:55:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:55:59 INFO - Running setup.py (path:/tmp/pip-niBms4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:00 INFO - Running setup.py (path:/tmp/pip-N8HGJB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:00 INFO - Running setup.py (path:/tmp/pip-EDmT2q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:00 INFO - Running setup.py (path:/tmp/pip-B4ut_T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:00 INFO - Running setup.py (path:/tmp/pip-66Lc3n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:00 INFO - Running setup.py (path:/tmp/pip-pcbM2E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:00 INFO - Running setup.py (path:/tmp/pip-lxRk_P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:00 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)) 09:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:00 INFO - Running setup.py (path:/tmp/pip-LAzkBH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:00 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)) 09:56:00 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)) 09:56:00 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)) 09:56:00 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)) 09:56:00 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)) 09:56:00 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)) 09:56:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:02 INFO - Downloading blessings-1.6.tar.gz 09:56:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:02 INFO - Installing collected packages: blessings 09:56:02 INFO - Running setup.py install for blessings 09:56:02 INFO - Successfully installed blessings 09:56:02 INFO - Cleaning up... 09:56:02 INFO - Return code: 0 09:56:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:56:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:56:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:56:02 INFO - Reading from file tmpfile_stdout 09:56:02 INFO - Current package versions: 09:56:02 INFO - argparse == 1.2.1 09:56:02 INFO - blessings == 1.6 09:56:02 INFO - blobuploader == 1.2.4 09:56:02 INFO - docopt == 0.6.1 09:56:02 INFO - manifestparser == 1.1 09:56:02 INFO - mozInstall == 1.12 09:56:02 INFO - mozcrash == 0.16 09:56:02 INFO - mozdebug == 0.1 09:56:02 INFO - mozdevice == 0.47 09:56:02 INFO - mozfile == 1.2 09:56:02 INFO - mozhttpd == 0.7 09:56:02 INFO - mozinfo == 0.9 09:56:02 INFO - mozleak == 0.1 09:56:02 INFO - mozlog == 3.1 09:56:02 INFO - moznetwork == 0.27 09:56:02 INFO - mozprocess == 0.22 09:56:02 INFO - mozprofile == 0.27 09:56:02 INFO - mozrunner == 6.11 09:56:02 INFO - mozscreenshot == 0.1 09:56:02 INFO - mozsystemmonitor == 0.0 09:56:02 INFO - moztest == 0.7 09:56:02 INFO - mozversion == 1.4 09:56:02 INFO - psutil == 3.1.1 09:56:02 INFO - requests == 1.2.3 09:56:02 INFO - wsgiref == 0.1.2 09:56:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:56:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:56:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:56:02 INFO - Using env: {'DISPLAY': ':0', 09:56:02 INFO - 'HOME': '/home/cltbld', 09:56:02 INFO - 'LANG': 'en_US.UTF-8', 09:56:02 INFO - 'LANGUAGE': 'en_US:en', 09:56:02 INFO - 'LOGNAME': 'cltbld', 09:56:02 INFO - 'MAIL': '/var/mail/cltbld', 09:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:02 INFO - 'MOZ_NO_REMOTE': '1', 09:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:02 INFO - 'NO_EM_RESTART': '1', 09:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:02 INFO - 'SHELL': '/bin/bash', 09:56:02 INFO - 'SHLVL': '1', 09:56:02 INFO - 'TERM': 'linux', 09:56:02 INFO - 'TMOUT': '86400', 09:56:02 INFO - 'USER': 'cltbld', 09:56:02 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:02 INFO - '_': '/tools/buildbot/bin/python'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 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)) 09:56:03 INFO - Cleaning up... 09:56:03 INFO - Return code: 0 09:56:03 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:56:03 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')]} 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 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')]} 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:56:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:56:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:56:03 INFO - Using env: {'DISPLAY': ':0', 09:56:03 INFO - 'HOME': '/home/cltbld', 09:56:03 INFO - 'LANG': 'en_US.UTF-8', 09:56:03 INFO - 'LANGUAGE': 'en_US:en', 09:56:03 INFO - 'LOGNAME': 'cltbld', 09:56:03 INFO - 'MAIL': '/var/mail/cltbld', 09:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:03 INFO - 'MOZ_NO_REMOTE': '1', 09:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:03 INFO - 'NO_EM_RESTART': '1', 09:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:03 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:03 INFO - 'SHELL': '/bin/bash', 09:56:03 INFO - 'SHLVL': '1', 09:56:03 INFO - 'TERM': 'linux', 09:56:03 INFO - 'TMOUT': '86400', 09:56:03 INFO - 'USER': 'cltbld', 09:56:03 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:03 INFO - '_': '/tools/buildbot/bin/python'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 INFO - Downloading/unpacking jsonschema 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:05 INFO - Downloading/unpacking functools32 (from jsonschema) 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:05 INFO - warning: no files found matching '*.txt' 09:56:05 INFO - no previously-included directories found matching 'build' 09:56:05 INFO - no previously-included directories found matching 'dist' 09:56:05 INFO - no previously-included directories found matching '.git*' 09:56:05 INFO - Installing collected packages: jsonschema, functools32 09:56:05 INFO - Running setup.py install for functools32 09:56:05 INFO - warning: no files found matching '*.txt' 09:56:05 INFO - no previously-included directories found matching 'build' 09:56:05 INFO - no previously-included directories found matching 'dist' 09:56:05 INFO - no previously-included directories found matching '.git*' 09:56:05 INFO - Successfully installed jsonschema functools32 09:56:05 INFO - Cleaning up... 09:56:05 INFO - Return code: 0 09:56:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:56: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')]} 09:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3eaa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', '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 09:56:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:56:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:56:05 INFO - Using env: {'DISPLAY': ':0', 09:56:05 INFO - 'HOME': '/home/cltbld', 09:56:05 INFO - 'LANG': 'en_US.UTF-8', 09:56:05 INFO - 'LANGUAGE': 'en_US:en', 09:56:05 INFO - 'LOGNAME': 'cltbld', 09:56:05 INFO - 'MAIL': '/var/mail/cltbld', 09:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:05 INFO - 'MOZ_NO_REMOTE': '1', 09:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:05 INFO - 'NO_EM_RESTART': '1', 09:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:05 INFO - 'SHELL': '/bin/bash', 09:56:05 INFO - 'SHLVL': '1', 09:56:05 INFO - 'TERM': 'linux', 09:56:05 INFO - 'TMOUT': '86400', 09:56:05 INFO - 'USER': 'cltbld', 09:56:05 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:05 INFO - '_': '/tools/buildbot/bin/python'} 09:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:05 INFO - Downloading/unpacking flake8 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:05 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 09:56:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:56:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:56:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:07 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 09:56:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:08 INFO - no previously-included directories found matching 'docs/_build' 09:56:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:56:08 INFO - Running setup.py install for pep8 09:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:08 INFO - no previously-included directories found matching 'docs/_build' 09:56:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:56:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:56:08 INFO - Cleaning up... 09:56:08 INFO - Return code: 0 09:56:08 INFO - Running post-action listener: _resource_record_post_action 09:56:08 INFO - Running post-action listener: _start_resource_monitoring 09:56:08 INFO - Starting resource monitoring. 09:56:08 INFO - ##### 09:56:08 INFO - ##### Running install step. 09:56:08 INFO - ##### 09:56:08 INFO - Running pre-action listener: _resource_record_pre_action 09:56:08 INFO - Running main action method: install 09:56:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:56:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:56:08 INFO - Reading from file tmpfile_stdout 09:56:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:56:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:56:08 INFO - Reading from file tmpfile_stdout 09:56:08 INFO - Output received: 09:56:08 INFO - Usage: mozinstall [options] installer 09:56:08 INFO - Options: 09:56:08 INFO - -h, --help show this help message and exit 09:56:08 INFO - -d DEST, --destination=DEST 09:56:08 INFO - Directory to install application into. [default: 09:56:08 INFO - "/builds/slave/test-pgo"] 09:56:08 INFO - --app=APP Application being installed. [default: firefox] 09:56:08 INFO - mkdir: /builds/slave/test-pgo/build/application 09:56:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:56:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:56:24 INFO - Reading from file tmpfile_stdout 09:56:24 INFO - Output received: 09:56:24 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:56:24 INFO - Running post-action listener: _resource_record_post_action 09:56:24 INFO - ##### 09:56:24 INFO - ##### Running run-tests step. 09:56:24 INFO - ##### 09:56:24 INFO - Running pre-action listener: _resource_record_pre_action 09:56:24 INFO - Running main action method: run_tests 09:56:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:56:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:56:24 INFO - Python 2.7.3 09:56:24 INFO - Return code: 0 09:56:24 INFO - grabbing minidump binary from tooltool 09:56:24 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')]} 09:56:24 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 0x1c44350>, '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 0x1c45250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c456e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:56:24 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 09:56:24 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 09:56:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:24 INFO - Return code: 0 09:56:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:56:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:56:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:56:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:56:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:56:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:56:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:56:25 INFO - Return code: 0 09:56:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:56:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost 09:56:25 INFO - Using env: {'DISPLAY': ':0', 09:56:25 INFO - 'HOME': '/home/cltbld', 09:56:25 INFO - 'LANG': 'en_US.UTF-8', 09:56:25 INFO - 'LANGUAGE': 'en_US:en', 09:56:25 INFO - 'LOGNAME': 'cltbld', 09:56:25 INFO - 'MAIL': '/var/mail/cltbld', 09:56:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:56:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:25 INFO - 'MOZ_NO_REMOTE': '1', 09:56:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:25 INFO - 'NO_EM_RESTART': '1', 09:56:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:25 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:56:25 INFO - 'SHELL': '/bin/bash', 09:56:25 INFO - 'SHLVL': '1', 09:56:25 INFO - 'TERM': 'linux', 09:56:25 INFO - 'TMOUT': '86400', 09:56:25 INFO - 'USER': 'cltbld', 09:56:25 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972', 09:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:25 INFO - '_': '/tools/buildbot/bin/python'} 09:56:25 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] with output_timeout 3600 09:56:25 INFO - mozversion INFO | application_buildid: 20160229071914 09:56:25 INFO - mozversion INFO | application_changeset: bbe048ab30ad3321a6505697703e5fee20e91343 09:56:25 INFO - mozversion INFO | application_display_name: Nightly 09:56:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:25 INFO - mozversion INFO | application_name: Firefox 09:56:25 INFO - mozversion INFO | application_remotingname: firefox 09:56:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:25 INFO - mozversion INFO | application_vendor: Mozilla 09:56:25 INFO - mozversion INFO | application_version: 45.0 09:56:25 INFO - mozversion INFO | platform_buildid: 20160229071914 09:56:25 INFO - mozversion INFO | platform_changeset: bbe048ab30ad3321a6505697703e5fee20e91343 09:56:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:25 INFO - mozversion INFO | platform_version: 45.0 09:56:25 INFO - 2016-02-29 09:56:25,459 DEBUG : using testdate: 1456768585 09:56:25 INFO - 2016-02-29 09:56:25,459 DEBUG : actual date: 1456768585 09:56:25 INFO - 2016-02-29 09:56:25,468 INFO : starting webserver on 'localhost:54361' 09:56:25 INFO - 2016-02-29 09:56:25,469 INFO : Starting test suite talos-linux64-ix-068 09:56:25 INFO - 2016-02-29 09:56:25,469 INFO : Starting test dromaeo_css 09:56:25 INFO - 2016-02-29 09:56:25,469 DEBUG : operating with platform_type : linux_ 09:56:25 INFO - 2016-02-29 09:56:25,470 INFO : Initialising browser for dromaeo_css test... 09:56:25 INFO - 2016-02-29 09:56:25,483 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLqVVl4/profile http://localhost:54361/getInfo.html 09:56:30 INFO - 2016-02-29 09:56:30,047 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:56:30 INFO - 2016-02-29 09:56:30,047 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:56:30 INFO - 2016-02-29 09:56:30,047 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:56:30 INFO - 2016-02-29 09:56:30,047 DEBUG : BROWSER_OUTPUT: __metrics 09:56:30 INFO - 2016-02-29 09:56:30,229 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:31 INFO - 2016-02-29 09:56:31,052 INFO : Browser initialized. 09:56:31 INFO - 2016-02-29 09:56:31,053 INFO : Running cycle 1/1 for dromaeo_css test... 09:56:31 INFO - 2016-02-29 09:56:31,053 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLqVVl4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:56:42 INFO - 2016-02-29 09:56:42,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 134516736 09:56:42 INFO - 2016-02-29 09:56:42,731 DEBUG : BROWSER_OUTPUT: 09:57:33 INFO - 2016-02-29 09:57:33,680 DEBUG : BROWSER_OUTPUT: 1456768653674 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] 09:59:42 INFO - 2016-02-29 09:59:42,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54361/tests/dromaeo/cssquery-ext.html) 09:59:43 INFO - 2016-02-29 09:59:43,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 222253056 09:59:43 INFO - 2016-02-29 09:59:43,217 DEBUG : BROWSER_OUTPUT: 10:02:45 INFO - 2016-02-29 10:02:45,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-ext.html (next: http://localhost:54361/tests/dromaeo/cssquery-jquery.html) 10:02:45 INFO - 2016-02-29 10:02:45,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 219578368 10:02:45 INFO - 2016-02-29 10:02:45,861 DEBUG : BROWSER_OUTPUT: 10:05:47 INFO - 2016-02-29 10:05:47,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54361/tests/dromaeo/cssquery-mootools.html) 10:05:48 INFO - 2016-02-29 10:05:48,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 207106048 10:05:48 INFO - 2016-02-29 10:05:48,093 DEBUG : BROWSER_OUTPUT: 10:08:49 INFO - 2016-02-29 10:08:49,814 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54361/tests/dromaeo/cssquery-prototype.html) 10:08:50 INFO - 2016-02-29 10:08:50,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 198275072 10:08:50 INFO - 2016-02-29 10:08:50,381 DEBUG : BROWSER_OUTPUT: 10:11:52 INFO - 2016-02-29 10:11:52,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54361/tests/dromaeo/cssquery-yui.html) 10:11:53 INFO - 2016-02-29 10:11:53,340 DEBUG : BROWSER_OUTPUT: RSS: Main: 201146368 10:11:53 INFO - 2016-02-29 10:11:53,340 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/dromaeo/cssquery-yui.html (next: http://localhost:54361/tests/dromaeo/cssquery-dojo.html) 10:14:55 INFO - 2016-02-29 10:14:55,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 218959872 10:14:55 INFO - 2016-02-29 10:14:55,609 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,610 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:14:55 INFO - 2016-02-29 10:14:55,610 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:14:55 INFO - 2016-02-29 10:14:55,610 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:14:55 INFO - 2016-02-29 10:14:55,611 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:14:55 INFO - 2016-02-29 10:14:55,612 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3303.768736188467;3373;3404.948925766114;3406.727461803056;3429.245565975485;5565.193047008186;5607.663540187592;5608.298962629671;5614.971925140374;5643.322801263849;5613.887722245563;5616.831495055145;5623.690697011662;5631.662100273987;5632.887342253152;4902.313676085351;4979.9293151972315;5070.822521211759;5091.414487333957;5169.638125331228;5082.339295891529;5125.846224613268;5131.332926719521;5131.461196574362;5136.280920671109;4991.850244492671;5098.005888851675;5122.743862806863;5123.769430375642;5159.174532074869;4994.525520075587;5122.769475373599;5124.666896651705;5129.230615407681;5137.922931156036;5039.042581909425;5135.12702840518;5140.665856719339;5147.897042059175;5151.562117220041;5242.8689282767855;5283.63014588979;5354.812581559666;5376.220448035013;5478.534324582415;5948.434898684618;6045.758169673208;6047.334793172748;6057.909131363033;6059.091136329585;5993;6076.422739839708;6080.635161890301;6083.330833608299;6092.390760923917;5328.254044433782;5428.782848686048;5625.409332020161;5702.0591602385975;5739.311282646109;6132.938670613326;6147.231596050494;6154.569180157436;6154.9384506154365;6161.84595385119;5686.061799803069;5964.671943043088;5972.40275972408;5975.342712301644;5979.551533634908;5903.2325797646035;6012.30858451275;6100.298465676416;6106.938930610726;6124.755009799647;5951.821445356646;5988.490978266809;5988.820335389945;5989.431004054608;5996.310424301174;5735.8852822944145;5754.5971781975695;5982.132590774313;5996.610220335664;5997.730102145414;4492.685512014193;4564.0871825635;4567.862964111082;4591.058832939239;4592.88517787058;4075.857344992911;4115.115250221217;4129.97935010323;4141.213169497786;4172.207280616673;5429.755660995264;5509.696966666872;5520.227168196456;5622.184783206412;5654.660720356792;5968.820935371946;6088.330283668793;6092.817215483543;6115.6330620162935;6129;5706.087026075808;5731.168980497804;5732.856678583069;5741.25363702716;5744.052231381859;5049.6212784041345;5134.589232861362;5220.451852555503;5306.8407947761625;5341.0653135701095;6123.2652081750475;6128.938710612926;6130.693465326716;6137.41694539018;6138.202033735586;5999.370066142992;6002.129691194839;6007.369226231182;6007.429294217042;6014.789482368096;6013.278406591237;6039.728212230372;6068.757249709962;6118.893302669218;6123.254872361355;5876.6180198286975;5908.527317814651;5913.5564832636865;5914.142449344735;5960.5827592068135;5735.8566035849435;6050.092486127116;6065.514758819196;6069.848253793691;6071.39286071407;5766.740496677576;5775.220345253337;5865.472107510344;5873.559483038704;5891.970540147272;5804.796832110856;5807.157962095388;5818.796342128006;5875.882482350244;5886.94113058864;5575.916361254665;5620.722636020755;5769.653820770599;5774.1916131740745;5779.162021506942;5881.588288819741;5983.9401605983385;6014.759409623566;6016.2479690038745;6024.578279520391;5757.337906140848;5804.448577385142;5808.041673123887;5810.506106980785;5838.12428135783 10:14:55 INFO - 2016-02-29 10:14:55,613 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.9852503687407;633.1136409027362;637.6333608175295;639.9984025000873;640.6700549217154;32580;42602;42920.356194657026;43026.7848660757;43113.568864311346;47430.762846185804;48035.75982120093;48151.27773083407;48193.7590312048;48215.75892120543;7741.264579864904;7832.960835195816;9153.450792952412;9296.72109836706;9336.859947100766;10942.726431839188;11142.442877856116;11180.04969577587;11181.217314787968;11197.16021298406;10866.67399977998;10950.39772812495;10962.671119866416;10967.890321096767;10972.286801357926;10878.184136189817;10952.123830093613;10983;10994.450277486094;11005.834912476319;10436;10557.841632375521;10570.841437378445;10574.942505749363;10578.576856925714;42935.785321073505;43576.78211608953;43814.78092609548;44016.3397549037;44062.77968610136;3712.9443058354145;3813.6949044076687;3915.4126880967797;3978.4430179774854;4159.9376009359885;31002;31041.223969400944;31460.37079258402;31750;31959.520607190912;4228.534861165269;4265.914681706379;4313.633341166004;4317.373980772771;4382.6055654991205;286010;286794;287196.9999999979;287314;287321;63698.36301637017;64952.35047649557;67912.66043669848;68317.31682683209;74044.62977685184;69728;69880.6505967467;70075.29924700789;70170.64914675395;70216;16140.192990350435;16195.352185912432;16298.103604301878;16366.345346186019;16450.506484805475;11746.88253117475;11757.588484403004;11813.940930295294;11818.704532386759;11839.940800295943;22327.441813954458;22676.433089172573;22724.545509089727;22727.204547840745;22744.772552274597;8377.732684253024;8489.68409896467;8588.355873309527;8591.570421479026;8632.91367086321;1027.8869324374314;1072.6138590107555;1078.5146683992234;1083.7865728116017;1168.4683469021575;29598.556021659693;29834.55248171279;30031.24921876927;30408.54387184194;30481.390372192433;1014.7666036811574;1102.5644870276356;1114.0920150077661;1127.5546159267044;1162.9475324831042;13799.655008624864;15209.239538023054;15792.842071579367;15922.60193495172;17411.73882391765;454850;459462;463120;463786;463907;60465.395346046855;60523.092153617734;60684.393156068756;61037;61211.38788612146;1301.0567338679382;1416.965615100982;1466.2155746675692;1500.1599104501731;1510.5166346768929;29848.913032623896;30003.84998075083;30185.849070754506;30206.54690179693;34549.827250863586;55697.44302556923;56047.43952560422;56353;56513.434865650815;56704;316855;318918;320419;321289;321800;44962.55037449714;45072;47158.528414715416;47242.52757472381;47279.763601181774;49524.50475495199;49653;49881;50013.74993125011;50563.49436505735;42061.57938420577;43041.78479107584;43175.35236971511;43663.12673746571;44449.55550444584;42282.36576451417;42485.787571063185;42600.57399425966;43062.78468607637;43517.78241108774;36546.624469409406;36916.63083369239;37015;37283.25433491369;37347.439788402655;37237.44143837791;38157.2368552633;38283;38869.41695874621;39353.21293574055;26836.865815670797;26843.463130737666;28097;28761.56857647179;28817.56773648439 10:14:55 INFO - 2016-02-29 10:14:55,613 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.97478163919345;388.54346143281634;391.50865663592185;395.4562476594682;396.1640937621616;14383.849292056637;17161.227744751486;17284.91357543212;19434.611307773837;19435.222591096357;17310.04794736289;17668.204930778113;17763.378281760142;17776;18064.729029064576;6067.4842638375685;6224.035274532454;6231.470325022356;6234.781782637609;6235.033569796666;4568;4673.088747694201;4677.649176311773;4786.56920877121;6152.938470615304;6752.358525940028;6906.205786334585;6916.515843890929;6972.2330543640155;6977.651117444133;6880.449564034865;6899.068625735512;6919.411849992706;6935.930640693604;6937.028815965768;6291.559590828644;6618.238902526175;6634.402903738638;6701.29636388182;6702.664866756642;23832;23968;24492.14277500297;24603.63094553583;25131;2338.8128949683983;2496.4133428644254;2519.370157460635;2536.6955965284283;2676.076753520052;14759.483418080423;14994.775078373725;15031.699366012728;15242.695146097127;15592.220388980506;2001.279539365827;2030.5126769575343;2060.9896950515154;2061.5361543652707;2069.7826728193622;72421.63789181074;73440;76764;76983;77086;16027.439039633557;16532.256048477848;16592.668146637243;16614.916925415295;16619.50141495757;26344.73655263461;26449.60325595079;26860.194194173815;26900.461990760075;26908.730912691015;8459.534725590029;8472.44929079608;8480.448770829877;8488.193621605938;8799.12008799115;7150.356467917808;7413.2957369049855;7435.962820185864;7447.292507211806;7459.850802984019;8152.347812175011;8193.8361232775;8198.672053117809;8223.753287401387;8238.423310368216;2507.7868381187805;3000.6999300070256;3114.6885311468673;3130.4365214261197;3141.6544180140168;890.6615486115235;955.2596737528332;966.8646389505475;1045.153425725165;1057.8677665291839;14844.406223751144;16920.830791692173;17113.828861711474;17251.568710782332;17612.383566575107;950.9762255943657;963.9710808675751;984.5766320482261;1004.4324956399611;1026.348268849271;9773.462459564793;9979.750506237402;10030.398176109456;10055.145312648507;10110.898891011142;645916;655740;657882;673831;673919;80637;82393.58803205946;83259.5837020811;83329.58335208407;84202.5789871059;1089;1105.1766434006715;1138.8633363996207;1139.0602752729033;1141.0529260713588;15637.765433518734;15675.29461174227;15705;15861.365545378056;16150.1117438542;34814;35617.82191089028;35706.28587428289;36372.63627363798;36488.45267321046;48544.271835923195;48595.27107093467;48657.756711216214;50701.23948140855;50872;9958.601655933682;10437;10499.842502362624;10502.78994420092;10631.681049568526;12123.151379403356;12181.69545761363;12243.326617035793;14954.327055282756;17920.10399480047;14609;14744.410223591365;14864.182469963824;15061.246937653294;15260.542183734062;14630;14654;15132.470363537665;15317.846821532088;15346.079235245474;14721.779173312194;14868.182249976355;14900.403983840524;15087.622809429851;15338.693226135192;10361.274710770474;10465.895341046493;10563.788724225318;10573.788524229627;10633.149348051982;14334;15591.688166236838;15715.528534143994;15738.606534836721;15855.52433426999 10:14:55 INFO - 2016-02-29 10:14:55,614 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1510.4109397335035;1737.4961261234241;1804.7653805005352;1820.781506219254;1849.2233262029954;7928.3260922821555;8145.674173033079;8202.261796438312;8202.794930126734;8216.055153657331;8028.2373174548475;8068.39487038473;8091.635876385562;8092.150324215946;8093.988251468566;2289.748127705951;2291.8510296830736;2334;2334.054707843326;2346.6128088865316;2336.217367181996;2363.385519764865;2364.326167042393;2364.574376612209;2365.207655435431;1950.7268982342412;1986.2352994097305;2122.373899699592;2168.3928500019942;2233.4528040630066;2276.635738281875;2319.9420014499606;2337.2403968710237;2337.333859849943;2338.7427382987944;2295.1507942061544;2349.3891588187025;2349.612313968193;2350.0247397330086;2352.423656204224;6848.828779280558;6986.441084713261;7548.282913123299;7688.807779805494;7689.384849212105;4607.470140933802;4683.227267500858;4683.929741053887;4687.1797435449;4689;3141.7329526990234;3149.6377916539664;3180.109569320594;3182.9363412731614;3236.7896086754286;1078.3260462211117;1106.1537923488538;1155.9133065020158;2016.8184863362367;2034.0541648133542;10647.946760266226;10853.294535855144;10859.945700271526;10861.3483191008;10868.782624347468;4131.793410329532;4146.523149837747;4217.746935183899;4244.04508985482;4247.447831781848;8912.242459390895;8923.776905577413;8924.598393072327;8933.955330223307;8937.865932011026;4643.582077612963;4656.534346565384;4678.60231880294;4684.508126646721;4689.835855745101;4230.513490948519;4265.786710664516;4267.76527291002;4276.123394704078;4276.593723596254;3531.876384326536;3579;3583.122135076923;3615.475756015363;3629.328574213779;1053.2258789789498;1064.1433645914958;1074.7532861880181;1078.719532921446;1086.1907878630313;1372.9107608005647;1384.8407433145317;1398.5384823008383;1409.2037998530798;1410.5133728863525;7291.453141014339;7446.627668616547;7459.104907411037;7464.925350746532;7466.962665186748;455.16477264219867;455.85640523235077;456.64153639393584;457.06986282914113;472.614818922577;5287.656302340336;5693.572982026365;5740.827775166752;5747.712614369265;5756.42435756421;8829.249513791272;9391.577379017961;9527.666531671359;9537.61849526025;9540.475273859865;9085.182333589877;9216.631334746535;9372.62509499626;9401.153896149242;9700.175485083706;7648.694052237959;7718.884216736754;7723.729669461654;7732.033495813023;7739.961300193463;7088.2202957675645;7288.3076107769675;7344.70621175147;7389.334959853639;7402.9629851850395;8030.879536806836;8088.433809633269;8156.632951517077;8262.793430164294;8773.38586298953;8780.297576193761;8866.468011919305;8935.419197752126;8952.68665596701;9080.40977336471;7770.339521140766;7852.882206766789;7853.803654908673;7863.84272314562;7869.134395216407;7836.921630783847;7971.760847174594;7984.92015079865;7993;8014.398920080901;7144.892826607501;7148.070750802362;7452.92547074544;7596.468247222641;7597.468177227762;7244.529105608119;7265.782026539212;7316.158641756266;7350.595717235603;7393.223711510429;6941.652917354213;7140.857182856417;7158.856822863618;7167.104111986002;7185.964070179615;6680.0647909291765;6693.665316734241;6704.463642908653;6709.496787741036;6714.52998290115;6601.075849380994;6736.696848641921;6755.898661520179;6790;6794.830129246809 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: |4;prototype.html;361.13868423809225;362.6228722128986;366.71945961339566;369.5177792980161;375.01184379160884;6044.788432404866;6076.088586711995;6077.939220607793;6084.269887613487;6160.81517554473;5998;6016.067509536018;6026.668533230671;6028.39716028398;6028.577999540034;4730.4559975602815;4743.241081426972;4745.952540474603;4746.6440016998695;4748.335233067373;4644.4658864230705;4718;4721.551452611996;4724.527547245282;4743.691660042104;4643.582077613014;4661.440627124733;4667.6965997210245;4676.953230467686;4676.976615116937;4495.707778994388;4626.907461850744;4661.114388266218;4664.743439110847;4671.275952227394;4168.083021735213;4209.0529630833125;4229.429027081366;4245.341971994346;4252.489701235841;5812.070068789016;5812.331581868097;5850.180974663551;5851.1223316502865;5856.619319744245;5106.310648062501;5280.392754833205;5408.945910540883;5553.16702494625;5572.331320241597;6513.283538810727;6518.739250429977;6533.967330163367;6547.9345206547805;6553.180852393451;4650.325702773079;4653.115907977473;4656.278276867091;4657.138429390574;4658.650601204923;6618.305077966792;6744.595324280559;6749.966250168767;6763.628000459973;6777.254502004726;4855.465898751135;4856.781444834991;4858.416989961227;4858.975705121452;4859.7327147006545;4911.901761964812;4914.311996320518;4921.680090794087;4923.359963204761;4930.482299358588;5577.247071645276;5589.720513974285;5599.0761524348845;5599.300087489064;5600.803971860965;6445.709943052574;6585.703643336062;6585.73657053722;6588.341165883373;6592.8351791205605;1694.9237284322235;1731.8181590933023;1733.1334332833583;1733.765941597868;1734.5056658852418;1184.8637406698167;1195.6592371174347;1197.245735186844;1217.3608855350835;1218.2203389830515;6295.055741638699;6296.338884417162;6308.08532762756;6311.211098612674;6328.208973878265;6773.017912402674;6780.355866192704;6782.593044417351;6787.287334829772;6792.694328755218;6205.565610407319;6285.371462853769;6311.1795466589965;6315.463185629181;6318.778842740482;3616.367135751233;3644.963550364462;3648.069742215771;3649.6897763690386;3650.79920604364;6887.449004079662;6892.965535172392;6896.965515172392;6899.413549848218;6902.723891044302;6517.087607734921;6632.2704502504685;6892.138482689664;6896.448284137257;6904.723811047502;6879.140107486564;7020.648967551602;7021.227664956953;7024.683889224997;7027;6841.794746157623;6847.144106986627;6850.965745171242;6858.108445902001;6860.5540639858245;6664.900026499703;6767.154105736783;6774.525783195129;6777.186737591521;6780.830479238059;7002.929970700432;7007.929920700932;7009.1589009319205;7013.123359580052;7017.05269788572;4865.197242454955;4868.342773725643;4869.756512174306;4872.220444728827;4872.3909511311085;6688.3491839443395;6801.693923773343;6826.214985276757;6834.760783372558;6847.281035491202;6632.966835165793;6640.203175618956;6649.202095748541;6649.534532582673;6660.900086498803;6624.271330153583;6629.3039230882;6638.402543771083;6638.734450622114;6641.269460359454;6330.588511746721;6476.838079048061;6491.188601424822;6542.443892269211;6542.901856472062;6693.832654183684;6698.899516507159;6702.128723266139;6702.731890724316;6704.296048914794;6524.934750652623;6536.477081833346;6541.672916354259;6542.149520562487;6543.836404089936 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: |5;yui.html;834.7120243515986;887.9156480134387;928.1924725488822;928.2063835420715;953.1040821627673;13417.530386436476;13752.65618359539;13771.5179968701;13790.379432925518;13829.308534573269;11970.76058478832;13411.262380569065;13475.663108422294;13476.393562289695;13525.120867143629;696.9407600353976;874.221942471198;905.0270958719403;906.6781292641116;907.1699395053519;890.9331800114985;893.9374243802937;905.755446029575;906.1301150895149;907.0294784580501;744.8659241336558;756.5952215564674;932.6362718539775;951.1867353412836;951.3816019587289;914.89021317442;932.995335023324;933.1554942776797;934.2899396458672;936.1855185988165;696.1924167965187;696.8466937273791;696.8815301398774;698.25635697982;699.9580025198453;13329.200247985054;13350.399232034484;13365.131266467648;13379.598612041655;13413.329333533284;13211;13530.323483825769;13663.248521331323;13682.247476388795;13734.587962361145;10413.562958107377;10420.583176672924;10773.245872788908;11009.339439633568;11067.225294229407;3859.903502412434;3906.238283534712;3937.940930886039;3947.131631041166;3951.150502641946;45959.77020114911;46551.30173047407;51833.481665183244;52573.948521029364;52643;10582.365058096539;10654.041136297616;10706.839397409045;10778.19163562736;10785.83821242682;18797.81202187988;18808.341708040152;18838.529036773914;18872.52818679544;18882.24471021144;7191.604461754549;7288.6720097595735;7303.488755787044;7471.626418679044;7479.065116860393;4377.4528183977;4416.889577760518;4474.932876006863;4514.277715565494;4660.930086048712;3197.8880739174483;3230.030990702827;3247.512873069011;3252.495863141217;3256.365008823304;594.551113908996;600.9519238460913;602.4668168670757;606.0696830365368;622.6202016769766;685.5201359048685;696.244574636525;699.9195092564319;710.3926143147638;719.4100837313352;8286.875696864552;8668.003179634297;8721.127887211229;8799.78417697482;9050.683226087056;628.9056641503811;643.3791391307411;645.7901182115831;764.3044829205508;769.2499812682702;3910.511186101737;3937.153511994935;4114.788795373431;4162.500499940026;4201.243776120327;56585;56959.14561281584;56988.145177821534;57474.425255747745;57758.71120644454;50479;51023;51550.48449515532;51898.740506297225;52132.73933630308;46372.99999999932;46547.069058619316;46754.76622616865;47198.292025619645;47411.28883066757;7755.689772409266;7773.572453514885;7880.605969701606;7920.683172673029;7986.840263194587;34613;35609.397577108575;35796.64203357933;36067.27865442729;36195.45706814347;41661;42983.78508107439;46161.307580386994;46286.30570541512;46778.29832552582;9593.616255349707;9949.801003980025;10075.949620251851;10149.441780702133;10219.540120694735;9843.557039933077;9939.602415903571;10040.849387259052;10133.493325333851;10209.38743675352;9738.415695058318;9831.213502919893;9848.409095454295;9866.654667086617;9993.60025598997;10431.426271555138;10530.947345263225;10893.237473376788;12159.392030398622;12377.504899803907;9969;11506;11664.591739289424;11911.106666999836;12297.262164270172;9908.801823963337;10172.237082218715;10559.83523377721;10971.63506411169;10984.725381865517;9511.857322140311;9683.854742178732;9811.313208075651;10109.624238304139;10429.217808664229 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,737 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: __startTimestamp1456769695609__endTimestamp 10:14:55 INFO - 2016-02-29 10:14:55,616 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,617 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:14:55 INFO - 2016-02-29 10:14:55,617 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:14:55 INFO - 2016-02-29 10:14:55,617 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,617 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5565.13 Median:5766.74 stddev:616.93 (10.7%) stddev-sans-first:592.77 10:14:55 INFO - 2016-02-29 10:14:55,618 DEBUG : BROWSER_OUTPUT: Values: 3303.8 3373.0 3404.9 3406.7 3429.2 5565.2 5607.7 5608.3 5615.0 5643.3 5613.9 5616.8 5623.7 5631.7 5632.9 4902.3 4979.9 5070.8 5091.4 5169.6 5082.3 5125.8 5131.3 5131.5 5136.3 4991.9 5098.0 5122.7 5123.8 5159.2 4994.5 5122.8 5124.7 5129.2 5137.9 5039.0 5135.1 5140.7 5147.9 5151.6 5242.9 5283.6 5354.8 5376.2 5478.5 5948.4 6045.8 6047.3 6057.9 6059.1 5993.0 6076.4 6080.6 6083.3 6092.4 5328.3 5428.8 5625.4 5702.1 5739.3 6132.9 6147.2 6154.6 6154.9 6161.8 5686.1 5964.7 5972.4 5975.3 5979.6 5903.2 6012.3 6100.3 6106.9 6124.8 5951.8 5988.5 5988.8 5989.4 5996.3 5735.9 5754.6 5982.1 5996.6 5997.7 4492.7 4564.1 4567.9 4591.1 4592.9 4075.9 4115.1 4130.0 4141.2 4172.2 5429.8 5509.7 5520.2 5622.2 5654.7 5968.8 6088.3 6092.8 6115.6 6129.0 5706.1 5731.2 5732.9 5741.3 5744.1 5049.6 5134.6 5220.5 5306.8 5341.1 6123.3 6128.9 6130.7 6137.4 6138.2 5999.4 6002.1 6007.4 6007.4 6014.8 6013.3 6039.7 6068.8 6118.9 6123.3 5876.6 5908.5 5913.6 5914.1 5960.6 5735.9 6050.1 6065.5 6069.8 6071.4 5766.7 5775.2 5865.5 5873.6 5892.0 5804.8 5807.2 5818.8 5875.9 5886.9 5575.9 5620.7 5769.7 5774.2 5779.2 5881.6 5983.9 6014.8 6016.2 6024.6 5757.3 5804.4 5808.0 5810.5 5838.1 10:14:55 INFO - 2016-02-29 10:14:55,618 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,619 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54802.50 Median:37260.35 stddev:95717.30 (256.9%) stddev-sans-first:95899.26 10:14:55 INFO - 2016-02-29 10:14:55,619 DEBUG : BROWSER_OUTPUT: Values: 590.0 633.1 637.6 640.0 640.7 32580.0 42602.0 42920.4 43026.8 43113.6 47430.8 48035.8 48151.3 48193.8 48215.8 7741.3 7833.0 9153.5 9296.7 9336.9 10942.7 11142.4 11180.0 11181.2 11197.2 10866.7 10950.4 10962.7 10967.9 10972.3 10878.2 10952.1 10983.0 10994.5 11005.8 10436.0 10557.8 10570.8 10574.9 10578.6 42935.8 43576.8 43814.8 44016.3 44062.8 3712.9 3813.7 3915.4 3978.4 4159.9 31002.0 31041.2 31460.4 31750.0 31959.5 4228.5 4265.9 4313.6 4317.4 4382.6 286010.0 286794.0 287197.0 287314.0 287321.0 63698.4 64952.4 67912.7 68317.3 74044.6 69728.0 69880.7 70075.3 70170.6 70216.0 16140.2 16195.4 16298.1 16366.3 16450.5 11746.9 11757.6 11813.9 11818.7 11839.9 22327.4 22676.4 22724.5 22727.2 22744.8 8377.7 8489.7 8588.4 8591.6 8632.9 1027.9 1072.6 1078.5 1083.8 1168.5 29598.6 29834.6 30031.2 30408.5 30481.4 1014.8 1102.6 1114.1 1127.6 1162.9 13799.7 15209.2 15792.8 15922.6 17411.7 454850.0 459462.0 463120.0 463786.0 463907.0 60465.4 60523.1 60684.4 61037.0 61211.4 1301.1 1417.0 1466.2 1500.2 1510.5 29848.9 30003.8 30185.8 30206.5 34549.8 55697.4 56047.4 56353.0 56513.4 56704.0 316855.0 318918.0 320419.0 321289.0 321800.0 44962.6 45072.0 47158.5 47242.5 47279.8 49524.5 49653.0 49881.0 50013.7 50563.5 42061.6 43041.8 43175.4 43663.1 44449.6 42282.4 42485.8 42600.6 43062.8 43517.8 36546.6 36916.6 37015.0 37283.3 37347.4 37237.4 38157.2 38283.0 38869.4 39353.2 26836.9 26843.5 28097.0 28761.6 28817.6 10:14:55 INFO - 2016-02-29 10:14:55,619 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,619 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:34089.53 Median:14440.72 stddev:107911.26 (747.3%) stddev-sans-first:108184.30 10:14:55 INFO - 2016-02-29 10:14:55,619 DEBUG : BROWSER_OUTPUT: Values: 388.0 388.5 391.5 395.5 396.2 14383.8 17161.2 17284.9 19434.6 19435.2 17310.0 17668.2 17763.4 17776.0 18064.7 6067.5 6224.0 6231.5 6234.8 6235.0 4568.0 4673.1 4677.6 4786.6 6152.9 6752.4 6906.2 6916.5 6972.2 6977.7 6880.4 6899.1 6919.4 6935.9 6937.0 6291.6 6618.2 6634.4 6701.3 6702.7 23832.0 23968.0 24492.1 24603.6 25131.0 2338.8 2496.4 2519.4 2536.7 2676.1 14759.5 14994.8 15031.7 15242.7 15592.2 2001.3 2030.5 2061.0 2061.5 2069.8 72421.6 73440.0 76764.0 76983.0 77086.0 16027.4 16532.3 16592.7 16614.9 16619.5 26344.7 26449.6 26860.2 26900.5 26908.7 8459.5 8472.4 8480.4 8488.2 8799.1 7150.4 7413.3 7436.0 7447.3 7459.9 8152.3 8193.8 8198.7 8223.8 8238.4 2507.8 3000.7 3114.7 3130.4 3141.7 890.7 955.3 966.9 1045.2 1057.9 14844.4 16920.8 17113.8 17251.6 17612.4 951.0 964.0 984.6 1004.4 1026.3 9773.5 9979.8 10030.4 10055.1 10110.9 645916.0 655740.0 657882.0 673831.0 673919.0 80637.0 82393.6 83259.6 83329.6 84202.6 1089.0 1105.2 1138.9 1139.1 1141.1 15637.8 15675.3 15705.0 15861.4 16150.1 34814.0 35617.8 35706.3 36372.6 36488.5 48544.3 48595.3 48657.8 50701.2 50872.0 9958.6 10437.0 10499.8 10502.8 10631.7 12123.2 12181.7 12243.3 14954.3 17920.1 14609.0 14744.4 14864.2 15061.2 15260.5 14630.0 14654.0 15132.5 15317.8 15346.1 14721.8 14868.2 14900.4 15087.6 15338.7 10361.3 10465.9 10563.8 10573.8 10633.1 14334.0 15591.7 15715.5 15738.6 15855.5 10:14:55 INFO - 2016-02-29 10:14:55,620 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,620 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5569.74 Median:6640.57 stddev:2895.12 (43.6%) stddev-sans-first:2887.16 10:14:55 INFO - 2016-02-29 10:14:55,621 DEBUG : BROWSER_OUTPUT: Values: 1510.4 1737.5 1804.8 1820.8 1849.2 7928.3 8145.7 8202.3 8202.8 8216.1 8028.2 8068.4 8091.6 8092.2 8094.0 2289.7 2291.9 2334.0 2334.1 2346.6 2336.2 2363.4 2364.3 2364.6 2365.2 1950.7 1986.2 2122.4 2168.4 2233.5 2276.6 2319.9 2337.2 2337.3 2338.7 2295.2 2349.4 2349.6 2350.0 2352.4 6848.8 6986.4 7548.3 7688.8 7689.4 4607.5 4683.2 4683.9 4687.2 4689.0 3141.7 3149.6 3180.1 3182.9 3236.8 1078.3 1106.2 1155.9 2016.8 2034.1 10647.9 10853.3 10859.9 10861.3 10868.8 4131.8 4146.5 4217.7 4244.0 4247.4 8912.2 8923.8 8924.6 8934.0 8937.9 4643.6 4656.5 4678.6 4684.5 4689.8 4230.5 4265.8 4267.8 4276.1 4276.6 3531.9 3579.0 3583.1 3615.5 3629.3 1053.2 1064.1 1074.8 1078.7 1086.2 1372.9 1384.8 1398.5 1409.2 1410.5 7291.5 7446.6 7459.1 7464.9 7467.0 455.2 455.9 456.6 457.1 472.6 5287.7 5693.6 5740.8 5747.7 5756.4 8829.2 9391.6 9527.7 9537.6 9540.5 9085.2 9216.6 9372.6 9401.2 9700.2 7648.7 7718.9 7723.7 7732.0 7740.0 7088.2 7288.3 7344.7 7389.3 7403.0 8030.9 8088.4 8156.6 8262.8 8773.4 8780.3 8866.5 8935.4 8952.7 9080.4 7770.3 7852.9 7853.8 7863.8 7869.1 7836.9 7971.8 7984.9 7993.0 8014.4 7144.9 7148.1 7452.9 7596.5 7597.5 7244.5 7265.8 7316.2 7350.6 7393.2 6941.7 7140.9 7158.9 7167.1 7186.0 6680.1 6693.7 6704.5 6709.5 6714.5 6601.1 6736.7 6755.9 6790.0 6794.8 10:14:55 INFO - 2016-02-29 10:14:55,621 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,621 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5511.04 Median:6245.47 stddev:1632.10 (26.1%) stddev-sans-first:1590.25 10:14:55 INFO - 2016-02-29 10:14:55,621 DEBUG : BROWSER_OUTPUT: Values: 361.1 362.6 366.7 369.5 375.0 6044.8 6076.1 6077.9 6084.3 6160.8 5998.0 6016.1 6026.7 6028.4 6028.6 4730.5 4743.2 4746.0 4746.6 4748.3 4644.5 4718.0 4721.6 4724.5 4743.7 4643.6 4661.4 4667.7 4677.0 4677.0 4495.7 4626.9 4661.1 4664.7 4671.3 4168.1 4209.1 4229.4 4245.3 4252.5 5812.1 5812.3 5850.2 5851.1 5856.6 5106.3 5280.4 5408.9 5553.2 5572.3 6513.3 6518.7 6534.0 6547.9 6553.2 4650.3 4653.1 4656.3 4657.1 4658.7 6618.3 6744.6 6750.0 6763.6 6777.3 4855.5 4856.8 4858.4 4859.0 4859.7 4911.9 4914.3 4921.7 4923.4 4930.5 5577.2 5589.7 5599.1 5599.3 5600.8 6445.7 6585.7 6585.7 6588.3 6592.8 1694.9 1731.8 1733.1 1733.8 1734.5 1184.9 1195.7 1197.2 1217.4 1218.2 6295.1 6296.3 6308.1 6311.2 6328.2 6773.0 6780.4 6782.6 6787.3 6792.7 6205.6 6285.4 6311.2 6315.5 6318.8 3616.4 3645.0 3648.1 3649.7 3650.8 6887.4 6893.0 6897.0 6899.4 6902.7 6517.1 6632.3 6892.1 6896.4 6904.7 6879.1 7020.6 7021.2 7024.7 7027.0 6841.8 6847.1 6851.0 6858.1 6860.6 6664.9 6767.2 6774.5 6777.2 6780.8 7002.9 7007.9 7009.2 7013.1 7017.1 4865.2 4868.3 4869.8 4872.2 4872.4 6688.3 6801.7 6826.2 6834.8 6847.3 6633.0 6640.2 6649.2 6649.5 6660.9 6624.3 6629.3 6638.4 6638.7 6641.3 6330.6 6476.8 6491.2 6542.4 6542.9 6693.8 6698.9 6702.1 6702.7 6704.3 6524.9 6536.5 6541.7 6542.1 6543.8 10:14:55 INFO - 2016-02-29 10:14:55,622 DEBUG : BROWSER_OUTPUT: 10:14:55 INFO - 2016-02-29 10:14:55,622 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13880.91 Median:46988.30 stddev:16106.67 (34.3%) stddev-sans-first:16122.05 10:14:55 INFO - 2016-02-29 10:14:55,622 DEBUG : BROWSER_OUTPUT: Values: 834.7 887.9 928.2 928.2 953.1 13417.5 13752.7 13771.5 13790.4 13829.3 11970.8 13411.3 13475.7 13476.4 13525.1 696.9 874.2 905.0 906.7 907.2 890.9 893.9 905.8 906.1 907.0 744.9 756.6 932.6 951.2 951.4 914.9 933.0 933.2 934.3 936.2 696.2 696.8 696.9 698.3 700.0 13329.2 13350.4 13365.1 13379.6 13413.3 13211.0 13530.3 13663.2 13682.2 13734.6 10413.6 10420.6 10773.2 11009.3 11067.2 3859.9 3906.2 3937.9 3947.1 3951.2 45959.8 46551.3 51833.5 52573.9 52643.0 10582.4 10654.0 10706.8 10778.2 10785.8 18797.8 18808.3 18838.5 18872.5 18882.2 7191.6 7288.7 7303.5 7471.6 7479.1 4377.5 4416.9 4474.9 4514.3 4660.9 3197.9 3230.0 3247.5 3252.5 3256.4 594.6 601.0 602.5 606.1 622.6 685.5 696.2 699.9 710.4 719.4 8286.9 8668.0 8721.1 8799.8 9050.7 628.9 643.4 645.8 764.3 769.2 3910.5 3937.2 4114.8 4162.5 4201.2 56585.0 56959.1 56988.1 57474.4 57758.7 50479.0 51023.0 51550.5 51898.7 52132.7 46373.0 46547.1 46754.8 47198.3 47411.3 7755.7 7773.6 7880.6 7920.7 7986.8 34613.0 35609.4 35796.6 36067.3 36195.5 41661.0 42983.8 46161.3 46286.3 46778.3 9593.6 9949.8 10075.9 10149.4 10219.5 9843.6 9939.6 10040.8 10133.5 10209.4 9738.4 9831.2 9848.4 9866.7 9993.6 10431.4 10530.9 10893.2 12159.4 12377.5 9969.0 11506.0 11664.6 11911.1 12297.3 9908.8 10172.2 10559.8 10971.6 10984.7 9511.9 9683.9 9811.3 10109.6 10429.2 10:14:55 INFO - 2016-02-29 10:14:55,622 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:14:55 INFO - 2016-02-29 10:14:55,622 DEBUG : BROWSER_OUTPUT: 10:14:56 INFO - 2016-02-29 10:14:56,208 INFO : Browser exited with error code: 0 10:14:56 INFO - 2016-02-29 10:14:56,229 INFO : Completed test dromaeo_css (00:18:30) 10:14:56 INFO - 2016-02-29 10:14:56,229 INFO : Starting test kraken 10:14:56 INFO - 2016-02-29 10:14:56,229 DEBUG : operating with platform_type : linux_ 10:14:56 INFO - 2016-02-29 10:14:56,230 INFO : Initialising browser for kraken test... 10:14:56 INFO - 2016-02-29 10:14:56,236 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCMhDu8/profile http://localhost:54361/getInfo.html 10:15:00 INFO - 2016-02-29 10:15:00,198 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:15:00 INFO - 2016-02-29 10:15:00,198 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:15:00 INFO - 2016-02-29 10:15:00,198 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:15:00 INFO - 2016-02-29 10:15:00,198 DEBUG : BROWSER_OUTPUT: __metrics 10:15:00 INFO - 2016-02-29 10:15:00,355 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:01 INFO - 2016-02-29 10:15:01,377 INFO : Browser initialized. 10:15:01 INFO - 2016-02-29 10:15:01,377 INFO : Running cycle 1/1 for kraken test... 10:15:01 INFO - 2016-02-29 10:15:01,378 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCMhDu8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:15:13 INFO - 2016-02-29 10:15:13,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 134828032 10:15:13 INFO - 2016-02-29 10:15:13,039 DEBUG : BROWSER_OUTPUT: 10:15:15 INFO - 2016-02-29 10:15:15,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54361/tests/kraken/driver.html?testName=audio-beat-detection) 10:15:16 INFO - 2016-02-29 10:15:16,030 DEBUG : BROWSER_OUTPUT: RSS: Main: 166240256 10:15:16 INFO - 2016-02-29 10:15:16,030 DEBUG : BROWSER_OUTPUT: 10:15:18 INFO - 2016-02-29 10:15:18,588 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54361/tests/kraken/driver.html?testName=audio-dft) 10:15:19 INFO - 2016-02-29 10:15:19,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 163172352 10:15:19 INFO - 2016-02-29 10:15:19,154 DEBUG : BROWSER_OUTPUT: 10:15:21 INFO - 2016-02-29 10:15:21,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54361/tests/kraken/driver.html?testName=audio-fft) 10:15:22 INFO - 2016-02-29 10:15:22,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 160784384 10:15:22 INFO - 2016-02-29 10:15:22,533 DEBUG : BROWSER_OUTPUT: 10:15:24 INFO - 2016-02-29 10:15:24,517 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54361/tests/kraken/driver.html?testName=audio-oscillator) 10:15:25 INFO - 2016-02-29 10:15:25,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 159604736 10:15:25 INFO - 2016-02-29 10:15:25,079 DEBUG : BROWSER_OUTPUT: 10:15:27 INFO - 2016-02-29 10:15:27,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54361/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:27 INFO - 2016-02-29 10:15:27,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 161026048 10:15:27 INFO - 2016-02-29 10:15:27,831 DEBUG : BROWSER_OUTPUT: 10:15:30 INFO - 2016-02-29 10:15:30,694 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54361/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:31 INFO - 2016-02-29 10:15:31,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 160178176 10:15:31 INFO - 2016-02-29 10:15:31,260 DEBUG : BROWSER_OUTPUT: 10:15:34 INFO - 2016-02-29 10:15:34,586 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54361/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:35 INFO - 2016-02-29 10:15:35,159 DEBUG : BROWSER_OUTPUT: RSS: Main: 159657984 10:15:35 INFO - 2016-02-29 10:15:35,160 DEBUG : BROWSER_OUTPUT: 10:15:37 INFO - 2016-02-29 10:15:37,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54361/tests/kraken/driver.html?testName=json-parse-financial) 10:15:38 INFO - 2016-02-29 10:15:38,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 161636352 10:15:38 INFO - 2016-02-29 10:15:38,414 DEBUG : BROWSER_OUTPUT: 10:15:39 INFO - 2016-02-29 10:15:39,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54361/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:40 INFO - 2016-02-29 10:15:40,518 DEBUG : BROWSER_OUTPUT: RSS: Main: 168169472 10:15:40 INFO - 2016-02-29 10:15:40,518 DEBUG : BROWSER_OUTPUT: 10:15:42 INFO - 2016-02-29 10:15:42,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:42 INFO - 2016-02-29 10:15:42,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 165322752 10:15:42 INFO - 2016-02-29 10:15:42,876 DEBUG : BROWSER_OUTPUT: 10:15:44 INFO - 2016-02-29 10:15:44,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:45 INFO - 2016-02-29 10:15:45,435 DEBUG : BROWSER_OUTPUT: RSS: Main: 171638784 10:15:45 INFO - 2016-02-29 10:15:45,435 DEBUG : BROWSER_OUTPUT: 10:15:47 INFO - 2016-02-29 10:15:47,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:15:48 INFO - 2016-02-29 10:15:48,391 DEBUG : BROWSER_OUTPUT: RSS: Main: 177627136 10:15:48 INFO - 2016-02-29 10:15:48,391 DEBUG : BROWSER_OUTPUT: 10:15:50 INFO - 2016-02-29 10:15:50,887 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:15:51 INFO - 2016-02-29 10:15:51,448 DEBUG : BROWSER_OUTPUT: RSS: Main: 174493696 10:15:51 INFO - 2016-02-29 10:15:51,448 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,102 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54361/tests/kraken/driver.html?testName=ai-astar) 10:15:53 INFO - 2016-02-29 10:15:53,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 231211008 10:15:53 INFO - 2016-02-29 10:15:53,353 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,353 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:15:53 INFO - 2016-02-29 10:15:53,354 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:15:53 INFO - 2016-02-29 10:15:53,354 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:15:53 INFO - 2016-02-29 10:15:53,354 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;108;102;107;110;109;98;108;108;109 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;121;142;156;128;146;148;121;161;120 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;164;160;160;160;161;160;163;161;161 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;71;142;72;73;73;74;159;72 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;142;92;92;93;140;91;91;146 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;108;109;109;109;110;110;109;109;110 10:15:53 INFO - 2016-02-29 10:15:53,355 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;148;145;146;146;145;145;145;145 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;96;96;95;95;96;96;95 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;57;57;58;58;57;56;55;54 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;65;66;66;66;66;66;65;66 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;78;78;86;79;79;79;79;77 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;124;115;117;114;124;116;113;113;112 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;146;134;135;142;145;142;141;140;141;142 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;63;64;66;63;66;64;65;65 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,893 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:15:53 INFO - 2016-02-29 10:15:53,356 DEBUG : BROWSER_OUTPUT: __startTimestamp1456769753352__endTimestamp 10:15:53 INFO - 2016-02-29 10:15:53,357 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.60 Median:108.50 stddev:3.72 (3.4%) stddev-sans-first:3.94 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: Values: 107.0 108.0 102.0 107.0 110.0 109.0 98.0 108.0 108.0 109.0 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,360 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.10 Median:147.00 stddev:15.36 (10.4%) stddev-sans-first:15.95 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: Values: 148.0 121.0 142.0 156.0 128.0 146.0 148.0 121.0 161.0 120.0 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.41 (0.9%) stddev-sans-first:1.45 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: Values: 160.0 164.0 160.0 160.0 160.0 161.0 160.0 163.0 161.0 161.0 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,361 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:72.00 stddev:33.36 (46.3%) stddev-sans-first:34.76 10:15:53 INFO - 2016-02-29 10:15:53,362 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 142.0 72.0 73.0 73.0 74.0 159.0 72.0 10:15:53 INFO - 2016-02-29 10:15:53,362 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,362 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.20 Median:92.00 stddev:24.53 (26.7%) stddev-sans-first:25.39 10:15:53 INFO - 2016-02-29 10:15:53,362 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 142.0 92.0 92.0 93.0 140.0 91.0 91.0 146.0 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: Values: 110.0 108.0 109.0 109.0 109.0 110.0 110.0 109.0 109.0 110.0 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:1.10 (0.8%) stddev-sans-first:1.09 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 148.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,363 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.26 (2.2%) stddev-sans-first:1.33 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 57.0 57.0 58.0 58.0 57.0 56.0 55.0 54.0 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 65.0 66.0 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:2.83 (3.6%) stddev-sans-first:2.83 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 78.0 78.0 86.0 79.0 79.0 79.0 79.0 77.0 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:116.50 stddev:4.77 (4.1%) stddev-sans-first:4.56 10:15:53 INFO - 2016-02-29 10:15:53,364 DEBUG : BROWSER_OUTPUT: Values: 123.0 124.0 115.0 117.0 114.0 124.0 116.0 113.0 113.0 112.0 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.80 Median:142.00 stddev:3.79 (2.7%) stddev-sans-first:3.53 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: Values: 146.0 134.0 135.0 142.0 145.0 142.0 141.0 140.0 141.0 142.0 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.07 (1.7%) stddev-sans-first:1.13 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 63.0 64.0 66.0 63.0 66.0 64.0 65.0 65.0 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:15:53 INFO - 2016-02-29 10:15:53,365 DEBUG : BROWSER_OUTPUT: 10:15:54 INFO - 2016-02-29 10:15:54,104 INFO : Browser exited with error code: 0 10:15:54 INFO - 2016-02-29 10:15:54,153 INFO : Completed test kraken (00:00:57) 10:15:54 INFO - 2016-02-29 10:15:54,153 INFO : Starting test v8_7 10:15:54 INFO - 2016-02-29 10:15:54,153 DEBUG : operating with platform_type : linux_ 10:15:54 INFO - 2016-02-29 10:15:54,153 INFO : Initialising browser for v8_7 test... 10:15:54 INFO - 2016-02-29 10:15:54,160 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFygTqS/profile http://localhost:54361/getInfo.html 10:15:58 INFO - 2016-02-29 10:15:58,454 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:15:58 INFO - 2016-02-29 10:15:58,455 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:15:58 INFO - 2016-02-29 10:15:58,455 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:15:58 INFO - 2016-02-29 10:15:58,455 DEBUG : BROWSER_OUTPUT: __metrics 10:15:58 INFO - 2016-02-29 10:15:58,600 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:59 INFO - 2016-02-29 10:15:59,457 INFO : Browser initialized. 10:15:59 INFO - 2016-02-29 10:15:59,457 INFO : Running cycle 1/1 for v8_7 test... 10:15:59 INFO - 2016-02-29 10:15:59,458 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFygTqS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:16:11 INFO - 2016-02-29 10:16:11,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 134303744 10:16:11 INFO - 2016-02-29 10:16:11,058 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,405 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54361/tests/v8_7/run.html (next: http://localhost:54361/tests/v8_7/run.html) 10:16:32 INFO - 2016-02-29 10:16:32,655 DEBUG : BROWSER_OUTPUT: RSS: Main: 419799040 10:16:32 INFO - 2016-02-29 10:16:32,655 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,655 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:16:32 INFO - 2016-02-29 10:16:32,655 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |0;Richards;168.71941960519655 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.257555847569 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.17364600781684 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |4;RayTrace;964.3201542912246 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |5;Earley;545.8515283842795 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |6;Boyer;10552.083333333334 10:16:32 INFO - 2016-02-29 10:16:32,656 DEBUG : BROWSER_OUTPUT: |7;RegExp;24333.333333333332 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: |8;Splay;519.5876288659794 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: __startTimestamp1456769792653__endTimestamp 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:16:32 INFO - 2016-02-29 10:16:32,657 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.72 Median:168.72 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: Values: 168.7 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.26 Median:164.26 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,658 DEBUG : BROWSER_OUTPUT: Values: 164.3 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.17 Median:279.17 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: Values: 279.2 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: Values: 5186.5 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:964.32 Median:964.32 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: Values: 964.3 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:545.85 Median:545.85 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: Values: 545.9 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10552.08 Median:10552.08 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,659 DEBUG : BROWSER_OUTPUT: Values: 10552.1 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24333.33 Median:24333.33 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: Values: 24333.3 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:519.59 Median:519.59 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: Values: 519.6 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: Values: 4830.9 10:16:32 INFO - 2016-02-29 10:16:32,660 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:16:32 INFO - 2016-02-29 10:16:32,661 DEBUG : BROWSER_OUTPUT: 10:16:33 INFO - 2016-02-29 10:16:33,333 INFO : Browser exited with error code: 0 10:16:33 INFO - 2016-02-29 10:16:33,350 INFO : Completed test v8_7 (00:00:39) 10:16:33 INFO - 2016-02-29 10:16:33,721 INFO : Completed test suite (00:20:08) 10:16:33 INFO - 2016-02-29 10:16:33,721 DEBUG : Working with test: dromaeo_css 10:16:33 INFO - 2016-02-29 10:16:33,721 DEBUG : Generating results file: dromaeo_css 10:16:33 INFO - 2016-02-29 10:16:33,722 DEBUG : Working with test: kraken 10:16:33 INFO - 2016-02-29 10:16:33,722 DEBUG : Generating results file: kraken 10:16:33 INFO - 2016-02-29 10:16:33,723 INFO : javascript benchmark 10:16:33 INFO - 2016-02-29 10:16:33,723 DEBUG : Working with test: v8_7 10:16:33 INFO - 2016-02-29 10:16:33,723 DEBUG : Generating results file: v8_7 10:16:33 INFO - 2016-02-29 10:16:33,723 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:16:33 INFO - 2016-02-29 10:16:33,790 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:16:33 INFO - 2016-02-29 10:16:33,790 DEBUG : process_Request line: dromaeo_css 7465.22 graph.html#tests=[[72,53,35]] 10:16:33 INFO - 2016-02-29 10:16:33,790 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:16:33 INFO - 2016-02-29 10:16:33,818 DEBUG : process_Request line: kraken 1475.50 graph.html#tests=[[232,53,35]] 10:16:33 INFO - 2016-02-29 10:16:33,818 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:16:33 INFO - 2016-02-29 10:16:33,846 DEBUG : process_Request line: v8_7 23227.53 graph.html#tests=[[230,53,35]] 10:16:33 INFO - 2016-02-29 10:16:33,847 INFO : javascript benchmark 10:16:33 INFO - 2016-02-29 10:16:33,851 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3303.768736188467, 3373.0, 3404.948925766114, 3406.727461803056, 3429.245565975485, 5565.193047008186, 5607.663540187592, 5608.298962629671, 5614.971925140374, 5643.322801263849, 5613.887722245563, 5616.831495055145, 5623.690697011662, 5631.662100273987, 5632.887342253152, 4902.313676085351, 4979.9293151972315, 5070.822521211759, 5091.414487333957, 5169.638125331228, 5082.339295891529, 5125.846224613268, 5131.332926719521, 5131.461196574362, 5136.280920671109, 4991.850244492671, 5098.005888851675, 5122.743862806863, 5123.769430375642, 5159.174532074869, 4994.525520075587, 5122.769475373599, 5124.666896651705, 5129.230615407681, 5137.922931156036, 5039.042581909425, 5135.12702840518, 5140.665856719339, 5147.897042059175, 5151.562117220041, 5242.8689282767855, 5283.63014588979, 5354.812581559666, 5376.220448035013, 5478.534324582415, 5948.434898684618, 6045.758169673208, 6047.334793172748, 6057.909131363033, 6059.091136329585, 5993.0, 6076.422739839708, 6080.635161890301, 6083.330833608299, 6092.390760923917, 5328.254044433782, 5428.782848686048, 5625.409332020161, 5702.0591602385975, 5739.311282646109, 6132.938670613326, 6147.231596050494, 6154.569180157436, 6154.9384506154365, 6161.84595385119, 5686.061799803069, 5964.671943043088, 5972.40275972408, 5975.342712301644, 5979.551533634908, 5903.2325797646035, 6012.30858451275, 6100.298465676416, 6106.938930610726, 6124.755009799647, 5951.821445356646, 5988.490978266809, 5988.820335389945, 5989.431004054608, 5996.310424301174, 5735.8852822944145, 5754.5971781975695, 5982.132590774313, 5996.610220335664, 5997.730102145414, 4492.685512014193, 4564.0871825635, 4567.862964111082, 4591.058832939239, 4592.88517787058, 4075.857344992911, 4115.115250221217, 4129.97935010323, 4141.213169497786, 4172.207280616673, 5429.755660995264, 5509.696966666872, 5520.227168196456, 5622.184783206412, 5654.660720356792, 5968.820935371946, 6088.330283668793, 6092.817215483543, 6115.6330620162935, 6129.0, 5706.087026075808, 5731.168980497804, 5732.856678583069, 5741.25363702716, 5744.052231381859, 5049.6212784041345, 5134.589232861362, 5220.451852555503, 5306.8407947761625, 5341.0653135701095, 6123.2652081750475, 6128.938710612926, 6130.693465326716, 6137.41694539018, 6138.202033735586, 5999.370066142992, 6002.129691194839, 6007.369226231182, 6007.429294217042, 6014.789482368096, 6013.278406591237, 6039.728212230372, 6068.757249709962, 6118.893302669218, 6123.254872361355, 5876.6180198286975, 5908.527317814651, 5913.5564832636865, 5914.142449344735, 5960.5827592068135, 5735.8566035849435, 6050.092486127116, 6065.514758819196, 6069.848253793691, 6071.39286071407, 5766.740496677576, 5775.220345253337, 5865.472107510344, 5873.559483038704, 5891.970540147272, 5804.796832110856, 5807.157962095388, 5818.796342128006, 5875.882482350244, 5886.94113058864, 5575.916361254665, 5620.722636020755, 5769.653820770599, 5774.1916131740745, 5779.162021506942, 5881.588288819741, 5983.9401605983385, 6014.759409623566, 6016.2479690038745, 6024.578279520391, 5757.337906140848, 5804.448577385142, 5808.041673123887, 5810.506106980785, 5838.12428135783], "unit": "ms", "name": "dojo.html", "value": 5525.5192839125475}, {"lowerIsBetter": false, "replicates": [589.9852503687407, 633.1136409027362, 637.6333608175295, 639.9984025000873, 640.6700549217154, 32580.0, 42602.0, 42920.356194657026, 43026.7848660757, 43113.568864311346, 47430.762846185804, 48035.75982120093, 48151.27773083407, 48193.7590312048, 48215.75892120543, 7741.264579864904, 7832.960835195816, 9153.450792952412, 9296.72109836706, 9336.859947100766, 10942.726431839188, 11142.442877856116, 11180.04969577587, 11181.217314787968, 11197.16021298406, 10866.67399977998, 10950.39772812495, 10962.671119866416, 10967.890321096767, 10972.286801357926, 10878.184136189817, 10952.123830093613, 10983.0, 10994.450277486094, 11005.834912476319, 10436.0, 10557.841632375521, 10570.841437378445, 10574.942505749363, 10578.576856925714, 42935.785321073505, 43576.78211608953, 43814.78092609548, 44016.3397549037, 44062.77968610136, 3712.9443058354145, 3813.6949044076687, 3915.4126880967797, 3978.4430179774854, 4159.9376009359885, 31002.0, 31041.223969400944, 31460.37079258402, 31750.0, 31959.520607190912, 4228.534861165269, 4265.914681706379, 4313.633341166004, 4317.373980772771, 4382.6055654991205, 286010.0, 286794.0, 287196.9999999979, 287314.0, 287321.0, 63698.36301637017, 64952.35047649557, 67912.66043669848, 68317.31682683209, 74044.62977685184, 69728.0, 69880.6505967467, 70075.29924700789, 70170.64914675395, 70216.0, 16140.192990350435, 16195.352185912432, 16298.103604301878, 16366.345346186019, 16450.506484805475, 11746.88253117475, 11757.588484403004, 11813.940930295294, 11818.704532386759, 11839.940800295943, 22327.441813954458, 22676.433089172573, 22724.545509089727, 22727.204547840745, 22744.772552274597, 8377.732684253024, 8489.68409896467, 8588.355873309527, 8591.570421479026, 8632.91367086321, 1027.8869324374314, 1072.6138590107555, 1078.5146683992234, 1083.7865728116017, 1168.4683469021575, 29598.556021659693, 29834.55248171279, 30031.24921876927, 30408.54387184194, 30481.390372192433, 1014.7666036811574, 1102.5644870276356, 1114.0920150077661, 1127.5546159267044, 1162.9475324831042, 13799.655008624864, 15209.239538023054, 15792.842071579367, 15922.60193495172, 17411.73882391765, 454850.0, 459462.0, 463120.0, 463786.0, 463907.0, 60465.395346046855, 60523.092153617734, 60684.393156068756, 61037.0, 61211.38788612146, 1301.0567338679382, 1416.965615100982, 1466.2155746675692, 1500.1599104501731, 1510.5166346768929, 29848.913032623896, 30003.84998075083, 30185.849070754506, 30206.54690179693, 34549.827250863586, 55697.44302556923, 56047.43952560422, 56353.0, 56513.434865650815, 56704.0, 316855.0, 318918.0, 320419.0, 321289.0, 321800.0, 44962.55037449714, 45072.0, 47158.528414715416, 47242.52757472381, 47279.763601181774, 49524.50475495199, 49653.0, 49881.0, 50013.74993125011, 50563.49436505735, 42061.57938420577, 43041.78479107584, 43175.35236971511, 43663.12673746571, 44449.55550444584, 42282.36576451417, 42485.787571063185, 42600.57399425966, 43062.78468607637, 43517.78241108774, 36546.624469409406, 36916.63083369239, 37015.0, 37283.25433491369, 37347.439788402655, 37237.44143837791, 38157.2368552633, 38283.0, 38869.41695874621, 39353.21293574055, 26836.865815670797, 26843.463130737666, 28097.0, 28761.56857647179, 28817.56773648439], "unit": "ms", "name": "ext.html", "value": 20972.6425920402}, {"lowerIsBetter": false, "replicates": [387.97478163919345, 388.54346143281634, 391.50865663592185, 395.4562476594682, 396.1640937621616, 14383.849292056637, 17161.227744751486, 17284.91357543212, 19434.611307773837, 19435.222591096357, 17310.04794736289, 17668.204930778113, 17763.378281760142, 17776.0, 18064.729029064576, 6067.4842638375685, 6224.035274532454, 6231.470325022356, 6234.781782637609, 6235.033569796666, 4568.0, 4673.088747694201, 4677.649176311773, 4786.56920877121, 6152.938470615304, 6752.358525940028, 6906.205786334585, 6916.515843890929, 6972.2330543640155, 6977.651117444133, 6880.449564034865, 6899.068625735512, 6919.411849992706, 6935.930640693604, 6937.028815965768, 6291.559590828644, 6618.238902526175, 6634.402903738638, 6701.29636388182, 6702.664866756642, 23832.0, 23968.0, 24492.14277500297, 24603.63094553583, 25131.0, 2338.8128949683983, 2496.4133428644254, 2519.370157460635, 2536.6955965284283, 2676.076753520052, 14759.483418080423, 14994.775078373725, 15031.699366012728, 15242.695146097127, 15592.220388980506, 2001.279539365827, 2030.5126769575343, 2060.9896950515154, 2061.5361543652707, 2069.7826728193622, 72421.63789181074, 73440.0, 76764.0, 76983.0, 77086.0, 16027.439039633557, 16532.256048477848, 16592.668146637243, 16614.916925415295, 16619.50141495757, 26344.73655263461, 26449.60325595079, 26860.194194173815, 26900.461990760075, 26908.730912691015, 8459.534725590029, 8472.44929079608, 8480.448770829877, 8488.193621605938, 8799.12008799115, 7150.356467917808, 7413.2957369049855, 7435.962820185864, 7447.292507211806, 7459.850802984019, 8152.347812175011, 8193.8361232775, 8198.672053117809, 8223.753287401387, 8238.423310368216, 2507.7868381187805, 3000.6999300070256, 3114.6885311468673, 3130.4365214261197, 3141.6544180140168, 890.6615486115235, 955.2596737528332, 966.8646389505475, 1045.153425725165, 1057.8677665291839, 14844.406223751144, 16920.830791692173, 17113.828861711474, 17251.568710782332, 17612.383566575107, 950.9762255943657, 963.9710808675751, 984.5766320482261, 1004.4324956399611, 1026.348268849271, 9773.462459564793, 9979.750506237402, 10030.398176109456, 10055.145312648507, 10110.898891011142, 645916.0, 655740.0, 657882.0, 673831.0, 673919.0, 80637.0, 82393.58803205946, 83259.5837020811, 83329.58335208407, 84202.5789871059, 1089.0, 1105.1766434006715, 1138.8633363996207, 1139.0602752729033, 1141.0529260713588, 15637.765433518734, 15675.29461174227, 15705.0, 15861.365545378056, 16150.1117438542, 34814.0, 35617.82191089028, 35706.28587428289, 36372.63627363798, 36488.45267321046, 48544.271835923195, 48595.27107093467, 48657.756711216214, 50701.23948140855, 50872.0, 9958.601655933682, 10437.0, 10499.842502362624, 10502.78994420092, 10631.681049568526, 12123.151379403356, 12181.69545761363, 12243.326617035793, 14954.327055282756, 17920.10399480047, 14609.0, 14744.410223591365, 14864.182469963824, 15061.246937653294, 15260.542183734062, 14630.0, 14654.0, 15132.470363537665, 15317.846821532088, 15346.079235245474, 14721.779173312194, 14868.182249976355, 14900.403983840524, 15087.622809429851, 15338.693226135192, 10361.274710770474, 10465.895341046493, 10563.788724225318, 10573.788524229627, 10633.149348051982, 14334.0, 15591.688166236838, 15715.528534143994, 15738.606534836721, 15855.52433426999], "unit": "ms", "name": "jquery.html", "value": 10260.77675143403}, {"lowerIsBetter": false, "replicates": [1510.4109397335035, 1737.4961261234241, 1804.7653805005352, 1820.781506219254, 1849.2233262029954, 7928.3260922821555, 8145.674173033079, 8202.261796438312, 8202.794930126734, 8216.055153657331, 8028.2373174548475, 8068.39487038473, 8091.635876385562, 8092.150324215946, 8093.988251468566, 2289.748127705951, 2291.8510296830736, 2334.0, 2334.054707843326, 2346.6128088865316, 2336.217367181996, 2363.385519764865, 2364.326167042393, 2364.574376612209, 2365.207655435431, 1950.7268982342412, 1986.2352994097305, 2122.373899699592, 2168.3928500019942, 2233.4528040630066, 2276.635738281875, 2319.9420014499606, 2337.2403968710237, 2337.333859849943, 2338.7427382987944, 2295.1507942061544, 2349.3891588187025, 2349.612313968193, 2350.0247397330086, 2352.423656204224, 6848.828779280558, 6986.441084713261, 7548.282913123299, 7688.807779805494, 7689.384849212105, 4607.470140933802, 4683.227267500858, 4683.929741053887, 4687.1797435449, 4689.0, 3141.7329526990234, 3149.6377916539664, 3180.109569320594, 3182.9363412731614, 3236.7896086754286, 1078.3260462211117, 1106.1537923488538, 1155.9133065020158, 2016.8184863362367, 2034.0541648133542, 10647.946760266226, 10853.294535855144, 10859.945700271526, 10861.3483191008, 10868.782624347468, 4131.793410329532, 4146.523149837747, 4217.746935183899, 4244.04508985482, 4247.447831781848, 8912.242459390895, 8923.776905577413, 8924.598393072327, 8933.955330223307, 8937.865932011026, 4643.582077612963, 4656.534346565384, 4678.60231880294, 4684.508126646721, 4689.835855745101, 4230.513490948519, 4265.786710664516, 4267.76527291002, 4276.123394704078, 4276.593723596254, 3531.876384326536, 3579.0, 3583.122135076923, 3615.475756015363, 3629.328574213779, 1053.2258789789498, 1064.1433645914958, 1074.7532861880181, 1078.719532921446, 1086.1907878630313, 1372.9107608005647, 1384.8407433145317, 1398.5384823008383, 1409.2037998530798, 1410.5133728863525, 7291.453141014339, 7446.627668616547, 7459.104907411037, 7464.925350746532, 7466.962665186748, 455.16477264219867, 455.85640523235077, 456.64153639393584, 457.06986282914113, 472.614818922577, 5287.656302340336, 5693.572982026365, 5740.827775166752, 5747.712614369265, 5756.42435756421, 8829.249513791272, 9391.577379017961, 9527.666531671359, 9537.61849526025, 9540.475273859865, 9085.182333589877, 9216.631334746535, 9372.62509499626, 9401.153896149242, 9700.175485083706, 7648.694052237959, 7718.884216736754, 7723.729669461654, 7732.033495813023, 7739.961300193463, 7088.2202957675645, 7288.3076107769675, 7344.70621175147, 7389.334959853639, 7402.9629851850395, 8030.879536806836, 8088.433809633269, 8156.632951517077, 8262.793430164294, 8773.38586298953, 8780.297576193761, 8866.468011919305, 8935.419197752126, 8952.68665596701, 9080.40977336471, 7770.339521140766, 7852.882206766789, 7853.803654908673, 7863.84272314562, 7869.134395216407, 7836.921630783847, 7971.760847174594, 7984.92015079865, 7993.0, 8014.398920080901, 7144.892826607501, 7148.070750802362, 7452.92547074544, 7596.468247222641, 7597.468177227762, 7244.529105608119, 7265.782026539212, 7316.158641756266, 7350.595717235603, 7393.223711510429, 6941.652917354213, 7140.857182856417, 7158.856822863618, 7167.104111986002, 7185.964070179615, 6680.0647909291765, 6693.665316734241, 6704.463642908653, 6709.496787741036, 6714.52998290115, 6601.075849380994, 6736.696848641921, 6755.898661520179, 6790.0, 6794.830129246809], "unit": "ms", "name": "mootools.html", "value": 4518.27037813031}, {"lowerIsBetter": false, "replicates": [361.13868423809225, 362.6228722128986, 366.71945961339566, 369.5177792980161, 375.01184379160884, 6044.788432404866, 6076.088586711995, 6077.939220607793, 6084.269887613487, 6160.81517554473, 5998.0, 6016.067509536018, 6026.668533230671, 6028.39716028398, 6028.577999540034, 4730.4559975602815, 4743.241081426972, 4745.952540474603, 4746.6440016998695, 4748.335233067373, 4644.4658864230705, 4718.0, 4721.551452611996, 4724.527547245282, 4743.691660042104, 4643.582077613014, 4661.440627124733, 4667.6965997210245, 4676.953230467686, 4676.976615116937, 4495.707778994388, 4626.907461850744, 4661.114388266218, 4664.743439110847, 4671.275952227394, 4168.083021735213, 4209.0529630833125, 4229.429027081366, 4245.341971994346, 4252.489701235841, 5812.070068789016, 5812.331581868097, 5850.180974663551, 5851.1223316502865, 5856.619319744245, 5106.310648062501, 5280.392754833205, 5408.945910540883, 5553.16702494625, 5572.331320241597, 6513.283538810727, 6518.739250429977, 6533.967330163367, 6547.9345206547805, 6553.180852393451, 4650.325702773079, 4653.115907977473, 4656.278276867091, 4657.138429390574, 4658.650601204923, 6618.305077966792, 6744.595324280559, 6749.966250168767, 6763.628000459973, 6777.254502004726, 4855.465898751135, 4856.781444834991, 4858.416989961227, 4858.975705121452, 4859.7327147006545, 4911.901761964812, 4914.311996320518, 4921.680090794087, 4923.359963204761, 4930.482299358588, 5577.247071645276, 5589.720513974285, 5599.0761524348845, 5599.300087489064, 5600.803971860965, 6445.709943052574, 6585.703643336062, 6585.73657053722, 6588.341165883373, 6592.8351791205605, 1694.9237284322235, 1731.8181590933023, 1733.1334332833583, 1733.765941597868, 1734.5056658852418, 1184.8637406698167, 1195.6592371174347, 1197.245735186844, 1217.3608855350835, 1218.2203389830515, 6295.055741638699, 6296.338884417162, 6308.08532762756, 6311.211098612674, 6328.208973878265, 6773.017912402674, 6780.355866192704, 6782.593044417351, 6787.287334829772, 6792.694328755218, 6205.565610407319, 6285.371462853769, 6311.1795466589965, 6315.463185629181, 6318.778842740482, 3616.367135751233, 3644.963550364462, 3648.069742215771, 3649.6897763690386, 3650.79920604364, 6887.449004079662, 6892.965535172392, 6896.965515172392, 6899.413549848218, 6902.723891044302, 6517.087607734921, 6632.2704502504685, 6892.138482689664, 6896.448284137257, 6904.723811047502, 6879.140107486564, 7020.648967551602, 7021.227664956953, 7024.683889224997, 7027.0, 6841.794746157623, 6847.144106986627, 6850.965745171242, 6858.108445902001, 6860.5540639858245, 6664.900026499703, 6767.154105736783, 6774.525783195129, 6777.186737591521, 6780.830479238059, 7002.929970700432, 7007.929920700932, 7009.1589009319205, 7013.123359580052, 7017.05269788572, 4865.197242454955, 4868.342773725643, 4869.756512174306, 4872.220444728827, 4872.3909511311085, 6688.3491839443395, 6801.693923773343, 6826.214985276757, 6834.760783372558, 6847.281035491202, 6632.966835165793, 6640.203175618956, 6649.202095748541, 6649.534532582673, 6660.900086498803, 6624.271330153583, 6629.3039230882, 6638.402543771083, 6638.734450622114, 6641.269460359454, 6330.588511746721, 6476.838079048061, 6491.188601424822, 6542.443892269211, 6542.901856472062, 6693.832654183684, 6698.899516507159, 6702.128723266139, 6702.731890724316, 6704.296048914794, 6524.934750652623, 6536.477081833346, 6541.672916354259, 6542.149520562487, 6543.836404089936], "unit": "ms", "name": "prototype.html", "value": 4992.692904564362}, {"lowerIsBetter": false, "replicates": [834.7120243515986, 887.9156480134387, 928.1924725488822, 928.2063835420715, 953.1040821627673, 13417.530386436476, 13752.65618359539, 13771.5179968701, 13790.379432925518, 13829.308534573269, 11970.76058478832, 13411.262380569065, 13475.663108422294, 13476.393562289695, 13525.120867143629, 696.9407600353976, 874.221942471198, 905.0270958719403, 906.6781292641116, 907.1699395053519, 890.9331800114985, 893.9374243802937, 905.755446029575, 906.1301150895149, 907.0294784580501, 744.8659241336558, 756.5952215564674, 932.6362718539775, 951.1867353412836, 951.3816019587289, 914.89021317442, 932.995335023324, 933.1554942776797, 934.2899396458672, 936.1855185988165, 696.1924167965187, 696.8466937273791, 696.8815301398774, 698.25635697982, 699.9580025198453, 13329.200247985054, 13350.399232034484, 13365.131266467648, 13379.598612041655, 13413.329333533284, 13211.0, 13530.323483825769, 13663.248521331323, 13682.247476388795, 13734.587962361145, 10413.562958107377, 10420.583176672924, 10773.245872788908, 11009.339439633568, 11067.225294229407, 3859.903502412434, 3906.238283534712, 3937.940930886039, 3947.131631041166, 3951.150502641946, 45959.77020114911, 46551.30173047407, 51833.481665183244, 52573.948521029364, 52643.0, 10582.365058096539, 10654.041136297616, 10706.839397409045, 10778.19163562736, 10785.83821242682, 18797.81202187988, 18808.341708040152, 18838.529036773914, 18872.52818679544, 18882.24471021144, 7191.604461754549, 7288.6720097595735, 7303.488755787044, 7471.626418679044, 7479.065116860393, 4377.4528183977, 4416.889577760518, 4474.932876006863, 4514.277715565494, 4660.930086048712, 3197.8880739174483, 3230.030990702827, 3247.512873069011, 3252.495863141217, 3256.365008823304, 594.551113908996, 600.9519238460913, 602.4668168670757, 606.0696830365368, 622.6202016769766, 685.5201359048685, 696.244574636525, 699.9195092564319, 710.3926143147638, 719.4100837313352, 8286.875696864552, 8668.003179634297, 8721.127887211229, 8799.78417697482, 9050.683226087056, 628.9056641503811, 643.3791391307411, 645.7901182115831, 764.3044829205508, 769.2499812682702, 3910.511186101737, 3937.153511994935, 4114.788795373431, 4162.500499940026, 4201.243776120327, 56585.0, 56959.14561281584, 56988.145177821534, 57474.425255747745, 57758.71120644454, 50479.0, 51023.0, 51550.48449515532, 51898.740506297225, 52132.73933630308, 46372.99999999932, 46547.069058619316, 46754.76622616865, 47198.292025619645, 47411.28883066757, 7755.689772409266, 7773.572453514885, 7880.605969701606, 7920.683172673029, 7986.840263194587, 34613.0, 35609.397577108575, 35796.64203357933, 36067.27865442729, 36195.45706814347, 41661.0, 42983.78508107439, 46161.307580386994, 46286.30570541512, 46778.29832552582, 9593.616255349707, 9949.801003980025, 10075.949620251851, 10149.441780702133, 10219.540120694735, 9843.557039933077, 9939.602415903571, 10040.849387259052, 10133.493325333851, 10209.38743675352, 9738.415695058318, 9831.213502919893, 9848.409095454295, 9866.654667086617, 9993.60025598997, 10431.426271555138, 10530.947345263225, 10893.237473376788, 12159.392030398622, 12377.504899803907, 9969.0, 11506.0, 11664.591739289424, 11911.106666999836, 12297.262164270172, 9908.801823963337, 10172.237082218715, 10559.83523377721, 10971.63506411169, 10984.725381865517, 9511.857322140311, 9683.854742178732, 9811.313208075651, 10109.624238304139, 10429.217808664229], "unit": "ms", "name": "yui.html", "value": 6452.069282549346}], "name": "dromaeo_css", "value": 7465.219721175919}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 108.0, 102.0, 107.0, 110.0, 109.0, 98.0, 108.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.6}, {"lowerIsBetter": true, "replicates": [148.0, 121.0, 142.0, 156.0, 128.0, 146.0, 148.0, 121.0, 161.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.1}, {"lowerIsBetter": true, "replicates": [160.0, 164.0, 160.0, 160.0, 160.0, 161.0, 160.0, 163.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 142.0, 72.0, 73.0, 73.0, 74.0, 159.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 142.0, 92.0, 92.0, 93.0, 140.0, 91.0, 91.0, 146.0], "unit": "ms", "name": "audio-oscillator", "value": 107.2}, {"lowerIsBetter": true, "replicates": [110.0, 108.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 148.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 57.0, 58.0, 58.0, 57.0, 56.0, 55.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.6}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 78.0, 78.0, 86.0, 79.0, 79.0, 79.0, 79.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.7}, {"lowerIsBetter": true, "replicates": [123.0, 124.0, 115.0, 117.0, 114.0, 124.0, 116.0, 113.0, 113.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.1}, {"lowerIsBetter": true, "replicates": [146.0, 134.0, 135.0, 142.0, 145.0, 142.0, 141.0, 140.0, 141.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.8}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 63.0, 64.0, 66.0, 63.0, 66.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.4}], "name": "kraken", "value": 1475.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.71941960519655], "unit": "score", "name": "Richards", "value": 209.234954}, {"lowerIsBetter": false, "replicates": [164.257555847569], "unit": "score", "name": "DeltaBlue", "value": 402.526384}, {"lowerIsBetter": false, "replicates": [279.17364600781684], "unit": "score", "name": "Encrypt", "value": 953.4603419999996}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [964.3201542912246], "unit": "score", "name": "RayTrace", "value": 767.3685930000003}, {"lowerIsBetter": false, "replicates": [545.8515283842795], "unit": "score", "name": "Earley", "value": 1220.9602159999997}, {"lowerIsBetter": false, "replicates": [10552.083333333334], "unit": "score", "name": "Boyer", "value": 63.159376110562626}, {"lowerIsBetter": false, "replicates": [24333.333333333332], "unit": "score", "name": "RegExp", "value": 37.437739726027424}, {"lowerIsBetter": false, "replicates": [519.5876288659794], "unit": "score", "name": "Splay", "value": 156.83783730158734}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23227.52883958375}]} 10:16:33 INFO - RETURN: dromaeo_css: 7465.22 10:16:33 INFO - RETURN: kraken: 1475.50 10:16:33 INFO - RETURN: v8_7: 23227.53 10:16:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23227.53"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7465.22"}}} 10:16:33 INFO - Return code: 0 10:16:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:16:33 INFO - # TBPL SUCCESS # 10:16:33 INFO - Running post-action listener: _resource_record_post_action 10:16:33 INFO - Running post-run listener: _resource_record_post_run 10:16:35 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 327680; Write bytes: 515129344; Read time: 612; Write time: 990128 10:16:35 INFO - install - Wall time: 16s; CPU: 17.0%; Read bytes: 4096; Write bytes: 252145664; Read time: 288; Write time: 665868 10:16:35 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 323584; Write bytes: 262983680; Read time: 324; Write time: 324260 10:16:35 INFO - Running post-run listener: _upload_blobber_files 10:16:35 INFO - Blob upload gear active. 10:16:35 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:16:35 INFO - Copying logs to upload dir... 10:16:35 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.565228 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-02-29 10:16:33.184934) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-29 10:16:33.188700) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010057 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456759154/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-29 10:16:33.240489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 10:16:33.240812) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1456768536.434411-533043972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 10:16:33.290052) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 10:16:33.290373) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 10:16:33.290656) ========= ========= Total master_lag: 0.25 =========