builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-066 starttime: 1448497344.04 results: success (0) buildid: 20151125143004 builduid: 0551c0ba97c042e384658b854b79a551 revision: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.043825) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.044309) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.044777) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.003887 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.091174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.091463) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.197482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.197919) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.030474 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.249564) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.249842) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.250200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.250487) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 16:22:24-- 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% 60.8M=0s 2015-11-25 16:22:24 (60.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.224101 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.486886) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.487203) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.070921 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.570775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.571141) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 --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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 16:22:24,777 truncating revision to first 12 chars 2015-11-25 16:22:24,777 Setting DEBUG logging. 2015-11-25 16:22:24,777 attempt 1/10 2015-11-25 16:22:24,777 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3e7edd0d28a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 16:22:25,039 unpacking tar archive at: fx-team-3e7edd0d28a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.533980 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.117663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.117969) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.127584) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.127965) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.128430) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-11-25 16:22:25.128716) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:22:25 INFO - MultiFileLogger online at 20151125 16:22:25 in /builds/slave/test-pgo 16:22:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:22:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:22:25 INFO - {'append_to_log': False, 16:22:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:22:25 INFO - 'blob_upload_branch': 'Fx-Team', 16:22:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:22:25 INFO - 'branch': 'Fx-Team', 16:22:25 INFO - 'buildbot_json_path': 'buildprops.json', 16:22:25 INFO - 'config_files': ('talos/linux_config.py',), 16:22:25 INFO - 'default_actions': ('clobber', 16:22:25 INFO - 'read-buildbot-config', 16:22:25 INFO - 'download-and-extract', 16:22:25 INFO - 'populate-webroot', 16:22:25 INFO - 'create-virtualenv', 16:22:25 INFO - 'install', 16:22:25 INFO - 'run-tests'), 16:22:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:22:25 INFO - 'download_minidump_stackwalk': True, 16:22:25 INFO - 'download_symbols': 'ondemand', 16:22:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:22:25 INFO - 'tooltool.py': '/tools/tooltool.py', 16:22:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:22:25 INFO - '/tools/misc-python/virtualenv.py')}, 16:22:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:22:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:22:25 INFO - 'installer_path': 'installer.exe', 16:22:25 INFO - 'log_level': 'info', 16:22:25 INFO - 'log_name': 'talos', 16:22:25 INFO - 'log_to_console': True, 16:22:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:22:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:22:25 INFO - 'opt_config_files': (), 16:22:25 INFO - 'pip_index': False, 16:22:25 INFO - 'sps_profile': False, 16:22:25 INFO - 'sps_profile_interval': 0, 16:22:25 INFO - 'suite': 'dromaeojs-e10s', 16:22:25 INFO - 'system_bits': '64', 16:22:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:22:25 INFO - 'title': 'talos-linux64-ix-066', 16:22:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:22:25 INFO - 'use_talos_json': True, 16:22:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:22:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:22:25 INFO - 'work_dir': 'build'} 16:22:25 INFO - ##### 16:22:25 INFO - ##### Running clobber step. 16:22:25 INFO - ##### 16:22:25 INFO - Running pre-action listener: _resource_record_pre_action 16:22:25 INFO - Running main action method: clobber 16:22:25 INFO - rmtree: /builds/slave/test-pgo/build 16:22:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:22:30 INFO - Running post-action listener: _resource_record_post_action 16:22:30 INFO - ##### 16:22:30 INFO - ##### Running read-buildbot-config step. 16:22:30 INFO - ##### 16:22:30 INFO - Running pre-action listener: _resource_record_pre_action 16:22:30 INFO - Running main action method: read_buildbot_config 16:22:30 INFO - Using buildbot properties: 16:22:30 INFO - { 16:22:30 INFO - "properties": { 16:22:30 INFO - "buildnumber": 73, 16:22:30 INFO - "product": "firefox", 16:22:30 INFO - "script_repo_revision": "production", 16:22:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 16:22:30 INFO - "repository": "", 16:22:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 16:22:30 INFO - "buildid": "20151125143004", 16:22:30 INFO - "slavename": "talos-linux64-ix-066", 16:22:30 INFO - "pgo_build": "True", 16:22:30 INFO - "basedir": "/builds/slave/test-pgo", 16:22:30 INFO - "project": "", 16:22:30 INFO - "platform": "ubuntu64_hw", 16:22:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:22:30 INFO - "slavebuilddir": "test", 16:22:30 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 16:22:30 INFO - "branch": "fx-team", 16:22:30 INFO - "repo_path": "integration/fx-team", 16:22:30 INFO - "stage_platform": "linux64-pgo", 16:22:30 INFO - "builduid": "0551c0ba97c042e384658b854b79a551", 16:22:30 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:30 INFO - }, 16:22:30 INFO - "sourcestamp": { 16:22:30 INFO - "repository": "", 16:22:30 INFO - "hasPatch": false, 16:22:30 INFO - "project": "", 16:22:30 INFO - "branch": "fx-team-linux64-pgo-talos", 16:22:30 INFO - "changes": [ 16:22:30 INFO - { 16:22:30 INFO - "category": null, 16:22:30 INFO - "files": [ 16:22:30 INFO - { 16:22:30 INFO - "url": null, 16:22:30 INFO - "name": "https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 16:22:30 INFO - } 16:22:30 INFO - ], 16:22:30 INFO - "repository": "", 16:22:30 INFO - "rev": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98", 16:22:30 INFO - "who": "sendchange", 16:22:30 INFO - "when": 1448497276, 16:22:30 INFO - "number": 6708453, 16:22:30 INFO - "comments": "", 16:22:30 INFO - "project": "", 16:22:30 INFO - "at": "Wed 25 Nov 2015 16:21:16", 16:22:30 INFO - "branch": "fx-team-linux64-pgo-talos", 16:22:30 INFO - "revlink": "", 16:22:30 INFO - "properties": [ 16:22:30 INFO - [ 16:22:30 INFO - "buildid", 16:22:30 INFO - "20151125143004", 16:22:30 INFO - "Change" 16:22:30 INFO - ], 16:22:30 INFO - [ 16:22:30 INFO - "builduid", 16:22:30 INFO - "0551c0ba97c042e384658b854b79a551", 16:22:30 INFO - "Change" 16:22:30 INFO - ], 16:22:30 INFO - [ 16:22:30 INFO - "pgo_build", 16:22:30 INFO - "True", 16:22:30 INFO - "Change" 16:22:30 INFO - ] 16:22:30 INFO - ], 16:22:30 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:30 INFO - } 16:22:30 INFO - ], 16:22:30 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:30 INFO - } 16:22:30 INFO - } 16:22:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 16:22:30 INFO - Running post-action listener: _resource_record_post_action 16:22:30 INFO - ##### 16:22:30 INFO - ##### Running download-and-extract step. 16:22:30 INFO - ##### 16:22:30 INFO - Running pre-action listener: _resource_record_pre_action 16:22:30 INFO - Running main action method: download_and_extract 16:22:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:30 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:22:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json 16:22:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json 16:22:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:22:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:22:32 INFO - Downloaded 1302 bytes. 16:22:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:22:32 INFO - Using the following test package requirements: 16:22:32 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:32 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:22:32 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'jsshell-linux-x86_64.zip'], 16:22:32 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:22:32 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:32 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:22:32 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:22:32 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:22:32 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:32 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:32 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:22:32 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:22:32 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')]} 16:22:32 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:22:34 INFO - Downloaded 22755164 bytes. 16:22:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:22:35 INFO - Return code: 0 16:22:35 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:35 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:22:40 INFO - Downloaded 22755164 bytes. 16:22:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:22:41 INFO - Return code: 0 16:22: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')]} 16:22:41 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:22:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:22:42 INFO - Downloaded 11131717 bytes. 16:22:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:22:42 INFO - Return code: 0 16:22: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')]} 16:22:42 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:22:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:22:43 INFO - Downloaded 58386647 bytes. 16:22:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:43 INFO - mkdir: /builds/slave/test-pgo/properties 16:22:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:22:43 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:22:43 INFO - Contents: 16:22:43 INFO - build_url:https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:43 INFO - Running post-action listener: _resource_record_post_action 16:22:43 INFO - Running post-action listener: set_extra_try_arguments 16:22:43 INFO - ##### 16:22:43 INFO - ##### Running populate-webroot step. 16:22:43 INFO - ##### 16:22:43 INFO - Running pre-action listener: _resource_record_pre_action 16:22:43 INFO - Running main action method: populate_webroot 16:22:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:22:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:22:43 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:22:43 INFO - '../fennec_ids.txt'], 16:22:43 INFO - 'tests': ['tcheck2']}, 16:22:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:22:43 INFO - '--tppagecycles', 16:22:43 INFO - '7'], 16:22:43 INFO - 'tests': ['tsvgm']}}, 16:22:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:22:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:22:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:22:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:22:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'tests': ['damp', 'tps']}, 16:22:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['damp', 'tps']}, 16:22:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:22:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:22:43 INFO - 'other': {'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'other_l64': {'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'other_nol64': {'tests': ['a11yr', 16:22:43 INFO - 'ts_paint', 16:22:43 INFO - 'tpaint', 16:22:43 INFO - 'sessionrestore', 16:22:43 INFO - 'sessionrestore_no_auto_restore']}, 16:22:43 INFO - 'svgr': {'tests': ['tsvgx', 16:22:43 INFO - 'tsvgr_opacity', 16:22:43 INFO - 'tart', 16:22:43 INFO - 'tscrollx', 16:22:43 INFO - 'cart']}, 16:22:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['tsvgx', 16:22:43 INFO - 'tsvgr_opacity', 16:22:43 INFO - 'tart', 16:22:43 INFO - 'tscrollx', 16:22:43 INFO - 'cart']}, 16:22:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'tests': ['tp5o']}, 16:22:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'talos_options': ['--e10s'], 16:22:43 INFO - 'tests': ['tp5o']}, 16:22:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'talos_options': ['--xperf_path', 16:22:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:43 INFO - 'tests': ['tp5n']}, 16:22:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:43 INFO - 'talos_options': ['--e10s', 16:22:43 INFO - '--xperf_path', 16:22:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:43 INFO - 'tests': ['tp5n']}}, 16:22:43 INFO - 'talos.zip': {'path': '', 16:22:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:22:43 INFO - Running post-action listener: _resource_record_post_action 16:22:43 INFO - ##### 16:22:43 INFO - ##### Running create-virtualenv step. 16:22:43 INFO - ##### 16:22:43 INFO - Running pre-action listener: _resource_record_pre_action 16:22:43 INFO - Running main action method: create_virtualenv 16:22:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:22:43 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 16:22:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:22:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:22:44 INFO - Using real prefix '/usr' 16:22:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:22:44 INFO - Installing distribute.............................................................................................................................................................................................done. 16:22:46 INFO - Installing pip.................done. 16:22:46 INFO - Return code: 0 16:22:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:22:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:22:46 INFO - Using env: {'DISPLAY': ':0', 16:22:46 INFO - 'HOME': '/home/cltbld', 16:22:46 INFO - 'LANG': 'en_US.UTF-8', 16:22:46 INFO - 'LANGUAGE': 'en_US:en', 16:22:46 INFO - 'LOGNAME': 'cltbld', 16:22:46 INFO - 'MAIL': '/var/mail/cltbld', 16:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:46 INFO - 'MOZ_NO_REMOTE': '1', 16:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:46 INFO - 'NO_EM_RESTART': '1', 16:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:47 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:47 INFO - 'SHELL': '/bin/bash', 16:22:47 INFO - 'SHLVL': '1', 16:22:47 INFO - 'TERM': 'linux', 16:22:47 INFO - 'TMOUT': '86400', 16:22:47 INFO - 'USER': 'cltbld', 16:22:47 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:47 INFO - '_': '/tools/buildbot/bin/python'} 16:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22: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 16:22:47 INFO - Cleaning up... 16:22:47 INFO - Return code: 0 16:22:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:22: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')]} 16:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:22:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:22:47 INFO - Using env: {'DISPLAY': ':0', 16:22:47 INFO - 'HOME': '/home/cltbld', 16:22:47 INFO - 'LANG': 'en_US.UTF-8', 16:22:47 INFO - 'LANGUAGE': 'en_US:en', 16:22:47 INFO - 'LOGNAME': 'cltbld', 16:22:47 INFO - 'MAIL': '/var/mail/cltbld', 16:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:47 INFO - 'MOZ_NO_REMOTE': '1', 16:22:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:47 INFO - 'NO_EM_RESTART': '1', 16:22:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:47 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:47 INFO - 'SHELL': '/bin/bash', 16:22:47 INFO - 'SHLVL': '1', 16:22:47 INFO - 'TERM': 'linux', 16:22:47 INFO - 'TMOUT': '86400', 16:22:47 INFO - 'USER': 'cltbld', 16:22:47 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:47 INFO - '_': '/tools/buildbot/bin/python'} 16:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:47 INFO - Downloading/unpacking psutil>=0.7.1 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:49 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:22:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:22:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:22:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:49 INFO - Installing collected packages: psutil 16:22:49 INFO - Running setup.py install for psutil 16:22:49 INFO - building 'psutil._psutil_linux' extension 16:22: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 16:22: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:22:51 INFO - building 'psutil._psutil_posix' extension 16:22:51 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 16:22:51 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 16:22:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:51 INFO - Successfully installed psutil 16:22:51 INFO - Cleaning up... 16:22:51 INFO - Return code: 0 16:22:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:22:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:22:51 INFO - Using env: {'DISPLAY': ':0', 16:22:51 INFO - 'HOME': '/home/cltbld', 16:22:51 INFO - 'LANG': 'en_US.UTF-8', 16:22:51 INFO - 'LANGUAGE': 'en_US:en', 16:22:51 INFO - 'LOGNAME': 'cltbld', 16:22:51 INFO - 'MAIL': '/var/mail/cltbld', 16:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:51 INFO - 'MOZ_NO_REMOTE': '1', 16:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:51 INFO - 'NO_EM_RESTART': '1', 16:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:51 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:51 INFO - 'SHELL': '/bin/bash', 16:22:51 INFO - 'SHLVL': '1', 16:22:51 INFO - 'TERM': 'linux', 16:22:51 INFO - 'TMOUT': '86400', 16:22:51 INFO - 'USER': 'cltbld', 16:22:51 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:51 INFO - '_': '/tools/buildbot/bin/python'} 16:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:22:51 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 16:22:51 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 16:22:51 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 16:22:51 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 16:22:51 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 16:22:51 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 16:22:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:22:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:22:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:22:53 INFO - Installing collected packages: mozsystemmonitor 16:22:53 INFO - Running setup.py install for mozsystemmonitor 16:22:53 INFO - Successfully installed mozsystemmonitor 16:22:53 INFO - Cleaning up... 16:22:53 INFO - Return code: 0 16:22:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:22:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:22:53 INFO - Using env: {'DISPLAY': ':0', 16:22:53 INFO - 'HOME': '/home/cltbld', 16:22:53 INFO - 'LANG': 'en_US.UTF-8', 16:22:53 INFO - 'LANGUAGE': 'en_US:en', 16:22:53 INFO - 'LOGNAME': 'cltbld', 16:22:53 INFO - 'MAIL': '/var/mail/cltbld', 16:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:53 INFO - 'MOZ_NO_REMOTE': '1', 16:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:53 INFO - 'NO_EM_RESTART': '1', 16:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:53 INFO - 'SHELL': '/bin/bash', 16:22:53 INFO - 'SHLVL': '1', 16:22:53 INFO - 'TERM': 'linux', 16:22:53 INFO - 'TMOUT': '86400', 16:22:53 INFO - 'USER': 'cltbld', 16:22:53 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:53 INFO - '_': '/tools/buildbot/bin/python'} 16:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:22:53 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 16:22:53 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 16:22:53 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 16:22:53 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 16:22:53 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 16:22:53 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 16:22:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:22:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:22:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:22:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:22:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:22:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 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 16:22:55 INFO - Downloading docopt-0.6.1.tar.gz 16:22:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:22:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:22:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:22:56 INFO - Running setup.py install for blobuploader 16:22:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:22:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:22:56 INFO - Running setup.py install for requests 16:22:56 INFO - Running setup.py install for docopt 16:22:56 INFO - Successfully installed blobuploader requests docopt 16:22:56 INFO - Cleaning up... 16:22:56 INFO - Return code: 0 16:22:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:22:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:22:56 INFO - Using env: {'DISPLAY': ':0', 16:22:56 INFO - 'HOME': '/home/cltbld', 16:22:56 INFO - 'LANG': 'en_US.UTF-8', 16:22:56 INFO - 'LANGUAGE': 'en_US:en', 16:22:56 INFO - 'LOGNAME': 'cltbld', 16:22:56 INFO - 'MAIL': '/var/mail/cltbld', 16:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:56 INFO - 'MOZ_NO_REMOTE': '1', 16:22:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:56 INFO - 'NO_EM_RESTART': '1', 16:22:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:56 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:56 INFO - 'SHELL': '/bin/bash', 16:22:56 INFO - 'SHLVL': '1', 16:22:56 INFO - 'TERM': 'linux', 16:22:56 INFO - 'TMOUT': '86400', 16:22:56 INFO - 'USER': 'cltbld', 16:22:56 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:56 INFO - '_': '/tools/buildbot/bin/python'} 16:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:22:56 INFO - Running setup.py (path:/tmp/pip-z8PfnO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:22:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:22:56 INFO - Running setup.py (path:/tmp/pip-d2fzfk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:22:57 INFO - Running setup.py (path:/tmp/pip-58CIdG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:22:57 INFO - Running setup.py (path:/tmp/pip-MMvUYF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:22:57 INFO - Running setup.py (path:/tmp/pip-YcC_cd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:22:57 INFO - Running setup.py (path:/tmp/pip-lLbEQ2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:22:57 INFO - Running setup.py (path:/tmp/pip-PVVqiu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:22:57 INFO - Running setup.py (path:/tmp/pip-YPExiH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:22:57 INFO - Running setup.py (path:/tmp/pip-za_Zrc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:22:57 INFO - Running setup.py (path:/tmp/pip-uNIjQ0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:22:57 INFO - Running setup.py (path:/tmp/pip-B1XpK7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:22:57 INFO - Running setup.py (path:/tmp/pip-G_Iet5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:22:57 INFO - Running setup.py (path:/tmp/pip-aMoDHh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:22:57 INFO - Running setup.py (path:/tmp/pip-v_osy6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:22:57 INFO - Running setup.py (path:/tmp/pip-ADIA6I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:22:57 INFO - Running setup.py (path:/tmp/pip-bR2pY4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:22:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:22:58 INFO - Running setup.py (path:/tmp/pip-qHzk9p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:22:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:22:58 INFO - Running setup.py install for manifestparser 16:22:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Running setup.py install for mozcrash 16:22:58 INFO - Running setup.py install for mozdebug 16:22:58 INFO - Running setup.py install for mozdevice 16:22:58 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Running setup.py install for mozfile 16:22:58 INFO - Running setup.py install for mozhttpd 16:22:58 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Running setup.py install for mozinfo 16:22:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Running setup.py install for mozInstall 16:22:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:22:58 INFO - Running setup.py install for mozleak 16:22:58 INFO - Running setup.py install for mozlog 16:22:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Running setup.py install for moznetwork 16:22:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Running setup.py install for mozprocess 16:22:59 INFO - Running setup.py install for mozprofile 16:22:59 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Running setup.py install for mozrunner 16:22:59 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Running setup.py install for mozscreenshot 16:22:59 INFO - Running setup.py install for moztest 16:22:59 INFO - Running setup.py install for mozversion 16:22:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:22:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:22:59 INFO - Cleaning up... 16:22:59 INFO - Return code: 0 16:22:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:22: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')]} 16:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:22:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:22:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:22:59 INFO - Using env: {'DISPLAY': ':0', 16:22:59 INFO - 'HOME': '/home/cltbld', 16:22:59 INFO - 'LANG': 'en_US.UTF-8', 16:22:59 INFO - 'LANGUAGE': 'en_US:en', 16:22:59 INFO - 'LOGNAME': 'cltbld', 16:22:59 INFO - 'MAIL': '/var/mail/cltbld', 16:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:59 INFO - 'MOZ_NO_REMOTE': '1', 16:22:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:59 INFO - 'NO_EM_RESTART': '1', 16:22:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:59 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:59 INFO - 'SHELL': '/bin/bash', 16:22:59 INFO - 'SHLVL': '1', 16:22:59 INFO - 'TERM': 'linux', 16:22:59 INFO - 'TMOUT': '86400', 16:22:59 INFO - 'USER': 'cltbld', 16:22:59 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:59 INFO - '_': '/tools/buildbot/bin/python'} 16:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:00 INFO - Running setup.py (path:/tmp/pip-1VF192-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:00 INFO - Running setup.py (path:/tmp/pip-luZ4mz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:00 INFO - Running setup.py (path:/tmp/pip-gTzicT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:00 INFO - Running setup.py (path:/tmp/pip-FShAHg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:00 INFO - Running setup.py (path:/tmp/pip-Qi4HLD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:00 INFO - Running setup.py (path:/tmp/pip-0YiRAR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:00 INFO - Running setup.py (path:/tmp/pip-iXwbGE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:00 INFO - Running setup.py (path:/tmp/pip-KCNNvh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:00 INFO - Running setup.py (path:/tmp/pip-MLg1mn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:00 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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23:00 INFO - Running setup.py (path:/tmp/pip-ZCJBoN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23: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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23:00 INFO - Running setup.py (path:/tmp/pip-RyIIMW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23: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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23:00 INFO - Running setup.py (path:/tmp/pip-9JXCpi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23: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)) 16:23:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:00 INFO - Running setup.py (path:/tmp/pip-vu_kxS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:01 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)) 16:23:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:01 INFO - Running setup.py (path:/tmp/pip-FU6L0E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:01 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)) 16:23:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:01 INFO - Running setup.py (path:/tmp/pip-vNBQHq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:01 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)) 16:23:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:01 INFO - Running setup.py (path:/tmp/pip-kRmGd9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:01 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)) 16:23:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:01 INFO - Running setup.py (path:/tmp/pip-HMx4zJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:01 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)) 16:23:01 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)) 16:23:01 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)) 16:23:01 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)) 16:23:01 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)) 16:23:01 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)) 16:23:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:02 INFO - Downloading blessings-1.5.1.tar.gz 16:23:02 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:23:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:23:03 INFO - Installing collected packages: blessings 16:23:03 INFO - Running setup.py install for blessings 16:23:03 INFO - Successfully installed blessings 16:23:03 INFO - Cleaning up... 16:23:03 INFO - Return code: 0 16:23:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:23:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:23:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:23:03 INFO - Reading from file tmpfile_stdout 16:23:03 INFO - Current package versions: 16:23:03 INFO - argparse == 1.2.1 16:23:03 INFO - blessings == 1.5.1 16:23:03 INFO - blobuploader == 1.2.4 16:23:03 INFO - docopt == 0.6.1 16:23:03 INFO - manifestparser == 1.1 16:23:03 INFO - mozInstall == 1.12 16:23:03 INFO - mozcrash == 0.16 16:23:03 INFO - mozdebug == 0.1 16:23:03 INFO - mozdevice == 0.47 16:23:03 INFO - mozfile == 1.2 16:23:03 INFO - mozhttpd == 0.7 16:23:03 INFO - mozinfo == 0.9 16:23:03 INFO - mozleak == 0.1 16:23:03 INFO - mozlog == 3.1 16:23:03 INFO - moznetwork == 0.27 16:23:03 INFO - mozprocess == 0.22 16:23:03 INFO - mozprofile == 0.27 16:23:03 INFO - mozrunner == 6.11 16:23:03 INFO - mozscreenshot == 0.1 16:23:03 INFO - mozsystemmonitor == 0.0 16:23:03 INFO - moztest == 0.7 16:23:03 INFO - mozversion == 1.4 16:23:03 INFO - psutil == 3.1.1 16:23:03 INFO - requests == 1.2.3 16:23:03 INFO - wsgiref == 0.1.2 16:23:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:23: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')]} 16:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:23:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 16:23:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:23:03 INFO - Using env: {'DISPLAY': ':0', 16:23:03 INFO - 'HOME': '/home/cltbld', 16:23:03 INFO - 'LANG': 'en_US.UTF-8', 16:23:03 INFO - 'LANGUAGE': 'en_US:en', 16:23:03 INFO - 'LOGNAME': 'cltbld', 16:23:03 INFO - 'MAIL': '/var/mail/cltbld', 16:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:03 INFO - 'MOZ_NO_REMOTE': '1', 16:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:03 INFO - 'NO_EM_RESTART': '1', 16:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:03 INFO - 'SHELL': '/bin/bash', 16:23:03 INFO - 'SHLVL': '1', 16:23:03 INFO - 'TERM': 'linux', 16:23:03 INFO - 'TMOUT': '86400', 16:23:03 INFO - 'USER': 'cltbld', 16:23:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:03 INFO - '_': '/tools/buildbot/bin/python'} 16:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23:03 INFO - Cleaning up... 16:23:03 INFO - Return code: 0 16:23:03 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:23: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')]} 16:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ece8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', '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 16:23:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:23:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:23:03 INFO - Using env: {'DISPLAY': ':0', 16:23:03 INFO - 'HOME': '/home/cltbld', 16:23:03 INFO - 'LANG': 'en_US.UTF-8', 16:23:03 INFO - 'LANGUAGE': 'en_US:en', 16:23:03 INFO - 'LOGNAME': 'cltbld', 16:23:03 INFO - 'MAIL': '/var/mail/cltbld', 16:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:03 INFO - 'MOZ_NO_REMOTE': '1', 16:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:03 INFO - 'NO_EM_RESTART': '1', 16:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:03 INFO - 'SHELL': '/bin/bash', 16:23:03 INFO - 'SHLVL': '1', 16:23:03 INFO - 'TERM': 'linux', 16:23:03 INFO - 'TMOUT': '86400', 16:23:03 INFO - 'USER': 'cltbld', 16:23:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:03 INFO - '_': '/tools/buildbot/bin/python'} 16:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:03 INFO - Downloading/unpacking jsonschema 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:23:05 INFO - Downloading/unpacking functools32 (from jsonschema) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:23:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:23:05 INFO - warning: no files found matching '*.txt' 16:23:05 INFO - no previously-included directories found matching 'build' 16:23:05 INFO - no previously-included directories found matching 'dist' 16:23:05 INFO - no previously-included directories found matching '.git*' 16:23:05 INFO - Installing collected packages: jsonschema, functools32 16:23:05 INFO - Running setup.py install for functools32 16:23:05 INFO - warning: no files found matching '*.txt' 16:23:05 INFO - no previously-included directories found matching 'build' 16:23:05 INFO - no previously-included directories found matching 'dist' 16:23:05 INFO - no previously-included directories found matching '.git*' 16:23:05 INFO - Successfully installed jsonschema functools32 16:23:05 INFO - Cleaning up... 16:23:06 INFO - Return code: 0 16:23:06 INFO - Running post-action listener: _resource_record_post_action 16:23:06 INFO - Running post-action listener: _start_resource_monitoring 16:23:06 INFO - Starting resource monitoring. 16:23:06 INFO - ##### 16:23:06 INFO - ##### Running install step. 16:23:06 INFO - ##### 16:23:06 INFO - Running pre-action listener: _resource_record_pre_action 16:23:06 INFO - Running main action method: install 16:23:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:23:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:23:06 INFO - Reading from file tmpfile_stdout 16:23:06 INFO - Detecting whether we're running mozinstall >=1.0... 16:23:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:23:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:23:06 INFO - Reading from file tmpfile_stdout 16:23:06 INFO - Output received: 16:23:06 INFO - Usage: mozinstall [options] installer 16:23:06 INFO - Options: 16:23:06 INFO - -h, --help show this help message and exit 16:23:06 INFO - -d DEST, --destination=DEST 16:23:06 INFO - Directory to install application into. [default: 16:23:06 INFO - "/builds/slave/test-pgo"] 16:23:06 INFO - --app=APP Application being installed. [default: firefox] 16:23:06 INFO - mkdir: /builds/slave/test-pgo/build/application 16:23:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 16:23:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 16:23:20 INFO - Reading from file tmpfile_stdout 16:23:20 INFO - Output received: 16:23:20 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:23:20 INFO - Running post-action listener: _resource_record_post_action 16:23:20 INFO - ##### 16:23:20 INFO - ##### Running run-tests step. 16:23:20 INFO - ##### 16:23:20 INFO - Running pre-action listener: _resource_record_pre_action 16:23:20 INFO - Running main action method: run_tests 16:23:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:23:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:23:20 INFO - Python 2.7.3 16:23:20 INFO - Return code: 0 16:23:20 INFO - grabbing minidump binary from tooltool 16:23:20 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')]} 16:23:20 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 0x202a640>, '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 0x202b540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202b9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:23:20 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 16:23:20 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 16:23:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:23:21 INFO - Return code: 0 16:23:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:23:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:23:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:23:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:23:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:23:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:23:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 16:23:21 INFO - Using env: {'DISPLAY': ':0', 16:23:21 INFO - 'HOME': '/home/cltbld', 16:23:21 INFO - 'LANG': 'en_US.UTF-8', 16:23:21 INFO - 'LANGUAGE': 'en_US:en', 16:23:21 INFO - 'LOGNAME': 'cltbld', 16:23:21 INFO - 'MAIL': '/var/mail/cltbld', 16:23:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:23:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:21 INFO - 'MOZ_NO_REMOTE': '1', 16:23:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:21 INFO - 'NO_EM_RESTART': '1', 16:23:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:21 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:23:21 INFO - 'SHELL': '/bin/bash', 16:23:21 INFO - 'SHLVL': '1', 16:23:21 INFO - 'TERM': 'linux', 16:23:21 INFO - 'TMOUT': '86400', 16:23:21 INFO - 'USER': 'cltbld', 16:23:21 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951', 16:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:21 INFO - '_': '/tools/buildbot/bin/python'} 16:23:21 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 16:23:21 INFO - mozversion INFO | application_buildid: 20151125143004 16:23:21 INFO - mozversion INFO | application_changeset: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 16:23:21 INFO - mozversion INFO | application_display_name: Nightly 16:23:21 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:23:21 INFO - mozversion INFO | application_name: Firefox 16:23:21 INFO - mozversion INFO | application_remotingname: firefox 16:23:21 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 16:23:21 INFO - mozversion INFO | application_vendor: Mozilla 16:23:21 INFO - mozversion INFO | application_version: 45.0a1 16:23:21 INFO - mozversion INFO | platform_buildid: 20151125143004 16:23:21 INFO - mozversion INFO | platform_changeset: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 16:23:21 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 16:23:21 INFO - mozversion INFO | platform_version: 45.0a1 16:23:21 INFO - 2015-11-25 16:23:21,361 DEBUG : using testdate: 1448497401 16:23:21 INFO - 2015-11-25 16:23:21,361 DEBUG : actual date: 1448497401 16:23:21 INFO - 2015-11-25 16:23:21,408 INFO : starting webserver on 'localhost:45436' 16:23:21 INFO - 2015-11-25 16:23:21,409 INFO : Starting test suite talos-linux64-ix-066.e 16:23:21 INFO - 2015-11-25 16:23:21,409 INFO : Starting test dromaeo_css 16:23:21 INFO - 2015-11-25 16:23:21,410 DEBUG : operating with platform_type : linux_ 16:23:21 INFO - 2015-11-25 16:23:21,410 INFO : Initialising browser for dromaeo_css test... 16:23:21 INFO - 2015-11-25 16:23:21,429 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2S6c5w/profile http://localhost:45436/getInfo.html 16:23:29 INFO - 2015-11-25 16:23:29,277 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:23:29 INFO - 2015-11-25 16:23:29,277 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:23:29 INFO - 2015-11-25 16:23:29,278 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:23:29 INFO - 2015-11-25 16:23:29,278 DEBUG : BROWSER_OUTPUT: __metrics 16:23:30 INFO - 2015-11-25 16:23:30,555 INFO : Browser initialized. 16:23:30 INFO - 2015-11-25 16:23:30,556 INFO : Running cycle 1/1 for dromaeo_css test... 16:23:30 INFO - 2015-11-25 16:23:30,556 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2S6c5w/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:23:42 INFO - 2015-11-25 16:23:42,329 DEBUG : BROWSER_OUTPUT: RSS: Main: 140431360 16:23:42 INFO - 2015-11-25 16:23:42,329 DEBUG : BROWSER_OUTPUT: 16:24:31 INFO - 2015-11-25 16:24:31,949 DEBUG : BROWSER_OUTPUT: 1448497471948 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] 16:26:41 INFO - 2015-11-25 16:26:41,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45436/tests/dromaeo/cssquery-ext.html) 16:26:42 INFO - 2015-11-25 16:26:42,469 DEBUG : BROWSER_OUTPUT: RSS: Main: 149463040 16:26:42 INFO - 2015-11-25 16:26:42,469 DEBUG : BROWSER_OUTPUT: 16:29:44 INFO - 2015-11-25 16:29:44,416 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-ext.html (next: http://localhost:45436/tests/dromaeo/cssquery-jquery.html) 16:29:44 INFO - 2015-11-25 16:29:44,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 142774272 16:29:44 INFO - 2015-11-25 16:29:44,997 DEBUG : BROWSER_OUTPUT: 16:32:46 INFO - 2015-11-25 16:32:46,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45436/tests/dromaeo/cssquery-mootools.html) 16:32:47 INFO - 2015-11-25 16:32:47,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 144744448 16:32:47 INFO - 2015-11-25 16:32:47,365 DEBUG : BROWSER_OUTPUT: 16:35:48 INFO - 2015-11-25 16:35:48,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45436/tests/dromaeo/cssquery-prototype.html) 16:35:49 INFO - 2015-11-25 16:35:49,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 141508608 16:35:49 INFO - 2015-11-25 16:35:49,566 DEBUG : BROWSER_OUTPUT: 16:38:51 INFO - 2015-11-25 16:38:51,960 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45436/tests/dromaeo/cssquery-yui.html) 16:38:52 INFO - 2015-11-25 16:38:52,561 DEBUG : BROWSER_OUTPUT: RSS: Main: 141885440 16:38:52 INFO - 2015-11-25 16:38:52,561 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,128 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/dromaeo/cssquery-yui.html (next: http://localhost:45436/tests/dromaeo/cssquery-dojo.html) 16:41:55 INFO - 2015-11-25 16:41:55,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 144838656 16:41:55 INFO - 2015-11-25 16:41:55,386 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,386 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:41:55 INFO - 2015-11-25 16:41:55,386 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:41:55 INFO - 2015-11-25 16:41:55,387 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:41:55 INFO - 2015-11-25 16:41:55,387 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:41:55 INFO - 2015-11-25 16:41:55,387 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3234.272288735034;3247.821369824662;3258.3809076275493;3308.156420112871;3312.569365982422;5253.763580638871;5381.0045141648825;5395.1637496188105;5417.187421886719;5481.780728770849;5302.337207849019;5305.575553955684;5381.515663590276;5415.566754659628;5458.6724796512235;5025.7487125643665;5096.0062787756215;5109.795608175687;5124.410692770323;5148.485151484859;5040.445550989388;5070.670406423589;5102.540771330579;5126.333576635032;5142.177251639739;4873.707577545341;4900.58345040672;4958.950410495885;5025.221090730906;5033.647644664875;5057.747112644353;5060.569851562622;5061.519155680205;5063.493650634944;5125.641205115643;4861.805527778884;5025.547700706954;5039.546440820343;5045.268436076785;5050.444451110412;5243.764030618631;5274.156135018378;5292.967871265105;5306.389765177016;5334.306540149794;6031;6033.637981721111;6041.516678665696;6046.2744470663365;6046.516278697738;5771.942280577183;5880.206172166745;6055.455009049207;6082.635041897501;6099.420555047263;5585.944140558583;5589.804356847531;5601.271834661508;5603.5237004854225;5607.186957891124;5975.43233392827;5991.910121348184;6041.577089603774;6082.148499210114;6087.178230938855;5831.854203644858;5841.386654401226;5866.325372582122;5871.442212989845;5894.8821023579285;5775.913361299584;5867.119932010232;5902.085176797517;5928.911066334009;5986.5210783137245;5828.679422631796;5859.355470898283;5889.263842019747;5915.497182739429;5934.703264836741;5916.2013128228;5916.585838991229;5943.64338139713;5947.137665038632;5983.132445795335;4447.421835161408;4520.141173177086;4568.588827005586;4581.587657110876;4621.745803980746;3967.8214480347874;4018.65841403484;4045.7167998239843;4079.02103495159;4083.6528895044253;5691.203231547586;5699.230603868425;5703.058995265817;5737.684427356536;5748.281464816898;5757.6833274169485;5867.325257595431;6033.3664965178905;6043.516518678495;6054.969725151346;5690.2887139107615;5753.971230143906;5795.536357091422;5828.154917536934;5846.035404158351;5275.419703832576;5280.155175171954;5282.260483532308;5282.735863206824;5285.524302812765;5969.343372229051;6101.145839582462;6112.663803490851;6139.907901381483;6142.078688196806;5971.28344598651;5975.282966044103;6048.6975651218145;6110.327863935053;6113.724882380215;6127.6936153193055;6145.938540614716;6149.44654981036;6160.7535698571555;6302.936970630419;5632;5737.942620573908;5844.766209351578;5991.101334799815;6046.879062418639;5956.374580668968;5967;6006.15913772081;6008.218931538872;6011.519078473799;5846.503047241032;5902.734376952962;5903.822885313448;5908.645481271137;5913.940860591339;5604.271444712324;5664.546836253172;5691.4877661010705;5732.512736417451;5734.139879018181;5714.05718056516;5745.224394706661;5769.913451298318;5777.104548794943;5789.913151302818;5662.801801936913;5880.059190529495;5886.293644762656;5924.170616113662;5924.377940316204;5636.661800291996;5683.119116536944;5781.508571771278;5785.537157027344;5832.9792286348875 16:41:55 INFO - 2015-11-25 16:41:55,387 DEBUG : BROWSER_OUTPUT: |1;ext.html;552.682207730555;597.7967491053042;600.3966014155773;603.0983679399297;616.2697203813478;38088.619113808854;38748;39033;39061.21877562447;39268;41654.16691666165;42246.15507689852;42317.15365692684;42339.36490952638;42582;8565.65737370506;8809.603567839462;8810.735677929672;8827;8862.689805856797;8634.309255259564;8687.174718401742;8733.301335893113;8753.212210901018;8781.604827782734;8753.781155471132;8797.164269394398;8808;8822.073682263366;8833.734987950373;8565.400421970464;8771.166739159802;8824.338174636863;8825.470471771714;8843.425177363482;8465.873011904827;8570.82858342836;8571.95714021426;8579.52812595307;8584.785380365542;40408.39387409191;40568.1886362274;40653.18693626111;40735;40939.7953010236;3188.2420566490127;3275.5086736989642;3281.323642497443;3326.911751494759;3857.9228415431535;30001.549976750368;30454.695453045406;30861.845690771628;30898;31576;3793.146542028049;3823.770573765555;3854.1520865409566;3855.9421608675893;3914.5106861642294;263775;264084;264414;264538;264933;49878;50582;57074.42925570691;58174.999999999156;59037;54415.72792136014;54631;54757.178642320396;55184;55430.44569554253;14632.561023169323;14644.707105858037;14659.413623455155;14726.116433014053;14938.327775249922;11150.21948463616;11184.44077796107;11217.102631789438;11268.774624507629;11343.943280283545;19303.42089737282;19668.508287292647;19845.30541431072;20015.69897956628;20114.19543218284;7499.587522686195;7866.72466463671;7913.366930645535;7925.841483170304;7934.246246606564;943.6697155995347;1002.9899701003043;1045.0124632222478;1062.9529913035653;1151.2229245259416;24297.635535466983;26325.473490530465;26905.461890762075;27793.861030694716;28820.567691484644;1001.1890368801294;1038.402918321968;1111.5767626573984;1113.1929351220463;1125.808612535871;14246.501372452121;14447.566573002827;14461.927690361481;14522.49171279;14692.632684182981;326723;330857;331312.8687131316;332062;334407;51464.742676287126;51570.74214628903;52199.47800522022;52228.47771522312;52240.738796306534;1329.5811819276896;1338.5850386380253;1350.108928107464;1355.200431745275;1356.3557310277736;27413.45173096487;27971.379884203576;28039;28409.431811363243;28475.715242847305;46214;46337.3049404266;46805.53194468148;46964;47098.52901470941;304401;304933;305972;306959;309449;31479.68520314859;31779.52330714995;31879.52180717245;32040;32504.674953250164;42393.21676722979;42792.358114627685;43656;43701.78149109234;43755.78122109369;33582;33717.157071073416;33828.83085584556;33907.83046084754;34810.65189348074;34106.48840267348;34292.48561271633;36063.63936360603;36473.08817279589;36899;35936.28127437489;36323.091922702144;36428.4535731959;36565.81717091398;36708.44937325889;30824.38351232918;32447.837760811046;32939.01182964515;33068.1732956678;33225;24515.754842451348;25049.749502504743;25084.62373064369;25669.61495577605;26489.257691334282 16:41:55 INFO - 2015-11-25 16:41:55,388 DEBUG : BROWSER_OUTPUT: |2;jquery.html;352.973526985476;356.4297124600639;356.7896878835611;357.5155664075178;361.5823723599242;16329.122150952644;17485;17686.64626707465;18232.81767182328;19251.03744812759;11423.885761142386;12810.67973300668;16640;16640.66718665626;16649.91675041626;4645.628349732022;4783.593394561466;4809.7835597398025;4826;4827.44484384295;4718.150732868083;4753.643476739258;4778.78495467703;4982.676126051813;4991.126552853236;4969.503049695038;4982.526659967315;5104.770285337168;5170.53465188133;5263.710495922723;5142.817152055049;5147.279380886679;5147.510986456281;5191.351081114861;5518.944810551883;4998.075356059105;5097.923531147036;5103.8468845934685;5107.1828507439;5114.28400023997;12260.386980650932;12649.683757906034;12719.427625756864;12750.426230819543;12813.679658008532;2037.3174986379581;2049.631066408046;2094.86383385081;2133.328001679466;2186.081845624841;9422.104900034486;9738.074882886114;9811.656592019317;9955.402675839401;10623.263828712626;1883.5761953560477;1911.1208843931809;1920.8847469151895;1955.4426988308264;2014.405750303664;57770.13344799831;58866.11700824491;63888.68055659692;65672.6716366415;69627;12515.374231288399;14869.033512821632;15394.384224631114;16032.19839008045;16100.8729388944;23745.7625423747;23847.403814904766;23862.88068559646;23894.402639934142;23971.640425393634;8767.430117042371;8783.253423459077;8975.326850486239;9179.403338782959;9195.678151264674;7785.299323060952;7787.766367008885;7796.84406311882;7801.6879324827505;7851.607419628996;7654.4259180561685;7782.066152061676;7787.844243115106;7821.530708157415;7871.803204919808;1789.633125209329;3047.5123980163066;3054.9847250763605;3087.6757940415932;3198.2643991881996;417.55738916748345;937.4796987671771;961.1589858873485;982.4105536678079;996.9501524923725;10344.948275258575;10597.31117477377;10673.198026653026;10737.89262107369;10946.507407166697;418.52497415433703;421.31746570555896;427.8481139195603;432.6776551469176;448.6343629941587;5912.615679980788;5992.280926288873;6022.879542409215;9578.377405468762;10053.748656283651;416528;421171;424587;425448;572082;76203;81317.59341203255;81547.5922620383;82563.58718206371;83015;1132.365875109941;1149.057772626455;1164.5632887667125;1164.6564263542446;1164.9650510484698;14857.40570377216;15348.155851428279;15383.76924346158;15417.537473875802;15863.365465381255;35247.471287931214;35257.82371088128;35581.64418355887;35633.82183089068;36809.81595092007;45499.54500454953;45832;46782.53217467918;47313.763431184;47550.28674569954;8924.152205540464;9094.136057074567;9524.428534287965;9658.613655453702;9724.75688107803;11225.550977961117;13540.526081587097;17399.217035233698;17741.201645926223;17809;13772.862271377158;14166.433342666178;14381.64045898861;14534.418623254951;14742.26288685583;13903.721925561635;14085.718285634024;14227.50203742864;14325.928370358499;14478.782818257945;14673.192974386511;14691.338889749773;14778;14873.107613542788;14970.251487425367;9323.766905827408;9374.718758437259;9439.905600943903;9455.338126331366;9554.235661146953;12298.569550065704;14273.928630357197;14466.638334041732;14906.478273260384;15438.691226175639 16:41:55 INFO - 2015-11-25 16:41:55,388 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1448.920309382984;1497.0718154744056;1597.4089586852865;1618.1181256215361;1734.9739753903687;7409.740659076933;7632.351250143731;7731.6907323707055;7763.805904852388;7881.408894332919;7708.807279818007;7819.33535649469;7846.411519136073;7854.8821767673535;7879.369650427966;2303.585354636169;2352.623580227164;2359.28041947206;2369.9763002369928;2377.7265614454386;2329.2080692564523;2333.3000099970027;2360.4688944987324;2375.774301441369;2389.450426401929;2319.7100362454694;2333.6499525071204;2339.8830058497006;2340.9882950585225;2369.537940101672;2279.931602051941;2292.954140917189;2321.6749655048307;2342.61346877765;2350.647402889563;2320.1183550250967;2364.2552595932225;2379.6668466414717;2393.9281821545383;2396.724376696685;7644;7681.539107653559;7693.115291741409;7703.807404814925;7770.067591889009;4633.374494443241;4741.786619602091;4746.810127594892;4771.6421268404665;4773.331733557305;3145.2294187923876;3238.627557830856;3248.7563432742404;3277.737780977516;3282.7373810095137;971.0338213477564;991.6529214774844;1012.7215015870621;1026.7741096958657;1044.2429238801883;10466.162706983423;10498.317609355367;10517.1060459861;10529.36823790575;10529.631462898838;4203.684723645739;4245.066085461193;4279.16556271524;4304.053108316228;4316.007318316805;8519.872201916976;8540.957295213484;8581.141885811368;8584.356173287028;8623.439476434012;4699;4699.577038066522;4707.411573553306;4712.929306060412;4721.961168542915;4274.529801721808;4306.784660766949;4326.783660816946;4348.913021739548;4352.434183556181;3627.6009638939695;3639.3994990826745;3679.632036796299;3684.152644891636;3686.6129056448685;1168.480026388266;1173.0322483950774;1173.747644256489;1177.3171560494893;1177.4819079604947;1058.6241884131023;1064.11678307005;1070.053003092269;1208.9516419343304;1344.7108871592654;7368.226336234726;7392.667329970164;7456.51532650376;7475.8504829903095;7476.439267054992;462.69693350855243;463.3304874456378;468.2227502346089;471.30011932280536;473.07749887719086;5662.094064949588;5718.857028574235;5741.425857414309;5766.904288185231;5807.041838096667;9360.719178424659;9446.29152813528;9464.526773661428;9476.431414115175;9601.951990240004;8964.506952117566;9160;9184.49485278316;9290.349675522648;9321.207697345802;7192.856142877114;7248.891266631004;7251.057362542836;7309.671064802096;7313.634318284171;6884.65576721172;6923.238443771282;7089.220185779664;7099.148102227766;7100.289971002859;7950.522968621901;8325.292350150305;8342.124076972073;8353.582320884054;8412.495250285003;8352.164783521599;8464.492130472192;8587.613557390057;8587.742367728979;8625.482471051757;6137.41694539018;6281.466075383643;6291.685415729286;6623.138991931018;6697.062411262525;7509.249075092447;7517.511361761468;7518.097828260644;7570;7574.697012119454;6952.721891124501;7103.964480177566;7178.389836863925;7275.163356213891;7306.123265208209;7043.401310888632;7087.787366379017;7118.679659415442;7173.1392232932385;7226.385757210696;6876.381125698711;7048.224695283618;7182.820429489305;7192.748253811092;7209.0988626421695;6422.389872962061;6439.356064393523;6442.130312407952;6453.128827608213;6464.191976003;6571.34286571339;6653.700583473658;6687.164104486939;6705.530612857248;6711.026901099411 16:41:55 INFO - 2015-11-25 16:41:55,391 DEBUG : BROWSER_OUTPUT: |4;prototype.html;307.5402273600967;309.4800734765594;324.1992279070694;326.0804531221954;335.635835118896;6258.812235632932;6328.303886572485;6358.45953093987;6358.650274234915;6487.3836985486305;6242.937570624304;6250.343713910042;6266.498680105592;6268.874622507558;6290.591111577755;4652.767361631922;4745.3593764841735;4753.073150735608;4756.191447453941;4759.286107083931;4632.305154226876;4657.953420465803;4676.532346765331;4759.190937540627;4764.094821983812;4612.962083531195;4622.653301002439;4626.514216007321;4630.513796051417;4675.485696573374;4664.067186562667;4667.906641867161;4672.299155126724;4679.064187162547;4680.742559159245;3977.8011099445202;4046.9190616187807;4056.1076563156057;4096.6313031826985;4106.568810274908;5937.168796368512;5942.316633587149;5954.583179177459;5954.910676339859;5959.046552551615;5502.394736578973;5505.476979686963;5513.531349835269;5514.6139770216105;5522.861928451821;6493.253275873288;6597.416620011163;6599.439047680953;6615.702293396761;6631.071649969008;4492.056668099703;4499.865004049884;4503.189425903336;4521.367008618796;4522.118186953546;6806.489513286523;6813.046173535709;6821.590704557742;6827;6890.655467226618;4511.323301504735;4551.977240113844;4608.6082682971655;4632.2356811126465;4633.258678611406;4611.515790841981;4620.44554653437;4675.742834144155;4686.5782079613;4694.976525117352;5489.643173193809;5490.478404551562;5490.725463726878;5493.835184944458;5500.202470641814;6592.670366481657;6693.531452798257;6702.664866756642;6702.932970670328;6705;1628.1045425016193;1640.7456844189169;1644.3176081926106;1657.0306370772992;1663.002198680782;1078.2075174746556;1088.8856670049688;1091.8689757229183;1111.349860331715;1115.1524841120645;6288.842778930563;6419.839504012437;6437.645929473831;6495.220573531112;6501.154849869581;6926.965365173242;6945.40964018054;6950.513464057467;6964.825879353057;6965.407940325028;6419.358064193637;6447.451966582893;6474.931636279961;6477.643729594917;6513.283538810727;3698.112453011258;3711.257748450321;3765.435184722259;3799.525059367579;3803.2393521295853;7187.928120718726;7221.747238846615;7261.782146535613;7262.600556969312;7279.59962202084;6978.511504194657;7078.973548835493;7149.92850071503;7151.964240178766;7167.534110282815;7084.43324534036;7137.92862071383;7146.964265178641;7156.427485801228;7186.6047367395295;6874.415674667709;6899.931000689929;6903.723851046103;6907.620080895398;6928.653567321513;6777.2883847195335;6906.585604863724;6911.308869113049;6916.481263905328;6921.446284297345;6986.720531178899;7136.143662760502;7142.321479459443;7163.8567228656175;7191.137063552407;4568.908621827479;4579.74811385377;4588.518205588499;4601;4601.539846015372;6878.759243426456;6898;6924.688389022405;6927.861442771217;6928.411085057826;6527.412532872065;6689.698963546554;6711.295313991961;6717.630530320879;6782.25395206517;6717.664116794043;6735.057092007025;6741.359570840762;6742.595444273163;6745.494087943326;6428.4857211423905;6488.286288508166;6502.057201705821;6598.60408375499;6610.570312929644;6527.836804079936;6531.477481801349;6578.5395022347975;6661.2006559213205;6701.1623547056615;6452.806415807533;6502.089707440868;6522.760675471645;6541.313162117909;6629.138212032405 16:41:55 INFO - 2015-11-25 16:41:55,391 DEBUG : BROWSER_OUTPUT: |5;yui.html;761.1095018827974;766.222284381353;783.0877028262075;827.6234313387409;840.7687885831396;13102.01734869885;13477.258750768704;13482.662933426656;13501.594952151427;13509.864901350997;12386.442610082546;12902.806457903116;12921.547745828899;12989.350532473385;13105.213687178775;780.0600276666618;787.6809891993752;788.03072221168;789.1319548496665;790.6046976511744;778.5173192620583;783.6551917156432;787.3661702329622;791.3827214772487;796.8804679298123;829.3738227638124;832.6086739232582;836.5817091454273;838.6435764799966;839.2991851803721;818.2717381530442;837.0457678246803;839.0770152831897;842.3471809347743;849.0193826130828;599.9340072592012;601.7623038899654;606.6299557270062;607.3076692570473;608.7504123309421;13082;13201.27392993385;13205.20768753878;13218.60344189676;13240.073194876362;11810.645680629508;12252.877471225263;12289.262644241284;12323.507059717691;12364.505419783198;9813.564116178792;10163.288569800117;10258.487075646188;10350.327228730108;10374.118199953013;3580.838862251179;3597.9820100899587;3629;3638.3450978823803;3740.2706472237924;41423.1715365694;42135.36796948048;43959;45288.77355613201;48274.517254827355;8799.108191738678;9563.90436095644;9986.101250887456;10019.699409017741;10226.233032522441;16981.575460613585;16988.320467181173;16992.875890513686;17004;17031.48905532836;7192.676329565182;7622.313991740659;7647.57938313387;7848.84302313962;7996.04047514302;4334.111507141029;4349.847755328548;4364.738115713068;4391.890202744957;4410.33844923264;3124.9843750781556;3308.7353011759483;3371.511130886008;3403.9659603404143;3421.828908554612;612.7518355066205;613.2487702564412;614.5790133758389;614.923134608174;622.3682961793783;537.9919301210485;581.7207740284687;612.8866159760458;664.4883439751364;699.5033526196356;8746.12538746133;9337.579808908615;9503.334766566273;9555.856662150074;9648.469334186546;668.358375959075;675.5743881354813;688.3151264491862;699.869710417682;704.7885634309789;3941.5905241966066;4224.746515209036;4259.084296876186;4285.571442855752;4329.675274354373;51463.22805157926;52149.7392513035;52493;52950.470495295325;56812;49597;50116.749416252685;50215.74892125516;50773.49226507688;50891.491085089416;43059.999999999374;46870;46925.5307446928;46927.530724693;47336;8269.131741167092;8439.493630382196;8598.269147122564;8634.266087382392;8651.95674021626;35418;35711.82144089263;35920;36129.27741445209;36557;41023.179536408505;41389;42780;44637.55362446334;45072;10018.549165287433;10161.136303414292;10753.301035432673;11176.944115279372;11338.489767960296;11481.598144064918;11585.189036767346;11634.956465656105;11664;11669.416529173337;10891.237613366988;11117.388543630179;11285.153613479186;11377.43112844371;11505.884941150816;10731.624393146203;10892.891071089187;12067.396630168632;12835.294058826854;12934.223946563236;11512;11822.34977076269;12235.571754988532;12244.448999794853;12300.892919637276;10211.795764084825;10395.94802025985;10679.626213082505;10714.464276786286;11206.103511719206;9359.719208423758;9703.563339649592;9797.706068817946;9958.55186516594;9963.800723985334 16:41:55 INFO - 2015-11-25 16:41:55,391 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:41:55 INFO - 2015-11-25 16:41:55,391 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,318 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498515378__endTimestamp 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5521.16 Median:5748.28 stddev:628.79 (10.9%) stddev-sans-first:604.59 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: Values: 3234.3 3247.8 3258.4 3308.2 3312.6 5253.8 5381.0 5395.2 5417.2 5481.8 5302.3 5305.6 5381.5 5415.6 5458.7 5025.7 5096.0 5109.8 5124.4 5148.5 5040.4 5070.7 5102.5 5126.3 5142.2 4873.7 4900.6 4959.0 5025.2 5033.6 5057.7 5060.6 5061.5 5063.5 5125.6 4861.8 5025.5 5039.5 5045.3 5050.4 5243.8 5274.2 5293.0 5306.4 5334.3 6031.0 6033.6 6041.5 6046.3 6046.5 5771.9 5880.2 6055.5 6082.6 6099.4 5585.9 5589.8 5601.3 5603.5 5607.2 5975.4 5991.9 6041.6 6082.1 6087.2 5831.9 5841.4 5866.3 5871.4 5894.9 5775.9 5867.1 5902.1 5928.9 5986.5 5828.7 5859.4 5889.3 5915.5 5934.7 5916.2 5916.6 5943.6 5947.1 5983.1 4447.4 4520.1 4568.6 4581.6 4621.7 3967.8 4018.7 4045.7 4079.0 4083.7 5691.2 5699.2 5703.1 5737.7 5748.3 5757.7 5867.3 6033.4 6043.5 6055.0 5690.3 5754.0 5795.5 5828.2 5846.0 5275.4 5280.2 5282.3 5282.7 5285.5 5969.3 6101.1 6112.7 6139.9 6142.1 5971.3 5975.3 6048.7 6110.3 6113.7 6127.7 6145.9 6149.4 6160.8 6302.9 5632.0 5737.9 5844.8 5991.1 6046.9 5956.4 5967.0 6006.2 6008.2 6011.5 5846.5 5902.7 5903.8 5908.6 5913.9 5604.3 5664.5 5691.5 5732.5 5734.1 5714.1 5745.2 5769.9 5777.1 5789.9 5662.8 5880.1 5886.3 5924.2 5924.4 5636.7 5683.1 5781.5 5785.5 5833.0 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,392 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46653.93 Median:35999.96 stddev:78936.28 (219.3%) stddev-sans-first:79081.83 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: Values: 552.7 597.8 600.4 603.1 616.3 38088.6 38748.0 39033.0 39061.2 39268.0 41654.2 42246.2 42317.2 42339.4 42582.0 8565.7 8809.6 8810.7 8827.0 8862.7 8634.3 8687.2 8733.3 8753.2 8781.6 8753.8 8797.2 8808.0 8822.1 8833.7 8565.4 8771.2 8824.3 8825.5 8843.4 8465.9 8570.8 8572.0 8579.5 8584.8 40408.4 40568.2 40653.2 40735.0 40939.8 3188.2 3275.5 3281.3 3326.9 3857.9 30001.5 30454.7 30861.8 30898.0 31576.0 3793.1 3823.8 3854.2 3855.9 3914.5 263775.0 264084.0 264414.0 264538.0 264933.0 49878.0 50582.0 57074.4 58175.0 59037.0 54415.7 54631.0 54757.2 55184.0 55430.4 14632.6 14644.7 14659.4 14726.1 14938.3 11150.2 11184.4 11217.1 11268.8 11343.9 19303.4 19668.5 19845.3 20015.7 20114.2 7499.6 7866.7 7913.4 7925.8 7934.2 943.7 1003.0 1045.0 1063.0 1151.2 24297.6 26325.5 26905.5 27793.9 28820.6 1001.2 1038.4 1111.6 1113.2 1125.8 14246.5 14447.6 14461.9 14522.5 14692.6 326723.0 330857.0 331312.9 332062.0 334407.0 51464.7 51570.7 52199.5 52228.5 52240.7 1329.6 1338.6 1350.1 1355.2 1356.4 27413.5 27971.4 28039.0 28409.4 28475.7 46214.0 46337.3 46805.5 46964.0 47098.5 304401.0 304933.0 305972.0 306959.0 309449.0 31479.7 31779.5 31879.5 32040.0 32504.7 42393.2 42792.4 43656.0 43701.8 43755.8 33582.0 33717.2 33828.8 33907.8 34810.7 34106.5 34292.5 36063.6 36473.1 36899.0 35936.3 36323.1 36428.5 36565.8 36708.4 30824.4 32447.8 32939.0 33068.2 33225.0 24515.8 25049.7 25084.6 25669.6 26489.3 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26587.90 Median:18585.70 stddev:74754.82 (402.2%) stddev-sans-first:74938.58 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: Values: 353.0 356.4 356.8 357.5 361.6 16329.1 17485.0 17686.6 18232.8 19251.0 11423.9 12810.7 16640.0 16640.7 16649.9 4645.6 4783.6 4809.8 4826.0 4827.4 4718.2 4753.6 4778.8 4982.7 4991.1 4969.5 4982.5 5104.8 5170.5 5263.7 5142.8 5147.3 5147.5 5191.4 5518.9 4998.1 5097.9 5103.8 5107.2 5114.3 12260.4 12649.7 12719.4 12750.4 12813.7 2037.3 2049.6 2094.9 2133.3 2186.1 9422.1 9738.1 9811.7 9955.4 10623.3 1883.6 1911.1 1920.9 1955.4 2014.4 57770.1 58866.1 63888.7 65672.7 69627.0 12515.4 14869.0 15394.4 16032.2 16100.9 23745.8 23847.4 23862.9 23894.4 23971.6 8767.4 8783.3 8975.3 9179.4 9195.7 7785.3 7787.8 7796.8 7801.7 7851.6 7654.4 7782.1 7787.8 7821.5 7871.8 1789.6 3047.5 3055.0 3087.7 3198.3 417.6 937.5 961.2 982.4 997.0 10344.9 10597.3 10673.2 10737.9 10946.5 418.5 421.3 427.8 432.7 448.6 5912.6 5992.3 6022.9 9578.4 10053.7 416528.0 421171.0 424587.0 425448.0 572082.0 76203.0 81317.6 81547.6 82563.6 83015.0 1132.4 1149.1 1164.6 1164.7 1165.0 14857.4 15348.2 15383.8 15417.5 15863.4 35247.5 35257.8 35581.6 35633.8 36809.8 45499.5 45832.0 46782.5 47313.8 47550.3 8924.2 9094.1 9524.4 9658.6 9724.8 11225.6 13540.5 17399.2 17741.2 17809.0 13772.9 14166.4 14381.6 14534.4 14742.3 13903.7 14085.7 14227.5 14325.9 14478.8 14673.2 14691.3 14778.0 14873.1 14970.3 9323.8 9374.7 9439.9 9455.3 9554.2 12298.6 14273.9 14466.6 14906.5 15438.7 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5435.99 Median:6357.04 stddev:2801.55 (44.1%) stddev-sans-first:2793.38 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: Values: 1448.9 1497.1 1597.4 1618.1 1735.0 7409.7 7632.4 7731.7 7763.8 7881.4 7708.8 7819.3 7846.4 7854.9 7879.4 2303.6 2352.6 2359.3 2370.0 2377.7 2329.2 2333.3 2360.5 2375.8 2389.5 2319.7 2333.6 2339.9 2341.0 2369.5 2279.9 2293.0 2321.7 2342.6 2350.6 2320.1 2364.3 2379.7 2393.9 2396.7 7644.0 7681.5 7693.1 7703.8 7770.1 4633.4 4741.8 4746.8 4771.6 4773.3 3145.2 3238.6 3248.8 3277.7 3282.7 971.0 991.7 1012.7 1026.8 1044.2 10466.2 10498.3 10517.1 10529.4 10529.6 4203.7 4245.1 4279.2 4304.1 4316.0 8519.9 8541.0 8581.1 8584.4 8623.4 4699.0 4699.6 4707.4 4712.9 4722.0 4274.5 4306.8 4326.8 4348.9 4352.4 3627.6 3639.4 3679.6 3684.2 3686.6 1168.5 1173.0 1173.7 1177.3 1177.5 1058.6 1064.1 1070.1 1209.0 1344.7 7368.2 7392.7 7456.5 7475.9 7476.4 462.7 463.3 468.2 471.3 473.1 5662.1 5718.9 5741.4 5766.9 5807.0 9360.7 9446.3 9464.5 9476.4 9602.0 8964.5 9160.0 9184.5 9290.3 9321.2 7192.9 7248.9 7251.1 7309.7 7313.6 6884.7 6923.2 7089.2 7099.1 7100.3 7950.5 8325.3 8342.1 8353.6 8412.5 8352.2 8464.5 8587.6 8587.7 8625.5 6137.4 6281.5 6291.7 6623.1 6697.1 7509.2 7517.5 7518.1 7570.0 7574.7 6952.7 7104.0 7178.4 7275.2 7306.1 7043.4 7087.8 7118.7 7173.1 7226.4 6876.4 7048.2 7182.8 7192.7 7209.1 6422.4 6439.4 6442.1 6453.1 6464.2 6571.3 6653.7 6687.2 6705.5 6711.0 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,394 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5551.92 Median:6419.60 stddev:1712.92 (26.7%) stddev-sans-first:1671.89 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: Values: 307.5 309.5 324.2 326.1 335.6 6258.8 6328.3 6358.5 6358.7 6487.4 6242.9 6250.3 6266.5 6268.9 6290.6 4652.8 4745.4 4753.1 4756.2 4759.3 4632.3 4658.0 4676.5 4759.2 4764.1 4613.0 4622.7 4626.5 4630.5 4675.5 4664.1 4667.9 4672.3 4679.1 4680.7 3977.8 4046.9 4056.1 4096.6 4106.6 5937.2 5942.3 5954.6 5954.9 5959.0 5502.4 5505.5 5513.5 5514.6 5522.9 6493.3 6597.4 6599.4 6615.7 6631.1 4492.1 4499.9 4503.2 4521.4 4522.1 6806.5 6813.0 6821.6 6827.0 6890.7 4511.3 4552.0 4608.6 4632.2 4633.3 4611.5 4620.4 4675.7 4686.6 4695.0 5489.6 5490.5 5490.7 5493.8 5500.2 6592.7 6693.5 6702.7 6702.9 6705.0 1628.1 1640.7 1644.3 1657.0 1663.0 1078.2 1088.9 1091.9 1111.3 1115.2 6288.8 6419.8 6437.6 6495.2 6501.2 6927.0 6945.4 6950.5 6964.8 6965.4 6419.4 6447.5 6474.9 6477.6 6513.3 3698.1 3711.3 3765.4 3799.5 3803.2 7187.9 7221.7 7261.8 7262.6 7279.6 6978.5 7079.0 7149.9 7152.0 7167.5 7084.4 7137.9 7147.0 7156.4 7186.6 6874.4 6899.9 6903.7 6907.6 6928.7 6777.3 6906.6 6911.3 6916.5 6921.4 6986.7 7136.1 7142.3 7163.9 7191.1 4568.9 4579.7 4588.5 4601.0 4601.5 6878.8 6898.0 6924.7 6927.9 6928.4 6527.4 6689.7 6711.3 6717.6 6782.3 6717.7 6735.1 6741.4 6742.6 6745.5 6428.5 6488.3 6502.1 6598.6 6610.6 6527.8 6531.5 6578.5 6661.2 6701.2 6452.8 6502.1 6522.8 6541.3 6629.1 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13526.11 Median:24175.45 stddev:15255.40 (63.1%) stddev-sans-first:15268.07 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: Values: 761.1 766.2 783.1 827.6 840.8 13102.0 13477.3 13482.7 13501.6 13509.9 12386.4 12902.8 12921.5 12989.4 13105.2 780.1 787.7 788.0 789.1 790.6 778.5 783.7 787.4 791.4 796.9 829.4 832.6 836.6 838.6 839.3 818.3 837.0 839.1 842.3 849.0 599.9 601.8 606.6 607.3 608.8 13082.0 13201.3 13205.2 13218.6 13240.1 11810.6 12252.9 12289.3 12323.5 12364.5 9813.6 10163.3 10258.5 10350.3 10374.1 3580.8 3598.0 3629.0 3638.3 3740.3 41423.2 42135.4 43959.0 45288.8 48274.5 8799.1 9563.9 9986.1 10019.7 10226.2 16981.6 16988.3 16992.9 17004.0 17031.5 7192.7 7622.3 7647.6 7848.8 7996.0 4334.1 4349.8 4364.7 4391.9 4410.3 3125.0 3308.7 3371.5 3404.0 3421.8 612.8 613.2 614.6 614.9 622.4 538.0 581.7 612.9 664.5 699.5 8746.1 9337.6 9503.3 9555.9 9648.5 668.4 675.6 688.3 699.9 704.8 3941.6 4224.7 4259.1 4285.6 4329.7 51463.2 52149.7 52493.0 52950.5 56812.0 49597.0 50116.7 50215.7 50773.5 50891.5 43060.0 46870.0 46925.5 46927.5 47336.0 8269.1 8439.5 8598.3 8634.3 8652.0 35418.0 35711.8 35920.0 36129.3 36557.0 41023.2 41389.0 42780.0 44637.6 45072.0 10018.5 10161.1 10753.3 11176.9 11338.5 11481.6 11585.2 11635.0 11664.0 11669.4 10891.2 11117.4 11285.2 11377.4 11505.9 10731.6 10892.9 12067.4 12835.3 12934.2 11512.0 11822.3 12235.6 12244.4 12300.9 10211.8 10395.9 10679.6 10714.5 11206.1 9359.7 9703.6 9797.7 9958.6 9963.8 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:41:55 INFO - 2015-11-25 16:41:55,396 DEBUG : BROWSER_OUTPUT: 16:41:56 INFO - 2015-11-25 16:41:56,083 INFO : Browser exited with error code: 0 16:41:56 INFO - 2015-11-25 16:41:56,099 INFO : Completed test dromaeo_css (00:18:34) 16:41:56 INFO - 2015-11-25 16:41:56,100 INFO : Starting test kraken 16:41:56 INFO - 2015-11-25 16:41:56,100 DEBUG : operating with platform_type : linux_ 16:41:56 INFO - 2015-11-25 16:41:56,100 INFO : Initialising browser for kraken test... 16:41:56 INFO - 2015-11-25 16:41:56,106 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUc3F6Y/profile http://localhost:45436/getInfo.html 16:42:00 INFO - 2015-11-25 16:42:00,465 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:42:00 INFO - 2015-11-25 16:42:00,465 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:42:00 INFO - 2015-11-25 16:42:00,465 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:42:00 INFO - 2015-11-25 16:42:00,465 DEBUG : BROWSER_OUTPUT: __metrics 16:42:01 INFO - 2015-11-25 16:42:01,511 INFO : Browser initialized. 16:42:01 INFO - 2015-11-25 16:42:01,512 INFO : Running cycle 1/1 for kraken test... 16:42:01 INFO - 2015-11-25 16:42:01,512 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUc3F6Y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:42:13 INFO - 2015-11-25 16:42:13,248 DEBUG : BROWSER_OUTPUT: RSS: Main: 139612160 16:42:13 INFO - 2015-11-25 16:42:13,248 DEBUG : BROWSER_OUTPUT: 16:42:15 INFO - 2015-11-25 16:42:15,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45436/tests/kraken/driver.html?testName=audio-beat-detection) 16:42:16 INFO - 2015-11-25 16:42:16,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 141340672 16:42:16 INFO - 2015-11-25 16:42:16,316 DEBUG : BROWSER_OUTPUT: 16:42:18 INFO - 2015-11-25 16:42:18,955 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45436/tests/kraken/driver.html?testName=audio-dft) 16:42:19 INFO - 2015-11-25 16:42:19,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 141135872 16:42:19 INFO - 2015-11-25 16:42:19,544 DEBUG : BROWSER_OUTPUT: 16:42:22 INFO - 2015-11-25 16:42:22,395 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45436/tests/kraken/driver.html?testName=audio-fft) 16:42:22 INFO - 2015-11-25 16:42:22,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 137101312 16:42:22 INFO - 2015-11-25 16:42:22,969 DEBUG : BROWSER_OUTPUT: 16:42:24 INFO - 2015-11-25 16:42:24,941 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45436/tests/kraken/driver.html?testName=audio-oscillator) 16:42:25 INFO - 2015-11-25 16:42:25,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 136433664 16:42:25 INFO - 2015-11-25 16:42:25,562 DEBUG : BROWSER_OUTPUT: 16:42:27 INFO - 2015-11-25 16:42:27,816 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45436/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:42:28 INFO - 2015-11-25 16:42:28,398 DEBUG : BROWSER_OUTPUT: RSS: Main: 137224192 16:42:28 INFO - 2015-11-25 16:42:28,399 DEBUG : BROWSER_OUTPUT: 16:42:31 INFO - 2015-11-25 16:42:31,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45436/tests/kraken/driver.html?testName=imaging-darkroom) 16:42:32 INFO - 2015-11-25 16:42:32,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 137547776 16:42:32 INFO - 2015-11-25 16:42:32,002 DEBUG : BROWSER_OUTPUT: 16:42:35 INFO - 2015-11-25 16:42:35,369 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45436/tests/kraken/driver.html?testName=imaging-desaturate) 16:42:35 INFO - 2015-11-25 16:42:35,954 DEBUG : BROWSER_OUTPUT: RSS: Main: 137891840 16:42:35 INFO - 2015-11-25 16:42:35,954 DEBUG : BROWSER_OUTPUT: 16:42:38 INFO - 2015-11-25 16:42:38,757 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45436/tests/kraken/driver.html?testName=json-parse-financial) 16:42:39 INFO - 2015-11-25 16:42:39,344 DEBUG : BROWSER_OUTPUT: RSS: Main: 138194944 16:42:39 INFO - 2015-11-25 16:42:39,344 DEBUG : BROWSER_OUTPUT: 16:42:40 INFO - 2015-11-25 16:42:40,932 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45436/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:42:41 INFO - 2015-11-25 16:42:41,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 138645504 16:42:41 INFO - 2015-11-25 16:42:41,513 DEBUG : BROWSER_OUTPUT: 16:42:43 INFO - 2015-11-25 16:42:43,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:42:43 INFO - 2015-11-25 16:42:43,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 137744384 16:42:43 INFO - 2015-11-25 16:42:43,956 DEBUG : BROWSER_OUTPUT: 16:42:46 INFO - 2015-11-25 16:42:46,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:42:46 INFO - 2015-11-25 16:42:46,642 DEBUG : BROWSER_OUTPUT: RSS: Main: 139780096 16:42:46 INFO - 2015-11-25 16:42:46,642 DEBUG : BROWSER_OUTPUT: 16:42:49 INFO - 2015-11-25 16:42:49,116 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:42:49 INFO - 2015-11-25 16:42:49,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 138895360 16:42:49 INFO - 2015-11-25 16:42:49,710 DEBUG : BROWSER_OUTPUT: 16:42:52 INFO - 2015-11-25 16:42:52,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:42:52 INFO - 2015-11-25 16:42:52,891 DEBUG : BROWSER_OUTPUT: RSS: Main: 137920512 16:42:52 INFO - 2015-11-25 16:42:52,891 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45436/tests/kraken/driver.html?testName=ai-astar) 16:42:54 INFO - 2015-11-25 16:42:54,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 138149888 16:42:54 INFO - 2015-11-25 16:42:54,796 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,798 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:42:54 INFO - 2015-11-25 16:42:54,798 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:42:54 INFO - 2015-11-25 16:42:54,798 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:42:54 INFO - 2015-11-25 16:42:54,798 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:42:54 INFO - 2015-11-25 16:42:54,800 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;108;110;110;110;123;110;109;110;109 16:42:54 INFO - 2015-11-25 16:42:54,800 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;131;149;167;126;154;148;135;170;124 16:42:54 INFO - 2015-11-25 16:42:54,800 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;161;161;161;160;160;164;178;162 16:42:54 INFO - 2015-11-25 16:42:54,800 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;72;72;72;72;156;74;72;72 16:42:54 INFO - 2015-11-25 16:42:54,800 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;149;93;93;93;148;93;93;135 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;111;110;110;109;110;111;110;110 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;147;148;146;148;148;147;146 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;99;97;95;96;96 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;62;62;62;62;63;65;62;65;59 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;72;71;71;70;72;71;71;71;73;71 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;87;85;95;97;86;87;86;86 16:42:54 INFO - 2015-11-25 16:42:54,801 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;121;119;130;124;134;121;133;119;117 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;154;146;164;149;156;150;155;151;150;151 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;68;67;69;67;69;68;68;66;66 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,706 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498574797__endTimestamp 16:42:54 INFO - 2015-11-25 16:42:54,802 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,806 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:42:54 INFO - 2015-11-25 16:42:54,806 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:42:54 INFO - 2015-11-25 16:42:54,806 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,806 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.30 Median:110.00 stddev:4.40 (4.0%) stddev-sans-first:4.56 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: Values: 114.0 108.0 110.0 110.0 110.0 123.0 110.0 109.0 110.0 109.0 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.00 Median:148.50 stddev:16.03 (10.8%) stddev-sans-first:17.00 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: Values: 146.0 131.0 149.0 167.0 126.0 154.0 148.0 135.0 170.0 124.0 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.10 Median:161.50 stddev:5.38 (3.3%) stddev-sans-first:5.71 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 161.0 161.0 161.0 160.0 160.0 164.0 178.0 162.0 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,807 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.60 Median:72.00 stddev:26.50 (36.8%) stddev-sans-first:27.92 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 72.0 72.0 72.0 72.0 156.0 74.0 72.0 72.0 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.50 Median:93.00 stddev:24.78 (26.6%) stddev-sans-first:25.72 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 149.0 93.0 93.0 93.0 148.0 93.0 93.0 135.0 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 111.0 110.0 110.0 109.0 110.0 111.0 110.0 110.0 16:42:54 INFO - 2015-11-25 16:42:54,808 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 147.0 148.0 146.0 148.0 148.0 147.0 146.0 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:1.14 (1.2%) stddev-sans-first:1.20 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 99.0 97.0 95.0 96.0 96.0 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,809 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.50 stddev:1.72 (2.7%) stddev-sans-first:1.81 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 62.0 62.0 62.0 63.0 65.0 62.0 65.0 59.0 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 70.0 72.0 71.0 71.0 71.0 73.0 71.0 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:86.50 stddev:4.29 (5.0%) stddev-sans-first:4.42 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 87.0 85.0 95.0 97.0 86.0 87.0 86.0 86.0 16:42:54 INFO - 2015-11-25 16:42:54,810 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:124.50 stddev:6.09 (4.9%) stddev-sans-first:6.46 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: Values: 125.0 121.0 119.0 130.0 124.0 134.0 121.0 133.0 119.0 117.0 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.60 Median:152.50 stddev:4.99 (3.3%) stddev-sans-first:5.27 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: Values: 154.0 146.0 164.0 149.0 156.0 150.0 155.0 151.0 150.0 151.0 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.13 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 67.0 69.0 67.0 69.0 68.0 68.0 66.0 66.0 16:42:54 INFO - 2015-11-25 16:42:54,811 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:42:54 INFO - 2015-11-25 16:42:54,812 DEBUG : BROWSER_OUTPUT: 16:42:55 INFO - 2015-11-25 16:42:55,631 INFO : Browser exited with error code: 0 16:42:55 INFO - 2015-11-25 16:42:55,681 INFO : Completed test kraken (00:00:59) 16:42:55 INFO - 2015-11-25 16:42:55,681 INFO : Starting test v8_7 16:42:55 INFO - 2015-11-25 16:42:55,681 DEBUG : operating with platform_type : linux_ 16:42:55 INFO - 2015-11-25 16:42:55,681 INFO : Initialising browser for v8_7 test... 16:42:55 INFO - 2015-11-25 16:42:55,688 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8v1sR6/profile http://localhost:45436/getInfo.html 16:42:59 INFO - 2015-11-25 16:42:59,929 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:42:59 INFO - 2015-11-25 16:42:59,929 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:42:59 INFO - 2015-11-25 16:42:59,929 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:42:59 INFO - 2015-11-25 16:42:59,929 DEBUG : BROWSER_OUTPUT: __metrics 16:43:00 INFO - 2015-11-25 16:43:00,883 INFO : Browser initialized. 16:43:00 INFO - 2015-11-25 16:43:00,884 INFO : Running cycle 1/1 for v8_7 test... 16:43:00 INFO - 2015-11-25 16:43:00,884 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8v1sR6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:43:12 INFO - 2015-11-25 16:43:12,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 138772480 16:43:12 INFO - 2015-11-25 16:43:12,614 DEBUG : BROWSER_OUTPUT: 16:43:33 INFO - 2015-11-25 16:43:33,967 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45436/tests/v8_7/run.html (next: http://localhost:45436/tests/v8_7/run.html) 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: RSS: Main: 137515008 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:43:34 INFO - 2015-11-25 16:43:34,213 DEBUG : BROWSER_OUTPUT: |0;Richards;164.257555847569 16:43:34 INFO - 2015-11-25 16:43:34,214 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.2740626207963 16:43:34 INFO - 2015-11-25 16:43:34,214 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.3476310625175 16:43:34 INFO - 2015-11-25 16:43:34,214 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 16:43:34 INFO - 2015-11-25 16:43:34,214 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1074.0343347639484 16:43:34 INFO - 2015-11-25 16:43:34,214 DEBUG : BROWSER_OUTPUT: |5;Earley;703.9381153305204 16:43:34 INFO - 2015-11-25 16:43:34,215 DEBUG : BROWSER_OUTPUT: |6;Boyer;12156.626506024097 16:43:34 INFO - 2015-11-25 16:43:34,215 DEBUG : BROWSER_OUTPUT: |7;RegExp;24829.268292682926 16:43:34 INFO - 2015-11-25 16:43:34,215 DEBUG : BROWSER_OUTPUT: |8;Splay;630.9148264984227 16:43:34 INFO - 2015-11-25 16:43:34,215 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 16:43:34 INFO - 2015-11-25 16:43:34,215 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498614212__endTimestamp 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,216 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.26 Median:164.26 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: Values: 164.3 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.27 Median:193.27 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: Values: 193.3 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,217 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.35 Median:280.35 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,218 DEBUG : BROWSER_OUTPUT: Values: 280.3 16:43:34 INFO - 2015-11-25 16:43:34,218 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,218 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,219 DEBUG : BROWSER_OUTPUT: Values: 5138.5 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1074.03 Median:1074.03 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: Values: 1074.0 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:703.94 Median:703.94 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: Values: 703.9 16:43:34 INFO - 2015-11-25 16:43:34,220 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12156.63 Median:12156.63 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: Values: 12156.6 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24829.27 Median:24829.27 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: Values: 24829.3 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,221 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:630.91 Median:630.91 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: Values: 630.9 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: Values: 4835.7 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:43:34 INFO - 2015-11-25 16:43:34,222 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,971 INFO : Browser exited with error code: 0 16:43:34 INFO - 2015-11-25 16:43:34,990 INFO : Completed test v8_7 (00:00:39) 16:43:35 INFO - 2015-11-25 16:43:35,278 INFO : Completed test suite (00:20:13) 16:43:35 INFO - 2015-11-25 16:43:35,278 DEBUG : Working with test: dromaeo_css 16:43:35 INFO - 2015-11-25 16:43:35,278 DEBUG : Generating results file: dromaeo_css 16:43:35 INFO - 2015-11-25 16:43:35,279 DEBUG : Working with test: kraken 16:43:35 INFO - 2015-11-25 16:43:35,279 DEBUG : Generating results file: kraken 16:43:35 INFO - 2015-11-25 16:43:35,280 INFO : javascript benchmark 16:43:35 INFO - 2015-11-25 16:43:35,280 DEBUG : Working with test: v8_7 16:43:35 INFO - 2015-11-25 16:43:35,280 DEBUG : Generating results file: v8_7 16:43:35 INFO - 2015-11-25 16:43:35,280 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,333 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 16:43:35 INFO - 2015-11-25 16:43:35,333 DEBUG : process_Request line: dromaeo_css 7047.89 graph.html#tests=[[72,64,43]] 16:43:35 INFO - 2015-11-25 16:43:35,334 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,364 DEBUG : process_Request line: kraken 1528.10 graph.html#tests=[[232,64,43]] 16:43:35 INFO - 2015-11-25 16:43:35,364 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,398 DEBUG : process_Request line: v8_7 21337.48 graph.html#tests=[[230,64,43]] 16:43:35 INFO - 2015-11-25 16:43:35,399 INFO : javascript benchmark 16:43:35 INFO - 2015-11-25 16:43:35,403 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3234.272288735034, 3247.821369824662, 3258.3809076275493, 3308.156420112871, 3312.569365982422, 5253.763580638871, 5381.0045141648825, 5395.1637496188105, 5417.187421886719, 5481.780728770849, 5302.337207849019, 5305.575553955684, 5381.515663590276, 5415.566754659628, 5458.6724796512235, 5025.7487125643665, 5096.0062787756215, 5109.795608175687, 5124.410692770323, 5148.485151484859, 5040.445550989388, 5070.670406423589, 5102.540771330579, 5126.333576635032, 5142.177251639739, 4873.707577545341, 4900.58345040672, 4958.950410495885, 5025.221090730906, 5033.647644664875, 5057.747112644353, 5060.569851562622, 5061.519155680205, 5063.493650634944, 5125.641205115643, 4861.805527778884, 5025.547700706954, 5039.546440820343, 5045.268436076785, 5050.444451110412, 5243.764030618631, 5274.156135018378, 5292.967871265105, 5306.389765177016, 5334.306540149794, 6031.0, 6033.637981721111, 6041.516678665696, 6046.2744470663365, 6046.516278697738, 5771.942280577183, 5880.206172166745, 6055.455009049207, 6082.635041897501, 6099.420555047263, 5585.944140558583, 5589.804356847531, 5601.271834661508, 5603.5237004854225, 5607.186957891124, 5975.43233392827, 5991.910121348184, 6041.577089603774, 6082.148499210114, 6087.178230938855, 5831.854203644858, 5841.386654401226, 5866.325372582122, 5871.442212989845, 5894.8821023579285, 5775.913361299584, 5867.119932010232, 5902.085176797517, 5928.911066334009, 5986.5210783137245, 5828.679422631796, 5859.355470898283, 5889.263842019747, 5915.497182739429, 5934.703264836741, 5916.2013128228, 5916.585838991229, 5943.64338139713, 5947.137665038632, 5983.132445795335, 4447.421835161408, 4520.141173177086, 4568.588827005586, 4581.587657110876, 4621.745803980746, 3967.8214480347874, 4018.65841403484, 4045.7167998239843, 4079.02103495159, 4083.6528895044253, 5691.203231547586, 5699.230603868425, 5703.058995265817, 5737.684427356536, 5748.281464816898, 5757.6833274169485, 5867.325257595431, 6033.3664965178905, 6043.516518678495, 6054.969725151346, 5690.2887139107615, 5753.971230143906, 5795.536357091422, 5828.154917536934, 5846.035404158351, 5275.419703832576, 5280.155175171954, 5282.260483532308, 5282.735863206824, 5285.524302812765, 5969.343372229051, 6101.145839582462, 6112.663803490851, 6139.907901381483, 6142.078688196806, 5971.28344598651, 5975.282966044103, 6048.6975651218145, 6110.327863935053, 6113.724882380215, 6127.6936153193055, 6145.938540614716, 6149.44654981036, 6160.7535698571555, 6302.936970630419, 5632.0, 5737.942620573908, 5844.766209351578, 5991.101334799815, 6046.879062418639, 5956.374580668968, 5967.0, 6006.15913772081, 6008.218931538872, 6011.519078473799, 5846.503047241032, 5902.734376952962, 5903.822885313448, 5908.645481271137, 5913.940860591339, 5604.271444712324, 5664.546836253172, 5691.4877661010705, 5732.512736417451, 5734.139879018181, 5714.05718056516, 5745.224394706661, 5769.913451298318, 5777.104548794943, 5789.913151302818, 5662.801801936913, 5880.059190529495, 5886.293644762656, 5924.170616113662, 5924.377940316204, 5636.661800291996, 5683.119116536944, 5781.508571771278, 5785.537157027344, 5832.9792286348875], "unit": "ms", "name": "dojo.html", "value": 5479.166825298459}, {"lowerIsBetter": false, "replicates": [552.682207730555, 597.7967491053042, 600.3966014155773, 603.0983679399297, 616.2697203813478, 38088.619113808854, 38748.0, 39033.0, 39061.21877562447, 39268.0, 41654.16691666165, 42246.15507689852, 42317.15365692684, 42339.36490952638, 42582.0, 8565.65737370506, 8809.603567839462, 8810.735677929672, 8827.0, 8862.689805856797, 8634.309255259564, 8687.174718401742, 8733.301335893113, 8753.212210901018, 8781.604827782734, 8753.781155471132, 8797.164269394398, 8808.0, 8822.073682263366, 8833.734987950373, 8565.400421970464, 8771.166739159802, 8824.338174636863, 8825.470471771714, 8843.425177363482, 8465.873011904827, 8570.82858342836, 8571.95714021426, 8579.52812595307, 8584.785380365542, 40408.39387409191, 40568.1886362274, 40653.18693626111, 40735.0, 40939.7953010236, 3188.2420566490127, 3275.5086736989642, 3281.323642497443, 3326.911751494759, 3857.9228415431535, 30001.549976750368, 30454.695453045406, 30861.845690771628, 30898.0, 31576.0, 3793.146542028049, 3823.770573765555, 3854.1520865409566, 3855.9421608675893, 3914.5106861642294, 263775.0, 264084.0, 264414.0, 264538.0, 264933.0, 49878.0, 50582.0, 57074.42925570691, 58174.999999999156, 59037.0, 54415.72792136014, 54631.0, 54757.178642320396, 55184.0, 55430.44569554253, 14632.561023169323, 14644.707105858037, 14659.413623455155, 14726.116433014053, 14938.327775249922, 11150.21948463616, 11184.44077796107, 11217.102631789438, 11268.774624507629, 11343.943280283545, 19303.42089737282, 19668.508287292647, 19845.30541431072, 20015.69897956628, 20114.19543218284, 7499.587522686195, 7866.72466463671, 7913.366930645535, 7925.841483170304, 7934.246246606564, 943.6697155995347, 1002.9899701003043, 1045.0124632222478, 1062.9529913035653, 1151.2229245259416, 24297.635535466983, 26325.473490530465, 26905.461890762075, 27793.861030694716, 28820.567691484644, 1001.1890368801294, 1038.402918321968, 1111.5767626573984, 1113.1929351220463, 1125.808612535871, 14246.501372452121, 14447.566573002827, 14461.927690361481, 14522.49171279, 14692.632684182981, 326723.0, 330857.0, 331312.8687131316, 332062.0, 334407.0, 51464.742676287126, 51570.74214628903, 52199.47800522022, 52228.47771522312, 52240.738796306534, 1329.5811819276896, 1338.5850386380253, 1350.108928107464, 1355.200431745275, 1356.3557310277736, 27413.45173096487, 27971.379884203576, 28039.0, 28409.431811363243, 28475.715242847305, 46214.0, 46337.3049404266, 46805.53194468148, 46964.0, 47098.52901470941, 304401.0, 304933.0, 305972.0, 306959.0, 309449.0, 31479.68520314859, 31779.52330714995, 31879.52180717245, 32040.0, 32504.674953250164, 42393.21676722979, 42792.358114627685, 43656.0, 43701.78149109234, 43755.78122109369, 33582.0, 33717.157071073416, 33828.83085584556, 33907.83046084754, 34810.65189348074, 34106.48840267348, 34292.48561271633, 36063.63936360603, 36473.08817279589, 36899.0, 35936.28127437489, 36323.091922702144, 36428.4535731959, 36565.81717091398, 36708.44937325889, 30824.38351232918, 32447.837760811046, 32939.01182964515, 33068.1732956678, 33225.0, 24515.754842451348, 25049.749502504743, 25084.62373064369, 25669.61495577605, 26489.257691334282], "unit": "ms", "name": "ext.html", "value": 18387.65444665566}, {"lowerIsBetter": false, "replicates": [352.973526985476, 356.4297124600639, 356.7896878835611, 357.5155664075178, 361.5823723599242, 16329.122150952644, 17485.0, 17686.64626707465, 18232.81767182328, 19251.03744812759, 11423.885761142386, 12810.67973300668, 16640.0, 16640.66718665626, 16649.91675041626, 4645.628349732022, 4783.593394561466, 4809.7835597398025, 4826.0, 4827.44484384295, 4718.150732868083, 4753.643476739258, 4778.78495467703, 4982.676126051813, 4991.126552853236, 4969.503049695038, 4982.526659967315, 5104.770285337168, 5170.53465188133, 5263.710495922723, 5142.817152055049, 5147.279380886679, 5147.510986456281, 5191.351081114861, 5518.944810551883, 4998.075356059105, 5097.923531147036, 5103.8468845934685, 5107.1828507439, 5114.28400023997, 12260.386980650932, 12649.683757906034, 12719.427625756864, 12750.426230819543, 12813.679658008532, 2037.3174986379581, 2049.631066408046, 2094.86383385081, 2133.328001679466, 2186.081845624841, 9422.104900034486, 9738.074882886114, 9811.656592019317, 9955.402675839401, 10623.263828712626, 1883.5761953560477, 1911.1208843931809, 1920.8847469151895, 1955.4426988308264, 2014.405750303664, 57770.13344799831, 58866.11700824491, 63888.68055659692, 65672.6716366415, 69627.0, 12515.374231288399, 14869.033512821632, 15394.384224631114, 16032.19839008045, 16100.8729388944, 23745.7625423747, 23847.403814904766, 23862.88068559646, 23894.402639934142, 23971.640425393634, 8767.430117042371, 8783.253423459077, 8975.326850486239, 9179.403338782959, 9195.678151264674, 7785.299323060952, 7787.766367008885, 7796.84406311882, 7801.6879324827505, 7851.607419628996, 7654.4259180561685, 7782.066152061676, 7787.844243115106, 7821.530708157415, 7871.803204919808, 1789.633125209329, 3047.5123980163066, 3054.9847250763605, 3087.6757940415932, 3198.2643991881996, 417.55738916748345, 937.4796987671771, 961.1589858873485, 982.4105536678079, 996.9501524923725, 10344.948275258575, 10597.31117477377, 10673.198026653026, 10737.89262107369, 10946.507407166697, 418.52497415433703, 421.31746570555896, 427.8481139195603, 432.6776551469176, 448.6343629941587, 5912.615679980788, 5992.280926288873, 6022.879542409215, 9578.377405468762, 10053.748656283651, 416528.0, 421171.0, 424587.0, 425448.0, 572082.0, 76203.0, 81317.59341203255, 81547.5922620383, 82563.58718206371, 83015.0, 1132.365875109941, 1149.057772626455, 1164.5632887667125, 1164.6564263542446, 1164.9650510484698, 14857.40570377216, 15348.155851428279, 15383.76924346158, 15417.537473875802, 15863.365465381255, 35247.471287931214, 35257.82371088128, 35581.64418355887, 35633.82183089068, 36809.81595092007, 45499.54500454953, 45832.0, 46782.53217467918, 47313.763431184, 47550.28674569954, 8924.152205540464, 9094.136057074567, 9524.428534287965, 9658.613655453702, 9724.75688107803, 11225.550977961117, 13540.526081587097, 17399.217035233698, 17741.201645926223, 17809.0, 13772.862271377158, 14166.433342666178, 14381.64045898861, 14534.418623254951, 14742.26288685583, 13903.721925561635, 14085.718285634024, 14227.50203742864, 14325.928370358499, 14478.782818257945, 14673.192974386511, 14691.338889749773, 14778.0, 14873.107613542788, 14970.251487425367, 9323.766905827408, 9374.718758437259, 9439.905600943903, 9455.338126331366, 9554.235661146953, 12298.569550065704, 14273.928630357197, 14466.638334041732, 14906.478273260384, 15438.691226175639], "unit": "ms", "name": "jquery.html", "value": 8799.63859661252}, {"lowerIsBetter": false, "replicates": [1448.920309382984, 1497.0718154744056, 1597.4089586852865, 1618.1181256215361, 1734.9739753903687, 7409.740659076933, 7632.351250143731, 7731.6907323707055, 7763.805904852388, 7881.408894332919, 7708.807279818007, 7819.33535649469, 7846.411519136073, 7854.8821767673535, 7879.369650427966, 2303.585354636169, 2352.623580227164, 2359.28041947206, 2369.9763002369928, 2377.7265614454386, 2329.2080692564523, 2333.3000099970027, 2360.4688944987324, 2375.774301441369, 2389.450426401929, 2319.7100362454694, 2333.6499525071204, 2339.8830058497006, 2340.9882950585225, 2369.537940101672, 2279.931602051941, 2292.954140917189, 2321.6749655048307, 2342.61346877765, 2350.647402889563, 2320.1183550250967, 2364.2552595932225, 2379.6668466414717, 2393.9281821545383, 2396.724376696685, 7644.0, 7681.539107653559, 7693.115291741409, 7703.807404814925, 7770.067591889009, 4633.374494443241, 4741.786619602091, 4746.810127594892, 4771.6421268404665, 4773.331733557305, 3145.2294187923876, 3238.627557830856, 3248.7563432742404, 3277.737780977516, 3282.7373810095137, 971.0338213477564, 991.6529214774844, 1012.7215015870621, 1026.7741096958657, 1044.2429238801883, 10466.162706983423, 10498.317609355367, 10517.1060459861, 10529.36823790575, 10529.631462898838, 4203.684723645739, 4245.066085461193, 4279.16556271524, 4304.053108316228, 4316.007318316805, 8519.872201916976, 8540.957295213484, 8581.141885811368, 8584.356173287028, 8623.439476434012, 4699.0, 4699.577038066522, 4707.411573553306, 4712.929306060412, 4721.961168542915, 4274.529801721808, 4306.784660766949, 4326.783660816946, 4348.913021739548, 4352.434183556181, 3627.6009638939695, 3639.3994990826745, 3679.632036796299, 3684.152644891636, 3686.6129056448685, 1168.480026388266, 1173.0322483950774, 1173.747644256489, 1177.3171560494893, 1177.4819079604947, 1058.6241884131023, 1064.11678307005, 1070.053003092269, 1208.9516419343304, 1344.7108871592654, 7368.226336234726, 7392.667329970164, 7456.51532650376, 7475.8504829903095, 7476.439267054992, 462.69693350855243, 463.3304874456378, 468.2227502346089, 471.30011932280536, 473.07749887719086, 5662.094064949588, 5718.857028574235, 5741.425857414309, 5766.904288185231, 5807.041838096667, 9360.719178424659, 9446.29152813528, 9464.526773661428, 9476.431414115175, 9601.951990240004, 8964.506952117566, 9160.0, 9184.49485278316, 9290.349675522648, 9321.207697345802, 7192.856142877114, 7248.891266631004, 7251.057362542836, 7309.671064802096, 7313.634318284171, 6884.65576721172, 6923.238443771282, 7089.220185779664, 7099.148102227766, 7100.289971002859, 7950.522968621901, 8325.292350150305, 8342.124076972073, 8353.582320884054, 8412.495250285003, 8352.164783521599, 8464.492130472192, 8587.613557390057, 8587.742367728979, 8625.482471051757, 6137.41694539018, 6281.466075383643, 6291.685415729286, 6623.138991931018, 6697.062411262525, 7509.249075092447, 7517.511361761468, 7518.097828260644, 7570.0, 7574.697012119454, 6952.721891124501, 7103.964480177566, 7178.389836863925, 7275.163356213891, 7306.123265208209, 7043.401310888632, 7087.787366379017, 7118.679659415442, 7173.1392232932385, 7226.385757210696, 6876.381125698711, 7048.224695283618, 7182.820429489305, 7192.748253811092, 7209.0988626421695, 6422.389872962061, 6439.356064393523, 6442.130312407952, 6453.128827608213, 6464.191976003, 6571.34286571339, 6653.700583473658, 6687.164104486939, 6705.530612857248, 6711.026901099411], "unit": "ms", "name": "mootools.html", "value": 4400.8698101270365}, {"lowerIsBetter": false, "replicates": [307.5402273600967, 309.4800734765594, 324.1992279070694, 326.0804531221954, 335.635835118896, 6258.812235632932, 6328.303886572485, 6358.45953093987, 6358.650274234915, 6487.3836985486305, 6242.937570624304, 6250.343713910042, 6266.498680105592, 6268.874622507558, 6290.591111577755, 4652.767361631922, 4745.3593764841735, 4753.073150735608, 4756.191447453941, 4759.286107083931, 4632.305154226876, 4657.953420465803, 4676.532346765331, 4759.190937540627, 4764.094821983812, 4612.962083531195, 4622.653301002439, 4626.514216007321, 4630.513796051417, 4675.485696573374, 4664.067186562667, 4667.906641867161, 4672.299155126724, 4679.064187162547, 4680.742559159245, 3977.8011099445202, 4046.9190616187807, 4056.1076563156057, 4096.6313031826985, 4106.568810274908, 5937.168796368512, 5942.316633587149, 5954.583179177459, 5954.910676339859, 5959.046552551615, 5502.394736578973, 5505.476979686963, 5513.531349835269, 5514.6139770216105, 5522.861928451821, 6493.253275873288, 6597.416620011163, 6599.439047680953, 6615.702293396761, 6631.071649969008, 4492.056668099703, 4499.865004049884, 4503.189425903336, 4521.367008618796, 4522.118186953546, 6806.489513286523, 6813.046173535709, 6821.590704557742, 6827.0, 6890.655467226618, 4511.323301504735, 4551.977240113844, 4608.6082682971655, 4632.2356811126465, 4633.258678611406, 4611.515790841981, 4620.44554653437, 4675.742834144155, 4686.5782079613, 4694.976525117352, 5489.643173193809, 5490.478404551562, 5490.725463726878, 5493.835184944458, 5500.202470641814, 6592.670366481657, 6693.531452798257, 6702.664866756642, 6702.932970670328, 6705.0, 1628.1045425016193, 1640.7456844189169, 1644.3176081926106, 1657.0306370772992, 1663.002198680782, 1078.2075174746556, 1088.8856670049688, 1091.8689757229183, 1111.349860331715, 1115.1524841120645, 6288.842778930563, 6419.839504012437, 6437.645929473831, 6495.220573531112, 6501.154849869581, 6926.965365173242, 6945.40964018054, 6950.513464057467, 6964.825879353057, 6965.407940325028, 6419.358064193637, 6447.451966582893, 6474.931636279961, 6477.643729594917, 6513.283538810727, 3698.112453011258, 3711.257748450321, 3765.435184722259, 3799.525059367579, 3803.2393521295853, 7187.928120718726, 7221.747238846615, 7261.782146535613, 7262.600556969312, 7279.59962202084, 6978.511504194657, 7078.973548835493, 7149.92850071503, 7151.964240178766, 7167.534110282815, 7084.43324534036, 7137.92862071383, 7146.964265178641, 7156.427485801228, 7186.6047367395295, 6874.415674667709, 6899.931000689929, 6903.723851046103, 6907.620080895398, 6928.653567321513, 6777.2883847195335, 6906.585604863724, 6911.308869113049, 6916.481263905328, 6921.446284297345, 6986.720531178899, 7136.143662760502, 7142.321479459443, 7163.8567228656175, 7191.137063552407, 4568.908621827479, 4579.74811385377, 4588.518205588499, 4601.0, 4601.539846015372, 6878.759243426456, 6898.0, 6924.688389022405, 6927.861442771217, 6928.411085057826, 6527.412532872065, 6689.698963546554, 6711.295313991961, 6717.630530320879, 6782.25395206517, 6717.664116794043, 6735.057092007025, 6741.359570840762, 6742.595444273163, 6745.494087943326, 6428.4857211423905, 6488.286288508166, 6502.057201705821, 6598.60408375499, 6610.570312929644, 6527.836804079936, 6531.477481801349, 6578.5395022347975, 6661.2006559213205, 6701.1623547056615, 6452.806415807533, 6502.089707440868, 6522.760675471645, 6541.313162117909, 6629.138212032405], "unit": "ms", "name": "prototype.html", "value": 4982.080968094641}, {"lowerIsBetter": false, "replicates": [761.1095018827974, 766.222284381353, 783.0877028262075, 827.6234313387409, 840.7687885831396, 13102.01734869885, 13477.258750768704, 13482.662933426656, 13501.594952151427, 13509.864901350997, 12386.442610082546, 12902.806457903116, 12921.547745828899, 12989.350532473385, 13105.213687178775, 780.0600276666618, 787.6809891993752, 788.03072221168, 789.1319548496665, 790.6046976511744, 778.5173192620583, 783.6551917156432, 787.3661702329622, 791.3827214772487, 796.8804679298123, 829.3738227638124, 832.6086739232582, 836.5817091454273, 838.6435764799966, 839.2991851803721, 818.2717381530442, 837.0457678246803, 839.0770152831897, 842.3471809347743, 849.0193826130828, 599.9340072592012, 601.7623038899654, 606.6299557270062, 607.3076692570473, 608.7504123309421, 13082.0, 13201.27392993385, 13205.20768753878, 13218.60344189676, 13240.073194876362, 11810.645680629508, 12252.877471225263, 12289.262644241284, 12323.507059717691, 12364.505419783198, 9813.564116178792, 10163.288569800117, 10258.487075646188, 10350.327228730108, 10374.118199953013, 3580.838862251179, 3597.9820100899587, 3629.0, 3638.3450978823803, 3740.2706472237924, 41423.1715365694, 42135.36796948048, 43959.0, 45288.77355613201, 48274.517254827355, 8799.108191738678, 9563.90436095644, 9986.101250887456, 10019.699409017741, 10226.233032522441, 16981.575460613585, 16988.320467181173, 16992.875890513686, 17004.0, 17031.48905532836, 7192.676329565182, 7622.313991740659, 7647.57938313387, 7848.84302313962, 7996.04047514302, 4334.111507141029, 4349.847755328548, 4364.738115713068, 4391.890202744957, 4410.33844923264, 3124.9843750781556, 3308.7353011759483, 3371.511130886008, 3403.9659603404143, 3421.828908554612, 612.7518355066205, 613.2487702564412, 614.5790133758389, 614.923134608174, 622.3682961793783, 537.9919301210485, 581.7207740284687, 612.8866159760458, 664.4883439751364, 699.5033526196356, 8746.12538746133, 9337.579808908615, 9503.334766566273, 9555.856662150074, 9648.469334186546, 668.358375959075, 675.5743881354813, 688.3151264491862, 699.869710417682, 704.7885634309789, 3941.5905241966066, 4224.746515209036, 4259.084296876186, 4285.571442855752, 4329.675274354373, 51463.22805157926, 52149.7392513035, 52493.0, 52950.470495295325, 56812.0, 49597.0, 50116.749416252685, 50215.74892125516, 50773.49226507688, 50891.491085089416, 43059.999999999374, 46870.0, 46925.5307446928, 46927.530724693, 47336.0, 8269.131741167092, 8439.493630382196, 8598.269147122564, 8634.266087382392, 8651.95674021626, 35418.0, 35711.82144089263, 35920.0, 36129.27741445209, 36557.0, 41023.179536408505, 41389.0, 42780.0, 44637.55362446334, 45072.0, 10018.549165287433, 10161.136303414292, 10753.301035432673, 11176.944115279372, 11338.489767960296, 11481.598144064918, 11585.189036767346, 11634.956465656105, 11664.0, 11669.416529173337, 10891.237613366988, 11117.388543630179, 11285.153613479186, 11377.43112844371, 11505.884941150816, 10731.624393146203, 10892.891071089187, 12067.396630168632, 12835.294058826854, 12934.223946563236, 11512.0, 11822.34977076269, 12235.571754988532, 12244.448999794853, 12300.892919637276, 10211.795764084825, 10395.94802025985, 10679.626213082505, 10714.464276786286, 11206.103511719206, 9359.719208423758, 9703.563339649592, 9797.706068817946, 9958.55186516594, 9963.800723985334], "unit": "ms", "name": "yui.html", "value": 6304.681465435837}], "name": "dromaeo_css", "value": 7047.891850007895}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 108.0, 110.0, 110.0, 110.0, 123.0, 110.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [146.0, 131.0, 149.0, 167.0, 126.0, 154.0, 148.0, 135.0, 170.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 164.0, 178.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 72.0, 72.0, 72.0, 72.0, 156.0, 74.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 149.0, 93.0, 93.0, 93.0, 148.0, 93.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 108.5}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 111.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 148.0, 146.0, 148.0, 148.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 99.0, 97.0, 95.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 63.0, 65.0, 62.0, 65.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 62.5}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 70.0, 72.0, 71.0, 71.0, 71.0, 73.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 71.3}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 87.0, 85.0, 95.0, 97.0, 86.0, 87.0, 86.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 88.0}, {"lowerIsBetter": true, "replicates": [125.0, 121.0, 119.0, 130.0, 124.0, 134.0, 121.0, 133.0, 119.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [154.0, 146.0, 164.0, 149.0, 156.0, 150.0, 155.0, 151.0, 150.0, 151.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.6}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 67.0, 69.0, 67.0, 69.0, 68.0, 68.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1528.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.257555847569], "unit": "score", "name": "Richards", "value": 214.918576}, {"lowerIsBetter": false, "replicates": [193.2740626207963], "unit": "score", "name": "DeltaBlue", "value": 342.094532}, {"lowerIsBetter": false, "replicates": [280.3476310625175], "unit": "score", "name": "Encrypt", "value": 949.4676269999999}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [1074.0343347639484], "unit": "score", "name": "RayTrace", "value": 688.9807672327672}, {"lowerIsBetter": false, "replicates": [703.9381153305204], "unit": "score", "name": "Earley", "value": 946.7636223776225}, {"lowerIsBetter": false, "replicates": [12156.626506024097], "unit": "score", "name": "Boyer", "value": 54.82302180376615}, {"lowerIsBetter": false, "replicates": [24829.268292682926], "unit": "score", "name": "RegExp", "value": 36.68996561886052}, {"lowerIsBetter": false, "replicates": [630.9148264984227], "unit": "score", "name": "Splay", "value": 129.16323500000001}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21337.475761086298}]} 16:43:35 INFO - RETURN: dromaeo_css: 7047.89 16:43:35 INFO - RETURN: kraken: 1528.10 16:43:35 INFO - RETURN: v8_7: 21337.48 16:43:35 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21337.48"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1528.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7047.89"}}} 16:43:35 INFO - Return code: 0 16:43:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:43:35 INFO - # TBPL SUCCESS # 16:43:35 INFO - Running post-action listener: _resource_record_post_action 16:43:35 INFO - Running post-run listener: _resource_record_post_run 16:43:36 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 10293248; Write bytes: 402448384; Read time: 2164; Write time: 451532 16:43:36 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 4550656; Read time: 0; Write time: 288 16:43:36 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 8556544; Write bytes: 397897728; Read time: 2104; Write time: 451244 16:43:36 INFO - Running post-run listener: _upload_blobber_files 16:43:36 INFO - Blob upload gear active. 16:43:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:43:36 INFO - Copying logs to upload dir... 16:43:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.345188 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-11-25 16:43:36.719045) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:36.723495) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010059 build_url: 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:36.749360) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:36.749664) ========= 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=004b26eee9c3b360c8654f40000001e1-1448491909.188676-1416224951 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004542 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:36.798707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 16:43:36.799023) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 16:43:36.799306) ========= ========= Total master_lag: 0.41 =========