builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1448462440.04 results: success (0) buildid: 20151125043001 builduid: d93833db6abc459d933c13f1b9bd1c31 revision: 1835baed2a38429a3cc301d21778a113d3a9e7d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.038633) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.039088) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.039383) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006607 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.073591) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.073980) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.092547) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.092917) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.141631) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.142118) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.142644) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.142980) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 06:40:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 12.8M=0.001s 2015-11-25 06:40:41 (12.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106818 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.263139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.263601) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022117 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.314830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.315286) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1835baed2a38429a3cc301d21778a113d3a9e7d8 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1835baed2a38429a3cc301d21778a113d3a9e7d8 --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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 06:40:41,694 truncating revision to first 12 chars 2015-11-25 06:40:41,694 Setting DEBUG logging. 2015-11-25 06:40:41,694 attempt 1/10 2015-11-25 06:40:41,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1835baed2a38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 06:40:41,933 unpacking tar archive at: mozilla-central-1835baed2a38/testing/mozharness/ program finished with exit code 0 elapsedTime=0.586711 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.916850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.917138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.932532) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.932806) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.933181) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-11-25 06:40:40.933456) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:40:42 INFO - MultiFileLogger online at 20151125 06:40:42 in /builds/slave/test-pgo 06:40:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:40:42 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:40:42 INFO - {'append_to_log': False, 06:40:42 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:40:42 INFO - 'blob_upload_branch': 'Firefox', 06:40:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:40:42 INFO - 'branch': 'Firefox', 06:40:42 INFO - 'buildbot_json_path': 'buildprops.json', 06:40:42 INFO - 'config_files': ('talos/linux_config.py',), 06:40:42 INFO - 'default_actions': ('clobber', 06:40:42 INFO - 'read-buildbot-config', 06:40:42 INFO - 'download-and-extract', 06:40:42 INFO - 'populate-webroot', 06:40:42 INFO - 'create-virtualenv', 06:40:42 INFO - 'install', 06:40:42 INFO - 'run-tests'), 06:40:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:40:42 INFO - 'download_minidump_stackwalk': True, 06:40:42 INFO - 'download_symbols': 'ondemand', 06:40:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:40:42 INFO - 'tooltool.py': '/tools/tooltool.py', 06:40:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:40:42 INFO - '/tools/misc-python/virtualenv.py')}, 06:40:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:40:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:40:42 INFO - 'installer_path': 'installer.exe', 06:40:42 INFO - 'log_level': 'info', 06:40:42 INFO - 'log_name': 'talos', 06:40:42 INFO - 'log_to_console': True, 06:40:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:40:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:40:42 INFO - 'opt_config_files': (), 06:40:42 INFO - 'pip_index': False, 06:40:42 INFO - 'sps_profile': False, 06:40:42 INFO - 'sps_profile_interval': 0, 06:40:42 INFO - 'suite': 'dromaeojs', 06:40:42 INFO - 'system_bits': '64', 06:40:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:40:42 INFO - 'title': 'talos-linux64-ix-064', 06:40:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:40:42 INFO - 'use_talos_json': True, 06:40:42 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:40:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:40:42 INFO - 'work_dir': 'build'} 06:40:42 INFO - ##### 06:40:42 INFO - ##### Running clobber step. 06:40:42 INFO - ##### 06:40:42 INFO - Running pre-action listener: _resource_record_pre_action 06:40:42 INFO - Running main action method: clobber 06:40:42 INFO - rmtree: /builds/slave/test-pgo/build 06:40:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:40:42 INFO - Running post-action listener: _resource_record_post_action 06:40:42 INFO - ##### 06:40:42 INFO - ##### Running read-buildbot-config step. 06:40:42 INFO - ##### 06:40:42 INFO - Running pre-action listener: _resource_record_pre_action 06:40:42 INFO - Running main action method: read_buildbot_config 06:40:42 INFO - Using buildbot properties: 06:40:42 INFO - { 06:40:42 INFO - "properties": { 06:40:42 INFO - "buildnumber": 580, 06:40:42 INFO - "product": "firefox", 06:40:42 INFO - "script_repo_revision": "production", 06:40:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:40:42 INFO - "repository": "", 06:40:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 06:40:42 INFO - "buildid": "20151125043001", 06:40:42 INFO - "slavename": "talos-linux64-ix-064", 06:40:42 INFO - "pgo_build": "True", 06:40:42 INFO - "basedir": "/builds/slave/test-pgo", 06:40:42 INFO - "project": "", 06:40:42 INFO - "platform": "ubuntu64_hw", 06:40:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:40:42 INFO - "slavebuilddir": "test", 06:40:42 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 06:40:42 INFO - "branch": "mozilla-central", 06:40:42 INFO - "repo_path": "mozilla-central", 06:40:42 INFO - "stage_platform": "linux64-pgo", 06:40:42 INFO - "builduid": "d93833db6abc459d933c13f1b9bd1c31", 06:40:42 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 06:40:42 INFO - }, 06:40:42 INFO - "sourcestamp": { 06:40:42 INFO - "repository": "", 06:40:42 INFO - "hasPatch": false, 06:40:42 INFO - "project": "", 06:40:42 INFO - "branch": "mozilla-central-linux64-pgo-talos", 06:40:42 INFO - "changes": [ 06:40:42 INFO - { 06:40:42 INFO - "category": null, 06:40:42 INFO - "files": [ 06:40:42 INFO - { 06:40:42 INFO - "url": null, 06:40:42 INFO - "name": "https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 06:40:42 INFO - } 06:40:42 INFO - ], 06:40:42 INFO - "repository": "", 06:40:42 INFO - "rev": "1835baed2a38429a3cc301d21778a113d3a9e7d8", 06:40:42 INFO - "who": "sendchange", 06:40:42 INFO - "when": 1448462429, 06:40:42 INFO - "number": 6705862, 06:40:42 INFO - "comments": "", 06:40:42 INFO - "project": "", 06:40:42 INFO - "at": "Wed 25 Nov 2015 06:40:29", 06:40:42 INFO - "branch": "mozilla-central-linux64-pgo-talos", 06:40:42 INFO - "revlink": "", 06:40:42 INFO - "properties": [ 06:40:42 INFO - [ 06:40:42 INFO - "buildid", 06:40:42 INFO - "20151125043001", 06:40:42 INFO - "Change" 06:40:42 INFO - ], 06:40:42 INFO - [ 06:40:42 INFO - "builduid", 06:40:42 INFO - "d93833db6abc459d933c13f1b9bd1c31", 06:40:42 INFO - "Change" 06:40:42 INFO - ], 06:40:42 INFO - [ 06:40:42 INFO - "pgo_build", 06:40:42 INFO - "True", 06:40:42 INFO - "Change" 06:40:42 INFO - ] 06:40:42 INFO - ], 06:40:42 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 06:40:42 INFO - } 06:40:42 INFO - ], 06:40:42 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 06:40:42 INFO - } 06:40:42 INFO - } 06:40:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 06:40:42 INFO - Running post-action listener: _resource_record_post_action 06:40:42 INFO - ##### 06:40:42 INFO - ##### Running download-and-extract step. 06:40:42 INFO - ##### 06:40:42 INFO - Running pre-action listener: _resource_record_pre_action 06:40:42 INFO - Running main action method: download_and_extract 06:40:42 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:40: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')]} 06:40:42 INFO - https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:40:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/test_packages.json 06:40:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/test_packages.json 06:40:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:40:43 INFO - Downloaded 1302 bytes. 06:40:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:40:43 INFO - Using the following test package requirements: 06:40:43 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:43 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:40:43 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'jsshell-linux-x86_64.zip'], 06:40:43 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:40:43 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:43 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:40:43 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:40:43 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:40:43 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:43 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:40:43 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:40:43 INFO - https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:40:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:44 INFO - Downloaded 22753997 bytes. 06:40:44 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 06:40:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:44 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 06:40:45 INFO - Return code: 0 06:40:45 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 06:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:40:45 INFO - https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:40:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:55 INFO - Downloaded 22753997 bytes. 06:40:55 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 06:40:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:40:55 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 06:40:56 INFO - Return code: 0 06:40: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')]} 06:40:56 INFO - https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:40:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:40:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:40:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:58 INFO - Downloaded 11131717 bytes. 06:40:58 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 06:40:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:40:58 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 06:40:58 INFO - Return code: 0 06:40:58 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')]} 06:40:58 INFO - https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:40:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:40:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:40:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:40:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1wUR3H2fQkeKiD7duAeERw/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 06:41:00 INFO - Downloaded 58391096 bytes. 06:41:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:41:00 INFO - mkdir: /builds/slave/test-pgo/properties 06:41:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:41:00 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:41:00 INFO - Contents: 06:41:00 INFO - build_url:https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:41:00 INFO - Running post-action listener: _resource_record_post_action 06:41:00 INFO - Running post-action listener: set_extra_try_arguments 06:41:00 INFO - ##### 06:41:00 INFO - ##### Running populate-webroot step. 06:41:00 INFO - ##### 06:41:00 INFO - Running pre-action listener: _resource_record_pre_action 06:41:00 INFO - Running main action method: populate_webroot 06:41:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:41:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:41:00 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 06:41:00 INFO - '../fennec_ids.txt'], 06:41:00 INFO - 'tests': ['tcheck2']}, 06:41:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:41:00 INFO - '--tppagecycles', 06:41:00 INFO - '7'], 06:41:00 INFO - 'tests': ['tsvgm']}}, 06:41:00 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:41:00 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:41:00 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:41:00 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:41:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:41:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:41:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'tests': ['damp', 'tps']}, 06:41:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['damp', 'tps']}, 06:41:00 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:41:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:41:00 INFO - 'other': {'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'other_l64': {'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'other_nol64': {'tests': ['a11yr', 06:41:00 INFO - 'ts_paint', 06:41:00 INFO - 'tpaint', 06:41:00 INFO - 'sessionrestore', 06:41:00 INFO - 'sessionrestore_no_auto_restore']}, 06:41:00 INFO - 'svgr': {'tests': ['tsvgx', 06:41:00 INFO - 'tsvgr_opacity', 06:41:00 INFO - 'tart', 06:41:00 INFO - 'tscrollx', 06:41:00 INFO - 'cart']}, 06:41:00 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['tsvgx', 06:41:00 INFO - 'tsvgr_opacity', 06:41:00 INFO - 'tart', 06:41:00 INFO - 'tscrollx', 06:41:00 INFO - 'cart']}, 06:41:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'tests': ['tp5o']}, 06:41:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'talos_options': ['--e10s'], 06:41:00 INFO - 'tests': ['tp5o']}, 06:41:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'talos_options': ['--xperf_path', 06:41:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:41:00 INFO - 'tests': ['tp5n']}, 06:41:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:00 INFO - 'talos_options': ['--e10s', 06:41:00 INFO - '--xperf_path', 06:41:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:41:00 INFO - 'tests': ['tp5n']}}, 06:41:00 INFO - 'talos.zip': {'path': '', 06:41:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:41:00 INFO - Running post-action listener: _resource_record_post_action 06:41:00 INFO - ##### 06:41:00 INFO - ##### Running create-virtualenv step. 06:41:00 INFO - ##### 06:41:00 INFO - Running pre-action listener: _resource_record_pre_action 06:41:00 INFO - Running main action method: create_virtualenv 06:41:00 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:41:00 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 06:41:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:41:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:41:00 INFO - Using real prefix '/usr' 06:41:00 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:41:00 INFO - Installing distribute.............................................................................................................................................................................................done. 06:41:02 INFO - Installing pip.................done. 06:41:02 INFO - Return code: 0 06:41:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 06:41:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:41:02 INFO - Using env: {'DISPLAY': ':0', 06:41:02 INFO - 'HOME': '/home/cltbld', 06:41:02 INFO - 'LANG': 'en_US.UTF-8', 06:41:02 INFO - 'LANGUAGE': 'en_US:en', 06:41:02 INFO - 'LOGNAME': 'cltbld', 06:41:02 INFO - 'MAIL': '/var/mail/cltbld', 06:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:02 INFO - 'MOZ_NO_REMOTE': '1', 06:41:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:02 INFO - 'NO_EM_RESTART': '1', 06:41:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:02 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:02 INFO - 'SHELL': '/bin/bash', 06:41:02 INFO - 'SHLVL': '1', 06:41:02 INFO - 'TERM': 'linux', 06:41:02 INFO - 'TMOUT': '86400', 06:41:02 INFO - 'USER': 'cltbld', 06:41:02 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:02 INFO - '_': '/tools/buildbot/bin/python'} 06:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:03 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 06:41:03 INFO - Cleaning up... 06:41:03 INFO - Return code: 0 06:41:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06:41: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')]} 06:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41: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')]} 06:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41: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', '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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 06:41: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 psutil>=0.7.1 06:41:03 INFO - Using env: {'DISPLAY': ':0', 06:41:03 INFO - 'HOME': '/home/cltbld', 06:41:03 INFO - 'LANG': 'en_US.UTF-8', 06:41:03 INFO - 'LANGUAGE': 'en_US:en', 06:41:03 INFO - 'LOGNAME': 'cltbld', 06:41:03 INFO - 'MAIL': '/var/mail/cltbld', 06:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:03 INFO - 'MOZ_NO_REMOTE': '1', 06:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:03 INFO - 'NO_EM_RESTART': '1', 06:41:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:03 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:03 INFO - 'SHELL': '/bin/bash', 06:41:03 INFO - 'SHLVL': '1', 06:41:03 INFO - 'TERM': 'linux', 06:41:03 INFO - 'TMOUT': '86400', 06:41:03 INFO - 'USER': 'cltbld', 06:41:03 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:03 INFO - '_': '/tools/buildbot/bin/python'} 06:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:03 INFO - Downloading/unpacking psutil>=0.7.1 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41:05 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 06:41:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 06:41:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:41:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:41:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:41:05 INFO - Installing collected packages: psutil 06:41:05 INFO - Running setup.py install for psutil 06:41:05 INFO - building 'psutil._psutil_linux' extension 06:41:05 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 06:41:05 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 06:41:05 INFO - building 'psutil._psutil_posix' extension 06:41:05 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 06:41:05 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 06:41:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:41:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:41:05 INFO - Successfully installed psutil 06:41:05 INFO - Cleaning up... 06:41:05 INFO - Return code: 0 06:41:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:05 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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:05 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 06:41:05 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 06:41:05 INFO - Using env: {'DISPLAY': ':0', 06:41:05 INFO - 'HOME': '/home/cltbld', 06:41:05 INFO - 'LANG': 'en_US.UTF-8', 06:41:05 INFO - 'LANGUAGE': 'en_US:en', 06:41:05 INFO - 'LOGNAME': 'cltbld', 06:41:05 INFO - 'MAIL': '/var/mail/cltbld', 06:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:05 INFO - 'MOZ_NO_REMOTE': '1', 06:41:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:05 INFO - 'NO_EM_RESTART': '1', 06:41:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:05 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:05 INFO - 'SHELL': '/bin/bash', 06:41:05 INFO - 'SHLVL': '1', 06:41:05 INFO - 'TERM': 'linux', 06:41:05 INFO - 'TMOUT': '86400', 06:41:05 INFO - 'USER': 'cltbld', 06:41:05 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:05 INFO - '_': '/tools/buildbot/bin/python'} 06:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:41:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:41:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:41:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:41:07 INFO - Installing collected packages: mozsystemmonitor 06:41:07 INFO - Running setup.py install for mozsystemmonitor 06:41:07 INFO - Successfully installed mozsystemmonitor 06:41:07 INFO - Cleaning up... 06:41:07 INFO - Return code: 0 06:41:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:41:07 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')]} 06:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:07 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')]} 06:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:07 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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:07 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 06:41:07 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 06:41:07 INFO - Using env: {'DISPLAY': ':0', 06:41:07 INFO - 'HOME': '/home/cltbld', 06:41:07 INFO - 'LANG': 'en_US.UTF-8', 06:41:07 INFO - 'LANGUAGE': 'en_US:en', 06:41:07 INFO - 'LOGNAME': 'cltbld', 06:41:07 INFO - 'MAIL': '/var/mail/cltbld', 06:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:07 INFO - 'MOZ_NO_REMOTE': '1', 06:41:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:07 INFO - 'NO_EM_RESTART': '1', 06:41:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:07 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:07 INFO - 'SHELL': '/bin/bash', 06:41:07 INFO - 'SHLVL': '1', 06:41:07 INFO - 'TERM': 'linux', 06:41:07 INFO - 'TMOUT': '86400', 06:41:07 INFO - 'USER': 'cltbld', 06:41:07 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:07 INFO - '_': '/tools/buildbot/bin/python'} 06:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:09 INFO - Downloading blobuploader-1.2.4.tar.gz 06:41:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 06:41:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:41:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:41:09 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 06:41:09 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 06:41:09 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 06:41:09 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 06:41:09 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 06:41:09 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 06:41:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 06:41:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:41:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:10 INFO - Downloading docopt-0.6.1.tar.gz 06:41:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 06:41:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:41:10 INFO - Installing collected packages: blobuploader, requests, docopt 06:41:10 INFO - Running setup.py install for blobuploader 06:41:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:41:10 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:41:10 INFO - Running setup.py install for requests 06:41:10 INFO - Running setup.py install for docopt 06:41:10 INFO - Successfully installed blobuploader requests docopt 06:41:10 INFO - Cleaning up... 06:41:10 INFO - Return code: 0 06:41:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:10 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')]} 06:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:10 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')]} 06:41:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:10 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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:10 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 06:41:10 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 06:41:10 INFO - Using env: {'DISPLAY': ':0', 06:41:10 INFO - 'HOME': '/home/cltbld', 06:41:10 INFO - 'LANG': 'en_US.UTF-8', 06:41:10 INFO - 'LANGUAGE': 'en_US:en', 06:41:10 INFO - 'LOGNAME': 'cltbld', 06:41:10 INFO - 'MAIL': '/var/mail/cltbld', 06:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:10 INFO - 'MOZ_NO_REMOTE': '1', 06:41:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:10 INFO - 'NO_EM_RESTART': '1', 06:41:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:10 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:10 INFO - 'SHELL': '/bin/bash', 06:41:10 INFO - 'SHLVL': '1', 06:41:10 INFO - 'TERM': 'linux', 06:41:10 INFO - 'TMOUT': '86400', 06:41:10 INFO - 'USER': 'cltbld', 06:41:10 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:10 INFO - '_': '/tools/buildbot/bin/python'} 06:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:11 INFO - Running setup.py (path:/tmp/pip-SPp5cY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:11 INFO - Running setup.py (path:/tmp/pip-VgYRYa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:11 INFO - Running setup.py (path:/tmp/pip-M9XVXq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:11 INFO - Running setup.py (path:/tmp/pip-zFiMZ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:11 INFO - Running setup.py (path:/tmp/pip-6ZBJOR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:11 INFO - Running setup.py (path:/tmp/pip-y0wm43-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:11 INFO - Running setup.py (path:/tmp/pip-yxHKMh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:11 INFO - Running setup.py (path:/tmp/pip-x9260p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:11 INFO - Running setup.py (path:/tmp/pip-mCRx5w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:11 INFO - Running setup.py (path:/tmp/pip-U5z8rM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:11 INFO - Running setup.py (path:/tmp/pip-LkM_iW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:11 INFO - Running setup.py (path:/tmp/pip-I9hDO7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:12 INFO - Running setup.py (path:/tmp/pip-Ma8sMp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:12 INFO - Running setup.py (path:/tmp/pip-gGaKqT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:12 INFO - Running setup.py (path:/tmp/pip-ix0mmt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:12 INFO - Running setup.py (path:/tmp/pip-YpUsJg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:12 INFO - Running setup.py (path:/tmp/pip-mqSo9a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:41:12 INFO - Running setup.py install for manifestparser 06:41:12 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:41:12 INFO - Running setup.py install for mozcrash 06:41:12 INFO - Running setup.py install for mozdebug 06:41:12 INFO - Running setup.py install for mozdevice 06:41:12 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:41:12 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:41:12 INFO - Running setup.py install for mozfile 06:41:12 INFO - Running setup.py install for mozhttpd 06:41:12 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozinfo 06:41:13 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozInstall 06:41:13 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozleak 06:41:13 INFO - Running setup.py install for mozlog 06:41:13 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for moznetwork 06:41:13 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozprocess 06:41:13 INFO - Running setup.py install for mozprofile 06:41:13 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozrunner 06:41:13 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:41:13 INFO - Running setup.py install for mozscreenshot 06:41:13 INFO - Running setup.py install for moztest 06:41:14 INFO - Running setup.py install for mozversion 06:41:14 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:41:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:41:14 INFO - Cleaning up... 06:41:14 INFO - Return code: 0 06:41:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:14 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 06:41:14 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 06:41:14 INFO - Using env: {'DISPLAY': ':0', 06:41:14 INFO - 'HOME': '/home/cltbld', 06:41:14 INFO - 'LANG': 'en_US.UTF-8', 06:41:14 INFO - 'LANGUAGE': 'en_US:en', 06:41:14 INFO - 'LOGNAME': 'cltbld', 06:41:14 INFO - 'MAIL': '/var/mail/cltbld', 06:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:14 INFO - 'MOZ_NO_REMOTE': '1', 06:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:14 INFO - 'NO_EM_RESTART': '1', 06:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:14 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:14 INFO - 'SHELL': '/bin/bash', 06:41:14 INFO - 'SHLVL': '1', 06:41:14 INFO - 'TERM': 'linux', 06:41:14 INFO - 'TMOUT': '86400', 06:41:14 INFO - 'USER': 'cltbld', 06:41:14 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:14 INFO - '_': '/tools/buildbot/bin/python'} 06:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:14 INFO - Running setup.py (path:/tmp/pip-l1QKew-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:14 INFO - Running setup.py (path:/tmp/pip-jHn21K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:14 INFO - Running setup.py (path:/tmp/pip-SromZq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:14 INFO - Running setup.py (path:/tmp/pip-51x7Tx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:14 INFO - Running setup.py (path:/tmp/pip-G4ei6_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:14 INFO - Running setup.py (path:/tmp/pip-z3_YaG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:14 INFO - Running setup.py (path:/tmp/pip-vEyPkK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:14 INFO - Running setup.py (path:/tmp/pip-ucrYuW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:14 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)) 06:41:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:14 INFO - Running setup.py (path:/tmp/pip-SYHOGC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:15 INFO - Running setup.py (path:/tmp/pip-W1Hcnc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:15 INFO - Running setup.py (path:/tmp/pip-VXnawA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:15 INFO - Running setup.py (path:/tmp/pip-QgXPIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:15 INFO - Running setup.py (path:/tmp/pip-Gpo_fE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:15 INFO - Running setup.py (path:/tmp/pip-LTSbIr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:15 INFO - Running setup.py (path:/tmp/pip-z4kJYG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:15 INFO - Running setup.py (path:/tmp/pip-yMWgmf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:15 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)) 06:41:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:15 INFO - Running setup.py (path:/tmp/pip-DPf9Qv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:17 INFO - Downloading blessings-1.5.1.tar.gz 06:41:17 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 06:41:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:41:17 INFO - Installing collected packages: blessings 06:41:17 INFO - Running setup.py install for blessings 06:41:17 INFO - Successfully installed blessings 06:41:17 INFO - Cleaning up... 06:41:17 INFO - Return code: 0 06:41:17 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:41:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:41:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:41:17 INFO - Reading from file tmpfile_stdout 06:41:17 INFO - Current package versions: 06:41:17 INFO - argparse == 1.2.1 06:41:17 INFO - blessings == 1.5.1 06:41:17 INFO - blobuploader == 1.2.4 06:41:17 INFO - docopt == 0.6.1 06:41:17 INFO - manifestparser == 1.1 06:41:17 INFO - mozInstall == 1.12 06:41:17 INFO - mozcrash == 0.16 06:41:17 INFO - mozdebug == 0.1 06:41:17 INFO - mozdevice == 0.47 06:41:17 INFO - mozfile == 1.2 06:41:17 INFO - mozhttpd == 0.7 06:41:17 INFO - mozinfo == 0.9 06:41:17 INFO - mozleak == 0.1 06:41:17 INFO - mozlog == 3.1 06:41:17 INFO - moznetwork == 0.27 06:41:17 INFO - mozprocess == 0.22 06:41:17 INFO - mozprofile == 0.27 06:41:17 INFO - mozrunner == 6.11 06:41:17 INFO - mozscreenshot == 0.1 06:41:17 INFO - mozsystemmonitor == 0.0 06:41:17 INFO - moztest == 0.7 06:41:17 INFO - mozversion == 1.4 06:41:17 INFO - psutil == 3.1.1 06:41:17 INFO - requests == 1.2.3 06:41:17 INFO - wsgiref == 0.1.2 06:41:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:17 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')]} 06:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:17 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')]} 06:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:17 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 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:17 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 06:41:17 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 06:41:17 INFO - Using env: {'DISPLAY': ':0', 06:41:17 INFO - 'HOME': '/home/cltbld', 06:41:17 INFO - 'LANG': 'en_US.UTF-8', 06:41:17 INFO - 'LANGUAGE': 'en_US:en', 06:41:17 INFO - 'LOGNAME': 'cltbld', 06:41:17 INFO - 'MAIL': '/var/mail/cltbld', 06:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:17 INFO - 'MOZ_NO_REMOTE': '1', 06:41:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:17 INFO - 'NO_EM_RESTART': '1', 06:41:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:17 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:17 INFO - 'SHELL': '/bin/bash', 06:41:17 INFO - 'SHLVL': '1', 06:41:17 INFO - 'TERM': 'linux', 06:41:17 INFO - 'TMOUT': '86400', 06:41:17 INFO - 'USER': 'cltbld', 06:41:17 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:17 INFO - '_': '/tools/buildbot/bin/python'} 06:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 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)) 06:41:18 INFO - Cleaning up... 06:41:18 INFO - Return code: 0 06:41:18 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 06:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', '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 06:41:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 06:41:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:41:18 INFO - Using env: {'DISPLAY': ':0', 06:41:18 INFO - 'HOME': '/home/cltbld', 06:41:18 INFO - 'LANG': 'en_US.UTF-8', 06:41:18 INFO - 'LANGUAGE': 'en_US:en', 06:41:18 INFO - 'LOGNAME': 'cltbld', 06:41:18 INFO - 'MAIL': '/var/mail/cltbld', 06:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:18 INFO - 'MOZ_NO_REMOTE': '1', 06:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:18 INFO - 'NO_EM_RESTART': '1', 06:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:18 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:18 INFO - 'SHELL': '/bin/bash', 06:41:18 INFO - 'SHLVL': '1', 06:41:18 INFO - 'TERM': 'linux', 06:41:18 INFO - 'TMOUT': '86400', 06:41:18 INFO - 'USER': 'cltbld', 06:41:18 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:18 INFO - '_': '/tools/buildbot/bin/python'} 06:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:18 INFO - Downloading/unpacking jsonschema 06:41:18 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 06:41:18 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 06:41:18 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 06:41:18 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 06:41:18 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 06:41:18 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 06:41:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:41:19 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 06:41:19 INFO - Downloading/unpacking functools32 (from jsonschema) 06:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:20 INFO - Downloading functools32-3.2.3-2.tar.gz 06:41:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 06:41:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:41:20 INFO - warning: no files found matching '*.txt' 06:41:20 INFO - no previously-included directories found matching 'build' 06:41:20 INFO - no previously-included directories found matching 'dist' 06:41:20 INFO - no previously-included directories found matching '.git*' 06:41:20 INFO - Installing collected packages: jsonschema, functools32 06:41:20 INFO - Running setup.py install for functools32 06:41:20 INFO - warning: no files found matching '*.txt' 06:41:20 INFO - no previously-included directories found matching 'build' 06:41:20 INFO - no previously-included directories found matching 'dist' 06:41:20 INFO - no previously-included directories found matching '.git*' 06:41:20 INFO - Successfully installed jsonschema functools32 06:41:20 INFO - Cleaning up... 06:41:20 INFO - Return code: 0 06:41:20 INFO - Running post-action listener: _resource_record_post_action 06:41:20 INFO - Running post-action listener: _start_resource_monitoring 06:41:20 INFO - Starting resource monitoring. 06:41:20 INFO - ##### 06:41:20 INFO - ##### Running install step. 06:41:20 INFO - ##### 06:41:20 INFO - Running pre-action listener: _resource_record_pre_action 06:41:20 INFO - Running main action method: install 06:41:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:41:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:41:20 INFO - Reading from file tmpfile_stdout 06:41:20 INFO - Detecting whether we're running mozinstall >=1.0... 06:41:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:41:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:41:20 INFO - Reading from file tmpfile_stdout 06:41:20 INFO - Output received: 06:41:20 INFO - Usage: mozinstall [options] installer 06:41:20 INFO - Options: 06:41:20 INFO - -h, --help show this help message and exit 06:41:20 INFO - -d DEST, --destination=DEST 06:41:20 INFO - Directory to install application into. [default: 06:41:20 INFO - "/builds/slave/test-pgo"] 06:41:20 INFO - --app=APP Application being installed. [default: firefox] 06:41:20 INFO - mkdir: /builds/slave/test-pgo/build/application 06:41:20 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'] 06:41:20 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 06:41:35 INFO - Reading from file tmpfile_stdout 06:41:35 INFO - Output received: 06:41:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:41:35 INFO - Running post-action listener: _resource_record_post_action 06:41:35 INFO - ##### 06:41:35 INFO - ##### Running run-tests step. 06:41:35 INFO - ##### 06:41:35 INFO - Running pre-action listener: _resource_record_pre_action 06:41:35 INFO - Running main action method: run_tests 06:41:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:41:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:41:35 INFO - Python 2.7.3 06:41:35 INFO - Return code: 0 06:41:35 INFO - grabbing minidump binary from tooltool 06:41: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')]} 06:41:35 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 0x1e45640>, '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 0x1e46540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e469d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:41:35 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 06:41:35 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 06:41:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:41:35 INFO - Return code: 0 06:41:35 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:41:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:41:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:41:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:41:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:41:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:41:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost 06:41:35 INFO - Using env: {'DISPLAY': ':0', 06:41:35 INFO - 'HOME': '/home/cltbld', 06:41:35 INFO - 'LANG': 'en_US.UTF-8', 06:41:35 INFO - 'LANGUAGE': 'en_US:en', 06:41:35 INFO - 'LOGNAME': 'cltbld', 06:41:35 INFO - 'MAIL': '/var/mail/cltbld', 06:41:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:41:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:35 INFO - 'MOZ_NO_REMOTE': '1', 06:41:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:41:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:35 INFO - 'NO_EM_RESTART': '1', 06:41:35 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:35 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:35 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:41:35 INFO - 'SHELL': '/bin/bash', 06:41:35 INFO - 'SHLVL': '1', 06:41:35 INFO - 'TERM': 'linux', 06:41:35 INFO - 'TMOUT': '86400', 06:41:35 INFO - 'USER': 'cltbld', 06:41:35 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105', 06:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:35 INFO - '_': '/tools/buildbot/bin/python'} 06:41:35 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] with output_timeout 3600 06:41:35 INFO - mozversion INFO | application_buildid: 20151125043001 06:41:35 INFO - mozversion INFO | application_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 06:41:35 INFO - mozversion INFO | application_display_name: Nightly 06:41:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:41:35 INFO - mozversion INFO | application_name: Firefox 06:41:35 INFO - mozversion INFO | application_remotingname: firefox 06:41:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 06:41:35 INFO - mozversion INFO | application_vendor: Mozilla 06:41:35 INFO - mozversion INFO | application_version: 45.0a1 06:41:35 INFO - mozversion INFO | platform_buildid: 20151125043001 06:41:35 INFO - mozversion INFO | platform_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 06:41:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 06:41:35 INFO - mozversion INFO | platform_version: 45.0a1 06:41:35 INFO - 2015-11-25 06:41:35,570 DEBUG : using testdate: 1448462495 06:41:35 INFO - 2015-11-25 06:41:35,570 DEBUG : actual date: 1448462495 06:41:35 INFO - 2015-11-25 06:41:35,663 INFO : starting webserver on 'localhost:35388' 06:41:35 INFO - 2015-11-25 06:41:35,664 INFO : Starting test suite talos-linux64-ix-064 06:41:35 INFO - 2015-11-25 06:41:35,664 INFO : Starting test dromaeo_css 06:41:35 INFO - 2015-11-25 06:41:35,664 DEBUG : operating with platform_type : linux_ 06:41:35 INFO - 2015-11-25 06:41:35,665 INFO : Initialising browser for dromaeo_css test... 06:41:35 INFO - 2015-11-25 06:41:35,677 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfvy435/profile http://localhost:35388/getInfo.html 06:41:39 INFO - 2015-11-25 06:41:39,412 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:41:39 INFO - 2015-11-25 06:41:39,412 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:41:39 INFO - 2015-11-25 06:41:39,412 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:41:39 INFO - 2015-11-25 06:41:39,413 DEBUG : BROWSER_OUTPUT: __metrics 06:41:39 INFO - 2015-11-25 06:41:39,574 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:41:40 INFO - 2015-11-25 06:41:40,714 INFO : Browser initialized. 06:41:40 INFO - 2015-11-25 06:41:40,714 INFO : Running cycle 1/1 for dromaeo_css test... 06:41:40 INFO - 2015-11-25 06:41:40,714 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfvy435/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:41:52 INFO - 2015-11-25 06:41:52,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 138678272 06:41:52 INFO - 2015-11-25 06:41:52,437 DEBUG : BROWSER_OUTPUT: 06:42:43 INFO - 2015-11-25 06:42:43,364 DEBUG : BROWSER_OUTPUT: 1448462563363 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] 06:44:53 INFO - 2015-11-25 06:44:53,241 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35388/tests/dromaeo/cssquery-ext.html) 06:44:54 INFO - 2015-11-25 06:44:54,391 DEBUG : BROWSER_OUTPUT: RSS: Main: 256110592 06:44:54 INFO - 2015-11-25 06:44:54,391 DEBUG : BROWSER_OUTPUT: 06:47:56 INFO - 2015-11-25 06:47:56,807 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-ext.html (next: http://localhost:35388/tests/dromaeo/cssquery-jquery.html) 06:47:57 INFO - 2015-11-25 06:47:57,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 223420416 06:47:57 INFO - 2015-11-25 06:47:57,373 DEBUG : BROWSER_OUTPUT: 06:50:59 INFO - 2015-11-25 06:50:59,180 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35388/tests/dromaeo/cssquery-mootools.html) 06:50:59 INFO - 2015-11-25 06:50:59,753 DEBUG : BROWSER_OUTPUT: RSS: Main: 219557888 06:50:59 INFO - 2015-11-25 06:50:59,753 DEBUG : BROWSER_OUTPUT: 06:54:01 INFO - 2015-11-25 06:54:01,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35388/tests/dromaeo/cssquery-prototype.html) 06:54:02 INFO - 2015-11-25 06:54:02,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 202420224 06:54:02 INFO - 2015-11-25 06:54:02,209 DEBUG : BROWSER_OUTPUT: 06:57:04 INFO - 2015-11-25 06:57:04,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35388/tests/dromaeo/cssquery-yui.html) 06:57:05 INFO - 2015-11-25 06:57:05,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 205086720 06:57:05 INFO - 2015-11-25 06:57:05,324 DEBUG : BROWSER_OUTPUT: 07:00:07 INFO - 2015-11-25 07:00:07,922 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/dromaeo/cssquery-yui.html (next: http://localhost:35388/tests/dromaeo/cssquery-dojo.html) 07:00:08 INFO - 2015-11-25 07:00:08,180 DEBUG : BROWSER_OUTPUT: RSS: Main: 249425920 07:00:08 INFO - 2015-11-25 07:00:08,181 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,181 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:00:08 INFO - 2015-11-25 07:00:08,181 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:00:08 INFO - 2015-11-25 07:00:08,181 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:00:08 INFO - 2015-11-25 07:00:08,181 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:00:08 INFO - 2015-11-25 07:00:08,182 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3199.520071989202;3240.9837950810243;3281.048495936179;3290.42417576924;3310.7351411887043;5157.871053223662;5262.605304602151;5465.207544905991;5496.477834605716;5500.037493438647;5460.31746031746;5471;5528.06022976094;5541.113421852504;5601.411851755568;4989.176785830343;5008.373953255843;5015.949840501585;5032.345795046639;5043.747812609373;4812.759362031885;5094.133997220481;5105.693658380491;5113.744312784364;5180.11937970544;4933.260010998361;4950.504949505057;5025.321581586476;5033.899322013576;5048.873778155576;4942.382202224722;4954.5540901319055;4960.627952903511;4961.5038496150455;4995.800167993276;4760.571548560646;4930.704157750546;4958.107540642683;4960.355153830015;5011.0228505441455;5142.562882155022;5178.974105129488;5221.71280579568;5235.921461178085;5266.815661451869;5953.166556682068;5955.583109182359;5956.255468066492;5960.284765828085;5967.254093238345;5751;5896.351401345849;6002.789902353396;6005.099235114724;6011.008183649693;5416.1063424534905;5492.752826122834;5573.219749235111;5576.804811831566;5635.0138725723245;6082.787102451393;6093.6039157454625;6093.6039157454625;6108.450239478469;6113.786017489367;5639.3232812062815;5677.432256774372;5904.291485021825;5910.852228694317;5944.108383742387;5773.682447465346;5822.912656310159;5892.234009578813;5920.467157955804;5957.4042595740075;5836.708164591753;5859.677717725481;5875.383084776037;5877.706114694248;5884.882302353929;5881.05903055509;5884.558658100659;5885.205497257815;5894.086416605433;5908.881822363614;4356.4336636237085;4475.194464996267;4493.348464472633;4559.247724125548;4566.589006989387;4023.979880100639;4061.634452899253;4070.430139780433;4084.387341898739;4109.383592461155;5614.214010038598;5615.410381909923;5617.8033768817895;5619.4099619540175;5671.716414179275;5625.4374562544235;5745.2818397700285;5750.741216645178;5788.7684492620665;5965.3139888912465;5614.214010038598;5660.632058916157;5671.291088613923;5689.743961521742;5692.57305702074;5199.142141546602;5210.739463026834;5212.192110222845;5213.165893457029;5216.374035115735;5835.095560188178;6117.724702388403;6119.2350956130485;6122.571420000646;6126.999999999911;5791.8552036199435;5812.82561523155;5872.882542349043;5972.5520585955355;6002.519798416029;6044.425779550936;6078.696065196634;6103.176071230327;6119.785807496716;6135.14108024868;5675.829725108253;5828.795992140254;5851;5853.385394533512;5877.794277200449;5759.913601296068;5768.53851691872;5956.344802071855;6038.788642397495;6096.268447786294;5807.390224026585;5811.070228763378;5816.82549523515;5817.650940943557;5825.79609713658;5620.690862002465;5800.912986305125;5801.622894511843;5805.245318108618;5807.332156802022;5619.915701264566;5633.8309850704545;5696.373398926197;5798.188253644409;5810.983077961424;5818.185454036354;5854.853628659147;5860.355360910382;5864.882702346014;5876.265466816648;5691.857703557444;5732.111522713819;5755.510781583613;5756.050251708589;5809.680467574324 07:00:08 INFO - 2015-11-25 07:00:08,182 DEBUG : BROWSER_OUTPUT: |1;ext.html;540.2342643337925;589.831897909096;602.2080963532952;604.2929772166565;612.8008397270883;31236.531452028208;32227;32233;40466.59533404665;40917.38623920643;42645;43069.353959690554;43149.35275970855;43212.3518147228;43230.6223157727;8587.012493563227;8746.475211487332;8754.6935857245;8757.386982911199;8760.780980475507;7686.730964416247;7876.173001834811;10368.963103689646;10646.62736804212;10657.573697052108;10344.069033786958;10421.791564168712;10426.113780328644;10439.582416703322;10448.791024179513;7651.387888968869;7854.253845884632;10256;10399.116075133623;10414.166866650688;7666.3100320970825;10492.737681557946;10761.56953721858;10769.83845242322;10771;39857.40213896794;40248.798756006036;40290;40335.39496907549;40345.79827100875;3284.967150328514;3291.8683252669866;3339.849706763177;3448.620651728308;3625.9484749422636;30548.236294092603;30783.538246926313;30919.227019324473;30995.22511937197;31448;3669.3880385290713;3683.9263214735824;3732.6267373262726;3757.3236817372863;3799.4680744695765;263121;265984;266715;266813;267015;52591.21113183305;53282.46717532853;57784.133238001465;58966;59146.70426647925;61239;61243.38756612466;61313.69343153345;61466.38533614696;61503.692481537306;13762.311884405739;13951.232682202373;13969;13976.930115349358;13982.930085349712;10415.479226038668;10518.579256829642;10533.525991330409;10559.36643801359;10560.419176945188;19598.118084686223;19654;19678.212871485266;19813.60372792565;19858;7633.69465221385;7811.140774514799;7857.528548287121;7878.803029924297;7923.484973476706;1039.7296702857311;1046.4872212615915;1050.3277902142481;1097.9945100274447;1156.9768604627861;27987.580186297222;28245;28747.56878646822;28787.85606071998;28832.855835720686;1044.38903241603;1106.8450416941635;1129.9887001130048;1139.549877798265;1183.6567395455393;13416;13502.582228866024;13679.794803077963;14178.929105354408;14413.135211887111;327040;327655;330610;331788;331865;51207.74396127996;51810.74094629503;51854.48145518572;51884.22173667398;51922.48077519176;1327.3694994877355;1329.0829327764006;1329.5413082486527;1342.2550484481012;1346.6633341664583;26018.219453416426;26604.600930985664;26786;26865.597016044383;27021.86489067542;44677.55322446734;44707.32939005983;44831.10337793291;44893.55106448894;45008.09983800371;299964;302227;302715;303404;304942;27622;27851.164465066082;31189.53215701721;31462.213444664067;31886.202844929063;41463.1707365857;41578;42042.789786050875;42546.36180457358;42577.787111064245;34622.82688586541;34739.305213896085;35003;35630.64369356377;36183.91448256557;29224.415511690073;33694.49458258079;34533.97182316592;35097.122571935906;35352.823235883654;32703.509447358785;32713.672863272015;33151.336973260884;33253;33444.49833252455;29692;32031.039068827973;33626.49560256647;33632.327353453285;33716.325673486885;24001;25584;25610.231693049238;25643;25979.74020259773 07:00:08 INFO - 2015-11-25 07:00:08,182 DEBUG : BROWSER_OUTPUT: |2;jquery.html;352.24443568545473;353.18766836276563;353.3815822310956;355.12461781708083;357.35675783589534;14040.71918561628;14237.71524569508;16562.83437165628;17744.467665970027;18801.81198188016;12980.156289841154;14093.92953035236;15461.22693865305;16245.26896289667;16253.18734063298;5108.821191258297;5261.447548007462;5266.710330931797;5266.973665131669;5270.1040823060175;3875.0312421894528;3998.6201310875567;5243.973780131094;5279.815206467755;5725.7995970141055;5820.796272130477;5824.301083869942;5854.063349864023;5862.706864656771;5881.500072493822;5691.06097493913;5834;5837.066069428893;5843.678597677126;5844.152597873306;5144.202648589475;5152;5154.896902061938;5160.406553246387;5160.587153027749;19748.210071597117;20347.287844925504;20368.490787730425;20697.793022069738;20891.477713057295;2224.5995720770256;2267.183813827021;2273.488465095357;2312.583734927712;2369.7985671217966;13277.933610331984;13337.933310333483;13357.599272021855;13552;13626.659333516742;1770.672425601255;1791.9193636286398;1839.5033340997857;1853;1864.4220291709614;56629;59242.40757592455;59985.40014599842;60584.697076514334;61110.0833487498;9313.627454901864;9577.760555986017;9623;9643.08390702882;9691.51542422876;12509.812352814715;12568.05739569536;12577.622671319876;12579.5597154101;12601.684957876127;7151.392131668867;7153.105861767279;7205.063341765537;7270.345668889825;7328.706851725978;6972.337627925339;7028.894566581505;7038.225795162528;7067.469939754539;7099;5780.450857168562;5824.854378640483;5868.413158684183;5876.529877609781;5909.29088509373;1660.8256133106092;1687.3419366447094;1701.3279754497153;1701.906395148254;1713.366054559821;930.6975233049286;941.0730430525927;962.0331566775346;1039.4542864995788;1046.9267151299337;16358;16925.7461138083;17300.95241866824;17976.550586235448;18184.545386365447;928.8838895138108;943.42451104826;959.3955807841015;998.0767789794411;1040.147079394889;10038.949805251073;10509.31689440184;10514.737131571772;10542.52558634848;10559.554260107432;556557;558678;559133;589789;599071;74159.62920185365;78310.60844695853;78634;79516.60241698755;80313.19686803057;1131.5926266544202;1133.9149563782748;1191.184038933316;1203.975920481603;1221.9389030548436;14853.108813471226;15258;15262.23688815577;15289.694206116037;15597.454089106826;33091;33266.83366583152;33392.49911251382;33779.4933076009;34805.47791783074;44892.55107448884;45195.32207016832;45369.77315113403;45551.54448455473;46503.30245046395;8891.688790892547;8984.23633991118;9188.586513607037;9532.761680958032;9544.761380965532;11046.613368532062;11079.667609971713;11308.43457827089;16888.83111168922;17875.28498860031;14857.925710371379;14886.10683359002;14988.250587470366;15010.399584016517;15169.24153792328;14474.131552106906;14477;14608.561743147722;14653.780193296896;14986.47547335838;14160.079594826715;14447.566573002827;14473.131612103307;14635.634109147357;15492.612684682974;8626;8871.733847984571;8879.556022198993;9380.859287110563;9721.951390243286;14718.190499522629;14765.852341476877;14768.261586920826;15087.773683394977;15209.087454752751 07:00:08 INFO - 2015-11-25 07:00:08,182 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1352.5942217334798;1684.5283320670212;1729.9999999999995;1753.1234382808595;1774.3434929076236;6624.171978502687;7712.383009359251;7857.214278572154;7862.646180921858;7868.055833300012;7622.237776222375;7811.960940195291;7821.7653470395835;7824.061112666488;7829.530228186299;1946.2993322403922;1973.9506512337164;1976.0317444452187;2263.196565219348;2299.4826164113024;2270.1713874435795;2327.150590034642;2327.5461285049423;2327.6159433693506;2329.4409341758023;2218.667199920009;2228.4317499037725;2287.6911616931666;2290.450291929933;2291.59897018022;1972.7928567500421;2008.9899550502228;2010.9195632174697;2232.162938897913;2265.9660105098437;2294;2300.0799680127916;2301.7698230177016;2302.861828290308;2303.9654405183937;7679.846403071963;7775.4168437367425;7782.455228134033;7789.221077892222;7790.065192176975;4647.512011238839;4661.627069834405;4680.859574212745;4700.788464519105;4717.858464246078;3187.1713354527756;3245.4158251514955;3259.348130373911;3291.1113999220074;3311.586051743532;1065.8934106589356;1068.1241382066708;1073.4257172412722;1094.9945250273777;1096.703889949717;10331.638392656221;10361.119304859021;10370.481475926174;10384.636537721219;10388.37669739818;4137.689673274516;4146.108586653823;4165.791710414467;4251.829926802893;4310.31035034393;8887.73336799897;8900.065493123258;8903.287736981027;8953.955230223808;9046.954765226132;4648.651351148677;4652.348671186037;4689.788959496831;4694.671373003857;4694.835680751157;4216.177845320133;4230.767307798101;4244.681648876347;4262.445882035316;4270.402143699823;3593.1196856769734;3663.8534458621357;3699.2786406650985;3710.22085362077;3719.8512059517857;1173.923694959842;1182.3378907811652;1184.3426898071652;1191.5591231244496;1194.737157825277;1008.7074748323051;1035.166690813887;1035.3942943378058;1167.4279602994472;1339.0091332413945;7246.058012458347;7268.164161121539;7341.559506429631;7342.412606991428;7344.963275183697;500.7546302311841;505.92664063710555;509.997450012755;511.58291532454894;511.9616028797855;5354.41101478837;5516.200150978086;5861.736221870026;5970.014947533695;5974.761009559656;9035.909640903506;9042.321825863086;9295.163435290857;9314.115159059878;9428.811423771485;9175.816483670289;9201.125893040151;9294.535273236312;9322.114399132071;9333.859992100124;7061.152661680529;7066.893996590055;7126.322999315057;7172.928270717331;7174.461915356474;7124.323189297009;7146.428285737024;7149.320814522612;7151.356377926011;7160.820979475347;7761.883571746315;7860.292573668397;7872.134065252703;8009.199080091944;8028.317593004426;7963.6814527418255;8289.626966786629;8368.456050356708;8411.53736544496;8416.4950102994;6686.632235227109;6745.089412929192;6796.082528858541;6827.419669327964;6843.452523797984;7431.182569917413;7484.887726684213;7485.1766305707415;7488.737894173678;7496.25037496246;6901.309869013058;6975;7020.473464490082;7052.33002864727;7149.499535032498;6864.141982252218;6869.862602748017;6897.275786042594;6946.895796562954;6966.895496567657;6794.966025169842;6957.304269573002;6960.5127641066665;7031.472639551952;7032.964835175791;6546.5417420782;6554.868902622016;6565.474762019123;6624.436922861418;6626.900596491153;5877.794277200277;6037.215162028908;6590.242122155822;6676.399124078856;6711.46308295345 07:00:08 INFO - 2015-11-25 07:00:08,183 DEBUG : BROWSER_OUTPUT: |4;prototype.html;342.385418174377;347.0542770949163;349.8215909885959;355.28942115768444;356.20743844944985;5815.127730840376;5900.468957793798;5933.3473317934995;5956.88086238275;5980.521558275338;5757.309122905257;5891.587588868781;5895.439933206349;5903.970480147604;5909.290885093784;4563.634909207272;4566.954330456686;4577.656675749332;4578.313253012058;4582.427196600425;4392.582704643054;4483.215437298476;4486.484054333744;4559.133764584735;4569.177548041352;4540.682152249344;4553.954460455386;4561.087782443508;4567.155076310876;4572.634189264867;4525.411696479437;4547.249703798886;4558.225101732697;4569.9314510282375;4577.519360467137;4024.396340548941;4025.3559430491273;4025.416314634391;4025.959740402617;4034.011667141569;5490.972545137249;5600.775968961237;5615.6630602164005;5616.269884914935;5656;5216;5221.451747566527;5223.6343455958095;5224.268602395668;5229.37247530295;5934.198883150762;6250.1874756281395;6252.093446450239;6256.186695729526;6260.467860231885;4183.288840897055;4272;4325.545817689129;4333.328334108218;4335.653147748204;6369.71336289868;6467.223933128054;6471.449926756231;6478.352164783484;6481;4434.068845542472;4445;4446.5553444655925;4448.065906159678;4448.132614140276;4370.67219958498;4490.730556166641;4496.482904465963;4498.145352383037;4533.591976722111;5207.062728708793;5219.608529360313;5221.686698798084;5222.843314700565;5229.267902493654;6255.249370075529;6297.6536290503545;6309.305976342599;6314.463270621956;6319.0837328587095;1551.1468692219235;1592.0845513829595;1592.6655402365634;1592.7610858371338;1593.6573636668172;1063.3301020357126;1063.739468729558;1071.7213524483534;1091.3124731419155;1097.8078836203633;6088.908666370008;6099.207103076572;6103.05402662588;6110.877782444415;6115.449609535074;6461.838454038592;6462.806115816533;6467.967660161669;6469.094326794253;6469.644169570719;6086.543509236825;6090.208272924491;6100.938990610037;6101.908471372933;6102.786402475892;3455.1880308128075;3532.116970757311;3534.8939531814085;3541.716662667032;3545.556805399325;6716.496262780213;6723.495737819585;6727.899081513781;6748.730050798012;6748.763793267212;6684.331566843374;6698;6698.497612679069;6702.832429189211;6702.932970670328;6571.244306904671;6737.663116844138;6737.797866064026;6738.764143254963;6742.325767423218;6287.025511045796;6458.418742313214;6534.313897040743;6535.901961470677;6539.280679125197;6357.80926572185;6482.481401487964;6499.0901273820755;6504.642244676399;6515.328715226734;6748.021536877223;6751.93248067513;6756.4257038150345;6760.22257440381;6761.729530818712;4345.283028300295;4355.803988820448;4362.0839623679385;4366.104948485617;4367.126574685012;6511.186101737283;6520.902186467112;6524.608523488606;6527.184101987252;6531.542792004514;6342.619442833445;6343.524235682435;6343.873122537616;6346.397092276226;6347.714352854225;6202.41077097675;6305.590136641287;6327.810165695037;6330.3669633036325;6332.398422149889;6123.112148738497;6172.629642221481;6181.041938499539;6200;6206.875862482635;6314.747410103544;6322.146510221061;6330.715117819617;6337.809865704036;6340.809775706736;6039.396060394101;6177.2587289525545;6184;6194.659293738708;6196.194494715838 07:00:08 INFO - 2015-11-25 07:00:08,184 DEBUG : BROWSER_OUTPUT: |5;yui.html;874.5670892908012;875.9737207883763;876.5354362188041;879.221659555803;880.4541184465633;13719.245441500725;13946.581602551927;13952.581422557314;13962.58112256634;13988.160710357386;12925.74148517029;12998;13005.93497032516;13009.674758131028;13019.414126364312;685.5817951049855;697.9371856532911;826.557791581504;843.995780021099;844.6410275632862;786.3394748411333;832.4672209785742;838.5513750143674;845.5222799118509;846.4032856835915;721.0482163544124;883.6553744039829;889.6797153024905;890.2432932007774;890.3011136258039;729.482067731911;729.8467321862415;731.2979539641976;732.150705181989;859.6002858670686;657.3492242679761;657.5002997721718;657.5857209957696;657.7829316325601;657.8454063295122;13240.470381184741;13366.732665346639;13371.398287077105;13371.799423008662;13397.12918660284;11624;12219.389030548526;12283.447244874002;12329.198602090835;12601.180923240052;10164.94917525415;10559.78880422395;10631.255812093157;10641.574337026586;10701.14390848738;3493.161641206092;3543.5924868639922;3546.8935931922083;3550.254446566224;3647.015305867401;48862.511374886155;48894.7555262225;48997.75501122472;49028.754856225845;49082.263766043536;10086.29395942277;10216.131628811634;10403.84394234087;10492;10579.206559508068;17082.572935676708;17219.569510762332;17230.396936107176;17275.654486910193;17393.65212695739;7426.777196684108;7528;7653.15815260321;7804.6878124874365;7915.683372665144;4297.914041719148;4330.501992270866;4381.605655490957;4387.27609944362;4485.865424037284;3039.893603723859;3071.846407679607;3073.6465306489595;3074.9692503074684;3076.138681169276;545.1033050631768;567.7785663591203;579.6290374160541;580.8489792653941;586.7330364684059;515.4150039704962;588.3616276340133;630.334996578614;642.6304874696974;646.1922596754057;8728.432651877607;8955.41789783662;8974.28205743539;9055.637774488947;9157.49633770149;587.8471597384711;624.1729708136737;663.1611012069735;683.4054372695787;690.5683947532792;4115.938260926088;4135.214309281227;4238.279492486285;4290.163418133434;4325;55974.72012639911;61241;61301;61363;61425.07862382068;50087;51615.225771613455;51721.48278517242;51865;53012;46798;47286.29070563944;47320.290195647096;48544.51455485471;48680.756596216794;8989.595468203815;9153.908460915305;9365.157135857533;9386.296027798078;9397.718068457958;35754.28491430209;35820.820895895355;35986.4602030975;36282.637173627925;36825.81587092047;40414.191716164925;46349.76825115853;46522;46626.76686616545;46638.533614664775;10417.114545263434;10463.79072418532;10673;10690.893091069302;10737.516811743333;12017.278963262232;12045.819312710491;12413.813792793297;12715.491380344683;13143.211407315592;11900.821487677866;11918.642440726791;11974.161808673309;12339.50641974311;12362.134650574733;10763.838542421714;10858.565657373616;11129.944350278196;11715.589954351557;11965.880341196476;10127.291089623655;10634.202434817265;10673.199510036624;10702.73243168927;10844.240903136704;10019;10130.898691012995;10135;10542.314749541256;10703.625373111903;8259.326366998626;9284.489353085375;9322.254219662547;9394.483303418105;9416.38793478449 07:00:08 INFO - 2015-11-25 07:00:08,184 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:00:08 INFO - 2015-11-25 07:00:08,184 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,916 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463608174__endTimestamp 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5480.02 Median:5692.57 stddev:620.10 (10.9%) stddev-sans-first:595.64 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: Values: 3199.5 3241.0 3281.0 3290.4 3310.7 5157.9 5262.6 5465.2 5496.5 5500.0 5460.3 5471.0 5528.1 5541.1 5601.4 4989.2 5008.4 5015.9 5032.3 5043.7 4812.8 5094.1 5105.7 5113.7 5180.1 4933.3 4950.5 5025.3 5033.9 5048.9 4942.4 4954.6 4960.6 4961.5 4995.8 4760.6 4930.7 4958.1 4960.4 5011.0 5142.6 5179.0 5221.7 5235.9 5266.8 5953.2 5955.6 5956.3 5960.3 5967.3 5751.0 5896.4 6002.8 6005.1 6011.0 5416.1 5492.8 5573.2 5576.8 5635.0 6082.8 6093.6 6093.6 6108.5 6113.8 5639.3 5677.4 5904.3 5910.9 5944.1 5773.7 5822.9 5892.2 5920.5 5957.4 5836.7 5859.7 5875.4 5877.7 5884.9 5881.1 5884.6 5885.2 5894.1 5908.9 4356.4 4475.2 4493.3 4559.2 4566.6 4024.0 4061.6 4070.4 4084.4 4109.4 5614.2 5615.4 5617.8 5619.4 5671.7 5625.4 5745.3 5750.7 5788.8 5965.3 5614.2 5660.6 5671.3 5689.7 5692.6 5199.1 5210.7 5212.2 5213.2 5216.4 5835.1 6117.7 6119.2 6122.6 6127.0 5791.9 5812.8 5872.9 5972.6 6002.5 6044.4 6078.7 6103.2 6119.8 6135.1 5675.8 5828.8 5851.0 5853.4 5877.8 5759.9 5768.5 5956.3 6038.8 6096.3 5807.4 5811.1 5816.8 5817.7 5825.8 5620.7 5800.9 5801.6 5805.2 5807.3 5619.9 5633.8 5696.4 5798.2 5811.0 5818.2 5854.9 5860.4 5864.9 5876.3 5691.9 5732.1 5755.5 5756.1 5809.7 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,185 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46509.29 Median:32468.25 stddev:78634.08 (242.2%) stddev-sans-first:78778.92 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: Values: 540.2 589.8 602.2 604.3 612.8 31236.5 32227.0 32233.0 40466.6 40917.4 42645.0 43069.4 43149.4 43212.4 43230.6 8587.0 8746.5 8754.7 8757.4 8760.8 7686.7 7876.2 10369.0 10646.6 10657.6 10344.1 10421.8 10426.1 10439.6 10448.8 7651.4 7854.3 10256.0 10399.1 10414.2 7666.3 10492.7 10761.6 10769.8 10771.0 39857.4 40248.8 40290.0 40335.4 40345.8 3285.0 3291.9 3339.8 3448.6 3625.9 30548.2 30783.5 30919.2 30995.2 31448.0 3669.4 3683.9 3732.6 3757.3 3799.5 263121.0 265984.0 266715.0 266813.0 267015.0 52591.2 53282.5 57784.1 58966.0 59146.7 61239.0 61243.4 61313.7 61466.4 61503.7 13762.3 13951.2 13969.0 13976.9 13982.9 10415.5 10518.6 10533.5 10559.4 10560.4 19598.1 19654.0 19678.2 19813.6 19858.0 7633.7 7811.1 7857.5 7878.8 7923.5 1039.7 1046.5 1050.3 1098.0 1157.0 27987.6 28245.0 28747.6 28787.9 28832.9 1044.4 1106.8 1130.0 1139.5 1183.7 13416.0 13502.6 13679.8 14178.9 14413.1 327040.0 327655.0 330610.0 331788.0 331865.0 51207.7 51810.7 51854.5 51884.2 51922.5 1327.4 1329.1 1329.5 1342.3 1346.7 26018.2 26604.6 26786.0 26865.6 27021.9 44677.6 44707.3 44831.1 44893.6 45008.1 299964.0 302227.0 302715.0 303404.0 304942.0 27622.0 27851.2 31189.5 31462.2 31886.2 41463.2 41578.0 42042.8 42546.4 42577.8 34622.8 34739.3 35003.0 35630.6 36183.9 29224.4 33694.5 34534.0 35097.1 35352.8 32703.5 32713.7 33151.3 33253.0 33444.5 29692.0 32031.0 33626.5 33632.3 33716.3 24001.0 25584.0 25610.2 25643.0 25979.7 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29645.27 Median:33586.00 stddev:93503.77 (278.4%) stddev-sans-first:93740.20 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: Values: 352.2 353.2 353.4 355.1 357.4 14040.7 14237.7 16562.8 17744.5 18801.8 12980.2 14093.9 15461.2 16245.3 16253.2 5108.8 5261.4 5266.7 5267.0 5270.1 3875.0 3998.6 5244.0 5279.8 5725.8 5820.8 5824.3 5854.1 5862.7 5881.5 5691.1 5834.0 5837.1 5843.7 5844.2 5144.2 5152.0 5154.9 5160.4 5160.6 19748.2 20347.3 20368.5 20697.8 20891.5 2224.6 2267.2 2273.5 2312.6 2369.8 13277.9 13337.9 13357.6 13552.0 13626.7 1770.7 1791.9 1839.5 1853.0 1864.4 56629.0 59242.4 59985.4 60584.7 61110.1 9313.6 9577.8 9623.0 9643.1 9691.5 12509.8 12568.1 12577.6 12579.6 12601.7 7151.4 7153.1 7205.1 7270.3 7328.7 6972.3 7028.9 7038.2 7067.5 7099.0 5780.5 5824.9 5868.4 5876.5 5909.3 1660.8 1687.3 1701.3 1701.9 1713.4 930.7 941.1 962.0 1039.5 1046.9 16358.0 16925.7 17301.0 17976.6 18184.5 928.9 943.4 959.4 998.1 1040.1 10038.9 10509.3 10514.7 10542.5 10559.6 556557.0 558678.0 559133.0 589789.0 599071.0 74159.6 78310.6 78634.0 79516.6 80313.2 1131.6 1133.9 1191.2 1204.0 1221.9 14853.1 15258.0 15262.2 15289.7 15597.5 33091.0 33266.8 33392.5 33779.5 34805.5 44892.6 45195.3 45369.8 45551.5 46503.3 8891.7 8984.2 9188.6 9532.8 9544.8 11046.6 11079.7 11308.4 16888.8 17875.3 14857.9 14886.1 14988.3 15010.4 15169.2 14474.1 14477.0 14608.6 14653.8 14986.5 14160.1 14447.6 14473.1 14635.6 15492.6 8626.0 8871.7 8879.6 9380.9 9722.0 14718.2 14765.9 14768.3 15087.8 15209.1 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,187 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5386.00 Median:6005.99 stddev:2789.39 (46.4%) stddev-sans-first:2780.74 07:00:08 INFO - 2015-11-25 07:00:08,188 DEBUG : BROWSER_OUTPUT: Values: 1352.6 1684.5 1730.0 1753.1 1774.3 6624.2 7712.4 7857.2 7862.6 7868.1 7622.2 7812.0 7821.8 7824.1 7829.5 1946.3 1974.0 1976.0 2263.2 2299.5 2270.2 2327.2 2327.5 2327.6 2329.4 2218.7 2228.4 2287.7 2290.5 2291.6 1972.8 2009.0 2010.9 2232.2 2266.0 2294.0 2300.1 2301.8 2302.9 2304.0 7679.8 7775.4 7782.5 7789.2 7790.1 4647.5 4661.6 4680.9 4700.8 4717.9 3187.2 3245.4 3259.3 3291.1 3311.6 1065.9 1068.1 1073.4 1095.0 1096.7 10331.6 10361.1 10370.5 10384.6 10388.4 4137.7 4146.1 4165.8 4251.8 4310.3 8887.7 8900.1 8903.3 8954.0 9047.0 4648.7 4652.3 4689.8 4694.7 4694.8 4216.2 4230.8 4244.7 4262.4 4270.4 3593.1 3663.9 3699.3 3710.2 3719.9 1173.9 1182.3 1184.3 1191.6 1194.7 1008.7 1035.2 1035.4 1167.4 1339.0 7246.1 7268.2 7341.6 7342.4 7345.0 500.8 505.9 510.0 511.6 512.0 5354.4 5516.2 5861.7 5970.0 5974.8 9035.9 9042.3 9295.2 9314.1 9428.8 9175.8 9201.1 9294.5 9322.1 9333.9 7061.2 7066.9 7126.3 7172.9 7174.5 7124.3 7146.4 7149.3 7151.4 7160.8 7761.9 7860.3 7872.1 8009.2 8028.3 7963.7 8289.6 8368.5 8411.5 8416.5 6686.6 6745.1 6796.1 6827.4 6843.5 7431.2 7484.9 7485.2 7488.7 7496.3 6901.3 6975.0 7020.5 7052.3 7149.5 6864.1 6869.9 6897.3 6946.9 6966.9 6795.0 6957.3 6960.5 7031.5 7033.0 6546.5 6554.9 6565.5 6624.4 6626.9 5877.8 6037.2 6590.2 6676.4 6711.5 07:00:08 INFO - 2015-11-25 07:00:08,188 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,188 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5261.75 Median:6009.96 stddev:1586.29 (26.4%) stddev-sans-first:1547.17 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: Values: 342.4 347.1 349.8 355.3 356.2 5815.1 5900.5 5933.3 5956.9 5980.5 5757.3 5891.6 5895.4 5904.0 5909.3 4563.6 4567.0 4577.7 4578.3 4582.4 4392.6 4483.2 4486.5 4559.1 4569.2 4540.7 4554.0 4561.1 4567.2 4572.6 4525.4 4547.2 4558.2 4569.9 4577.5 4024.4 4025.4 4025.4 4026.0 4034.0 5491.0 5600.8 5615.7 5616.3 5656.0 5216.0 5221.5 5223.6 5224.3 5229.4 5934.2 6250.2 6252.1 6256.2 6260.5 4183.3 4272.0 4325.5 4333.3 4335.7 6369.7 6467.2 6471.4 6478.4 6481.0 4434.1 4445.0 4446.6 4448.1 4448.1 4370.7 4490.7 4496.5 4498.1 4533.6 5207.1 5219.6 5221.7 5222.8 5229.3 6255.2 6297.7 6309.3 6314.5 6319.1 1551.1 1592.1 1592.7 1592.8 1593.7 1063.3 1063.7 1071.7 1091.3 1097.8 6088.9 6099.2 6103.1 6110.9 6115.4 6461.8 6462.8 6468.0 6469.1 6469.6 6086.5 6090.2 6100.9 6101.9 6102.8 3455.2 3532.1 3534.9 3541.7 3545.6 6716.5 6723.5 6727.9 6748.7 6748.8 6684.3 6698.0 6698.5 6702.8 6702.9 6571.2 6737.7 6737.8 6738.8 6742.3 6287.0 6458.4 6534.3 6535.9 6539.3 6357.8 6482.5 6499.1 6504.6 6515.3 6748.0 6751.9 6756.4 6760.2 6761.7 4345.3 4355.8 4362.1 4366.1 4367.1 6511.2 6520.9 6524.6 6527.2 6531.5 6342.6 6343.5 6343.9 6346.4 6347.7 6202.4 6305.6 6327.8 6330.4 6332.4 6123.1 6172.6 6181.0 6200.0 6206.9 6314.7 6322.1 6330.7 6337.8 6340.8 6039.4 6177.3 6184.0 6194.7 6196.2 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14023.78 Median:25417.82 stddev:16380.56 (64.4%) stddev-sans-first:16396.75 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: Values: 874.6 876.0 876.5 879.2 880.5 13719.2 13946.6 13952.6 13962.6 13988.2 12925.7 12998.0 13005.9 13009.7 13019.4 685.6 697.9 826.6 844.0 844.6 786.3 832.5 838.6 845.5 846.4 721.0 883.7 889.7 890.2 890.3 729.5 729.8 731.3 732.2 859.6 657.3 657.5 657.6 657.8 657.8 13240.5 13366.7 13371.4 13371.8 13397.1 11624.0 12219.4 12283.4 12329.2 12601.2 10164.9 10559.8 10631.3 10641.6 10701.1 3493.2 3543.6 3546.9 3550.3 3647.0 48862.5 48894.8 48997.8 49028.8 49082.3 10086.3 10216.1 10403.8 10492.0 10579.2 17082.6 17219.6 17230.4 17275.7 17393.7 7426.8 7528.0 7653.2 7804.7 7915.7 4297.9 4330.5 4381.6 4387.3 4485.9 3039.9 3071.8 3073.6 3075.0 3076.1 545.1 567.8 579.6 580.8 586.7 515.4 588.4 630.3 642.6 646.2 8728.4 8955.4 8974.3 9055.6 9157.5 587.8 624.2 663.2 683.4 690.6 4115.9 4135.2 4238.3 4290.2 4325.0 55974.7 61241.0 61301.0 61363.0 61425.1 50087.0 51615.2 51721.5 51865.0 53012.0 46798.0 47286.3 47320.3 48544.5 48680.8 8989.6 9153.9 9365.2 9386.3 9397.7 35754.3 35820.8 35986.5 36282.6 36825.8 40414.2 46349.8 46522.0 46626.8 46638.5 10417.1 10463.8 10673.0 10690.9 10737.5 12017.3 12045.8 12413.8 12715.5 13143.2 11900.8 11918.6 11974.2 12339.5 12362.1 10763.8 10858.6 11129.9 11715.6 11965.9 10127.3 10634.2 10673.2 10702.7 10844.2 10019.0 10130.9 10135.0 10542.3 10703.6 8259.3 9284.5 9322.3 9394.5 9416.4 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:00:08 INFO - 2015-11-25 07:00:08,189 DEBUG : BROWSER_OUTPUT: 07:00:08 INFO - 2015-11-25 07:00:08,898 INFO : Browser exited with error code: 0 07:00:08 INFO - 2015-11-25 07:00:08,919 INFO : Completed test dromaeo_css (00:18:33) 07:00:08 INFO - 2015-11-25 07:00:08,919 INFO : Starting test kraken 07:00:08 INFO - 2015-11-25 07:00:08,919 DEBUG : operating with platform_type : linux_ 07:00:08 INFO - 2015-11-25 07:00:08,920 INFO : Initialising browser for kraken test... 07:00:08 INFO - 2015-11-25 07:00:08,926 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpl2hbqu/profile http://localhost:35388/getInfo.html 07:00:12 INFO - 2015-11-25 07:00:12,464 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:00:12 INFO - 2015-11-25 07:00:12,464 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:00:12 INFO - 2015-11-25 07:00:12,464 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:00:12 INFO - 2015-11-25 07:00:12,464 DEBUG : BROWSER_OUTPUT: __metrics 07:00:12 INFO - 2015-11-25 07:00:12,561 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:13 INFO - 2015-11-25 07:00:13,696 INFO : Browser initialized. 07:00:13 INFO - 2015-11-25 07:00:13,697 INFO : Running cycle 1/1 for kraken test... 07:00:13 INFO - 2015-11-25 07:00:13,697 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpl2hbqu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:00:25 INFO - 2015-11-25 07:00:25,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 139276288 07:00:25 INFO - 2015-11-25 07:00:25,437 DEBUG : BROWSER_OUTPUT: 07:00:27 INFO - 2015-11-25 07:00:27,923 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35388/tests/kraken/driver.html?testName=audio-beat-detection) 07:00:28 INFO - 2015-11-25 07:00:28,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 177070080 07:00:28 INFO - 2015-11-25 07:00:28,498 DEBUG : BROWSER_OUTPUT: 07:00:31 INFO - 2015-11-25 07:00:31,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35388/tests/kraken/driver.html?testName=audio-dft) 07:00:31 INFO - 2015-11-25 07:00:31,663 DEBUG : BROWSER_OUTPUT: RSS: Main: 164532224 07:00:31 INFO - 2015-11-25 07:00:31,663 DEBUG : BROWSER_OUTPUT: 07:00:34 INFO - 2015-11-25 07:00:34,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35388/tests/kraken/driver.html?testName=audio-fft) 07:00:35 INFO - 2015-11-25 07:00:35,135 DEBUG : BROWSER_OUTPUT: RSS: Main: 149667840 07:00:35 INFO - 2015-11-25 07:00:35,135 DEBUG : BROWSER_OUTPUT: 07:00:37 INFO - 2015-11-25 07:00:37,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35388/tests/kraken/driver.html?testName=audio-oscillator) 07:00:37 INFO - 2015-11-25 07:00:37,673 DEBUG : BROWSER_OUTPUT: RSS: Main: 152682496 07:00:37 INFO - 2015-11-25 07:00:37,674 DEBUG : BROWSER_OUTPUT: 07:00:39 INFO - 2015-11-25 07:00:39,840 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:00:40 INFO - 2015-11-25 07:00:40,403 DEBUG : BROWSER_OUTPUT: RSS: Main: 154292224 07:00:40 INFO - 2015-11-25 07:00:40,403 DEBUG : BROWSER_OUTPUT: 07:00:43 INFO - 2015-11-25 07:00:43,307 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35388/tests/kraken/driver.html?testName=imaging-darkroom) 07:00:43 INFO - 2015-11-25 07:00:43,876 DEBUG : BROWSER_OUTPUT: RSS: Main: 155668480 07:00:43 INFO - 2015-11-25 07:00:43,876 DEBUG : BROWSER_OUTPUT: 07:00:47 INFO - 2015-11-25 07:00:47,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35388/tests/kraken/driver.html?testName=imaging-desaturate) 07:00:47 INFO - 2015-11-25 07:00:47,829 DEBUG : BROWSER_OUTPUT: RSS: Main: 156733440 07:00:47 INFO - 2015-11-25 07:00:47,829 DEBUG : BROWSER_OUTPUT: 07:00:50 INFO - 2015-11-25 07:00:50,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35388/tests/kraken/driver.html?testName=json-parse-financial) 07:00:51 INFO - 2015-11-25 07:00:51,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 156381184 07:00:51 INFO - 2015-11-25 07:00:51,221 DEBUG : BROWSER_OUTPUT: 07:00:52 INFO - 2015-11-25 07:00:52,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:00:53 INFO - 2015-11-25 07:00:53,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 166125568 07:00:53 INFO - 2015-11-25 07:00:53,335 DEBUG : BROWSER_OUTPUT: 07:00:55 INFO - 2015-11-25 07:00:55,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:00:55 INFO - 2015-11-25 07:00:55,760 DEBUG : BROWSER_OUTPUT: RSS: Main: 163688448 07:00:55 INFO - 2015-11-25 07:00:55,761 DEBUG : BROWSER_OUTPUT: 07:00:57 INFO - 2015-11-25 07:00:57,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:00:58 INFO - 2015-11-25 07:00:58,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 168415232 07:00:58 INFO - 2015-11-25 07:00:58,385 DEBUG : BROWSER_OUTPUT: 07:01:00 INFO - 2015-11-25 07:01:00,784 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:01:01 INFO - 2015-11-25 07:01:01,367 DEBUG : BROWSER_OUTPUT: RSS: Main: 178692096 07:01:01 INFO - 2015-11-25 07:01:01,367 DEBUG : BROWSER_OUTPUT: 07:01:03 INFO - 2015-11-25 07:01:03,901 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:01:04 INFO - 2015-11-25 07:01:04,463 DEBUG : BROWSER_OUTPUT: RSS: Main: 175644672 07:01:04 INFO - 2015-11-25 07:01:04,463 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35388/tests/kraken/driver.html?testName=ai-astar) 07:01:06 INFO - 2015-11-25 07:01:06,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 234463232 07:01:06 INFO - 2015-11-25 07:01:06,362 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,363 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:01:06 INFO - 2015-11-25 07:01:06,363 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:01:06 INFO - 2015-11-25 07:01:06,363 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;112;109;110;99;109;110;101 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;123;147;162;134;155;148;125;162;125 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;167;160;163;162;159;160;161;163 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;82;91;73;72;70;99;94;72 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;94;136;92;93;92;137;93;93;142 07:01:06 INFO - 2015-11-25 07:01:06,364 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;108;110;111;110;109;109;109 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;146;145;146;146;146;146 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;96;96;96;95;96;95 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;59;58;60;58;60;58;58;57 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;71;71;71;72;71;71;70;71;71 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;86;86;86;88;89;86;85;84 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;116;117;117;117;117;126;120;123;123 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;150;162;158;142;139;158;150;149;137 07:01:06 INFO - 2015-11-25 07:01:06,365 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;68;67;67;66;66;65;66;67;66 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,912 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463666362__endTimestamp 07:01:06 INFO - 2015-11-25 07:01:06,366 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,374 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:01:06 INFO - 2015-11-25 07:01:06,374 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:01:06 INFO - 2015-11-25 07:01:06,374 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,374 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:110.00 stddev:4.27 (3.9%) stddev-sans-first:4.52 07:01:06 INFO - 2015-11-25 07:01:06,374 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 112.0 109.0 110.0 99.0 109.0 110.0 101.0 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.80 Median:151.50 stddev:15.73 (10.4%) stddev-sans-first:15.94 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: Values: 157.0 123.0 147.0 162.0 134.0 155.0 148.0 125.0 162.0 125.0 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.90 Median:162.50 stddev:2.28 (1.4%) stddev-sans-first:2.39 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 167.0 160.0 163.0 162.0 159.0 160.0 161.0 163.0 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.60 Median:77.50 stddev:11.07 (14.3%) stddev-sans-first:11.39 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 82.0 91.0 73.0 72.0 70.0 99.0 94.0 72.0 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:92.50 stddev:22.10 (23.9%) stddev-sans-first:22.81 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 136.0 92.0 93.0 92.0 137.0 93.0 93.0 142.0 07:01:06 INFO - 2015-11-25 07:01:06,375 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.85 (0.8%) stddev-sans-first:0.88 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 108.0 110.0 111.0 110.0 109.0 109.0 109.0 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 59.0 58.0 60.0 58.0 60.0 58.0 58.0 57.0 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 71.0 72.0 71.0 71.0 70.0 71.0 71.0 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,376 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 86.0 86.0 86.0 88.0 89.0 86.0 85.0 84.0 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:119.50 stddev:3.41 (2.9%) stddev-sans-first:3.61 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: Values: 119.0 116.0 117.0 117.0 117.0 117.0 126.0 120.0 123.0 123.0 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.50 Median:150.00 stddev:8.85 (5.9%) stddev-sans-first:8.83 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: Values: 140.0 150.0 162.0 158.0 142.0 139.0 158.0 150.0 149.0 137.0 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 67.0 67.0 66.0 66.0 65.0 66.0 67.0 66.0 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:01:06 INFO - 2015-11-25 07:01:06,377 DEBUG : BROWSER_OUTPUT: 07:01:07 INFO - 2015-11-25 07:01:07,242 INFO : Browser exited with error code: 0 07:01:07 INFO - 2015-11-25 07:01:07,296 INFO : Completed test kraken (00:00:58) 07:01:07 INFO - 2015-11-25 07:01:07,296 INFO : Starting test v8_7 07:01:07 INFO - 2015-11-25 07:01:07,296 DEBUG : operating with platform_type : linux_ 07:01:07 INFO - 2015-11-25 07:01:07,296 INFO : Initialising browser for v8_7 test... 07:01:07 INFO - 2015-11-25 07:01:07,302 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZRYwA8/profile http://localhost:35388/getInfo.html 07:01:10 INFO - 2015-11-25 07:01:10,834 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:01:10 INFO - 2015-11-25 07:01:10,834 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:01:10 INFO - 2015-11-25 07:01:10,834 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:01:10 INFO - 2015-11-25 07:01:10,834 DEBUG : BROWSER_OUTPUT: __metrics 07:01:10 INFO - 2015-11-25 07:01:10,922 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:01:12 INFO - 2015-11-25 07:01:12,022 INFO : Browser initialized. 07:01:12 INFO - 2015-11-25 07:01:12,023 INFO : Running cycle 1/1 for v8_7 test... 07:01:12 INFO - 2015-11-25 07:01:12,023 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZRYwA8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:01:23 INFO - 2015-11-25 07:01:23,818 DEBUG : BROWSER_OUTPUT: RSS: Main: 139161600 07:01:23 INFO - 2015-11-25 07:01:23,819 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35388/tests/v8_7/run.html (next: http://localhost:35388/tests/v8_7/run.html) 07:01:45 INFO - 2015-11-25 07:01:45,527 DEBUG : BROWSER_OUTPUT: RSS: Main: 624250880 07:01:45 INFO - 2015-11-25 07:01:45,527 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,527 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:01:45 INFO - 2015-11-25 07:01:45,527 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:01:45 INFO - 2015-11-25 07:01:45,527 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:01:45 INFO - 2015-11-25 07:01:45,528 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:01:45 INFO - 2015-11-25 07:01:45,528 DEBUG : BROWSER_OUTPUT: |0;Richards;165.7000828500414 07:01:45 INFO - 2015-11-25 07:01:45,528 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.55252918287937 07:01:45 INFO - 2015-11-25 07:01:45,528 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.28611898017 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5159.79381443299 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1998.0039920159682 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |5;Earley;798.7220447284345 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |7;RegExp;26025.641025641027 07:01:45 INFO - 2015-11-25 07:01:45,529 DEBUG : BROWSER_OUTPUT: |8;Splay;658.0811332904057 07:01:45 INFO - 2015-11-25 07:01:45,530 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 07:01:45 INFO - 2015-11-25 07:01:45,530 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:01:45 INFO - 2015-11-25 07:01:45,530 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463705524__endTimestamp 07:01:45 INFO - 2015-11-25 07:01:45,530 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.70 Median:165.70 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: Values: 165.7 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,531 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.55 Median:194.55 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: Values: 194.6 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.29 Median:283.29 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: Values: 283.3 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5159.79 Median:5159.79 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: Values: 5159.8 07:01:45 INFO - 2015-11-25 07:01:45,532 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1998.00 Median:1998.00 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: Values: 1998.0 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:798.72 Median:798.72 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: Values: 798.7 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,533 DEBUG : BROWSER_OUTPUT: Values: 12219.5 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26025.64 Median:26025.64 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: Values: 26025.6 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:658.08 Median:658.08 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,534 DEBUG : BROWSER_OUTPUT: Values: 658.1 07:01:45 INFO - 2015-11-25 07:01:45,535 DEBUG : BROWSER_OUTPUT: 07:01:45 INFO - 2015-11-25 07:01:45,535 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 07:01:45 INFO - 2015-11-25 07:01:45,535 DEBUG : BROWSER_OUTPUT: Values: 4854.4 07:01:45 INFO - 2015-11-25 07:01:45,535 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:01:45 INFO - 2015-11-25 07:01:45,535 DEBUG : BROWSER_OUTPUT: 07:01:46 INFO - 2015-11-25 07:01:46,266 INFO : Browser exited with error code: 0 07:01:46 INFO - 2015-11-25 07:01:46,283 INFO : Completed test v8_7 (00:00:38) 07:01:46 INFO - 2015-11-25 07:01:46,475 INFO : Completed test suite (00:20:10) 07:01:46 INFO - 2015-11-25 07:01:46,476 DEBUG : Working with test: dromaeo_css 07:01:46 INFO - 2015-11-25 07:01:46,476 DEBUG : Generating results file: dromaeo_css 07:01:46 INFO - 2015-11-25 07:01:46,477 DEBUG : Working with test: kraken 07:01:46 INFO - 2015-11-25 07:01:46,477 DEBUG : Generating results file: kraken 07:01:46 INFO - 2015-11-25 07:01:46,477 INFO : javascript benchmark 07:01:46 INFO - 2015-11-25 07:01:46,477 DEBUG : Working with test: v8_7 07:01:46 INFO - 2015-11-25 07:01:46,477 DEBUG : Generating results file: v8_7 07:01:46 INFO - 2015-11-25 07:01:46,478 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:01:46 INFO - 2015-11-25 07:01:46,633 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 07:01:46 INFO - 2015-11-25 07:01:46,633 DEBUG : process_Request line: dromaeo_css 7008.87 graph.html#tests=[[72,1,35]] 07:01:46 INFO - 2015-11-25 07:01:46,633 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:01:46 INFO - 2015-11-25 07:01:46,756 DEBUG : process_Request line: kraken 1499.80 graph.html#tests=[[232,1,35]] 07:01:46 INFO - 2015-11-25 07:01:46,756 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:01:46 INFO - 2015-11-25 07:01:46,789 DEBUG : process_Request line: v8_7 19547.46 graph.html#tests=[[230,1,35]] 07:01:46 INFO - 2015-11-25 07:01:46,790 INFO : javascript benchmark 07:01:46 INFO - 2015-11-25 07:01:46,794 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3199.520071989202, 3240.9837950810243, 3281.048495936179, 3290.42417576924, 3310.7351411887043, 5157.871053223662, 5262.605304602151, 5465.207544905991, 5496.477834605716, 5500.037493438647, 5460.31746031746, 5471.0, 5528.06022976094, 5541.113421852504, 5601.411851755568, 4989.176785830343, 5008.373953255843, 5015.949840501585, 5032.345795046639, 5043.747812609373, 4812.759362031885, 5094.133997220481, 5105.693658380491, 5113.744312784364, 5180.11937970544, 4933.260010998361, 4950.504949505057, 5025.321581586476, 5033.899322013576, 5048.873778155576, 4942.382202224722, 4954.5540901319055, 4960.627952903511, 4961.5038496150455, 4995.800167993276, 4760.571548560646, 4930.704157750546, 4958.107540642683, 4960.355153830015, 5011.0228505441455, 5142.562882155022, 5178.974105129488, 5221.71280579568, 5235.921461178085, 5266.815661451869, 5953.166556682068, 5955.583109182359, 5956.255468066492, 5960.284765828085, 5967.254093238345, 5751.0, 5896.351401345849, 6002.789902353396, 6005.099235114724, 6011.008183649693, 5416.1063424534905, 5492.752826122834, 5573.219749235111, 5576.804811831566, 5635.0138725723245, 6082.787102451393, 6093.6039157454625, 6093.6039157454625, 6108.450239478469, 6113.786017489367, 5639.3232812062815, 5677.432256774372, 5904.291485021825, 5910.852228694317, 5944.108383742387, 5773.682447465346, 5822.912656310159, 5892.234009578813, 5920.467157955804, 5957.4042595740075, 5836.708164591753, 5859.677717725481, 5875.383084776037, 5877.706114694248, 5884.882302353929, 5881.05903055509, 5884.558658100659, 5885.205497257815, 5894.086416605433, 5908.881822363614, 4356.4336636237085, 4475.194464996267, 4493.348464472633, 4559.247724125548, 4566.589006989387, 4023.979880100639, 4061.634452899253, 4070.430139780433, 4084.387341898739, 4109.383592461155, 5614.214010038598, 5615.410381909923, 5617.8033768817895, 5619.4099619540175, 5671.716414179275, 5625.4374562544235, 5745.2818397700285, 5750.741216645178, 5788.7684492620665, 5965.3139888912465, 5614.214010038598, 5660.632058916157, 5671.291088613923, 5689.743961521742, 5692.57305702074, 5199.142141546602, 5210.739463026834, 5212.192110222845, 5213.165893457029, 5216.374035115735, 5835.095560188178, 6117.724702388403, 6119.2350956130485, 6122.571420000646, 6126.999999999911, 5791.8552036199435, 5812.82561523155, 5872.882542349043, 5972.5520585955355, 6002.519798416029, 6044.425779550936, 6078.696065196634, 6103.176071230327, 6119.785807496716, 6135.14108024868, 5675.829725108253, 5828.795992140254, 5851.0, 5853.385394533512, 5877.794277200449, 5759.913601296068, 5768.53851691872, 5956.344802071855, 6038.788642397495, 6096.268447786294, 5807.390224026585, 5811.070228763378, 5816.82549523515, 5817.650940943557, 5825.79609713658, 5620.690862002465, 5800.912986305125, 5801.622894511843, 5805.245318108618, 5807.332156802022, 5619.915701264566, 5633.8309850704545, 5696.373398926197, 5798.188253644409, 5810.983077961424, 5818.185454036354, 5854.853628659147, 5860.355360910382, 5864.882702346014, 5876.265466816648, 5691.857703557444, 5732.111522713819, 5755.510781583613, 5756.050251708589, 5809.680467574324], "unit": "ms", "name": "dojo.html", "value": 5439.148245998559}, {"lowerIsBetter": false, "replicates": [540.2342643337925, 589.831897909096, 602.2080963532952, 604.2929772166565, 612.8008397270883, 31236.531452028208, 32227.0, 32233.0, 40466.59533404665, 40917.38623920643, 42645.0, 43069.353959690554, 43149.35275970855, 43212.3518147228, 43230.6223157727, 8587.012493563227, 8746.475211487332, 8754.6935857245, 8757.386982911199, 8760.780980475507, 7686.730964416247, 7876.173001834811, 10368.963103689646, 10646.62736804212, 10657.573697052108, 10344.069033786958, 10421.791564168712, 10426.113780328644, 10439.582416703322, 10448.791024179513, 7651.387888968869, 7854.253845884632, 10256.0, 10399.116075133623, 10414.166866650688, 7666.3100320970825, 10492.737681557946, 10761.56953721858, 10769.83845242322, 10771.0, 39857.40213896794, 40248.798756006036, 40290.0, 40335.39496907549, 40345.79827100875, 3284.967150328514, 3291.8683252669866, 3339.849706763177, 3448.620651728308, 3625.9484749422636, 30548.236294092603, 30783.538246926313, 30919.227019324473, 30995.22511937197, 31448.0, 3669.3880385290713, 3683.9263214735824, 3732.6267373262726, 3757.3236817372863, 3799.4680744695765, 263121.0, 265984.0, 266715.0, 266813.0, 267015.0, 52591.21113183305, 53282.46717532853, 57784.133238001465, 58966.0, 59146.70426647925, 61239.0, 61243.38756612466, 61313.69343153345, 61466.38533614696, 61503.692481537306, 13762.311884405739, 13951.232682202373, 13969.0, 13976.930115349358, 13982.930085349712, 10415.479226038668, 10518.579256829642, 10533.525991330409, 10559.36643801359, 10560.419176945188, 19598.118084686223, 19654.0, 19678.212871485266, 19813.60372792565, 19858.0, 7633.69465221385, 7811.140774514799, 7857.528548287121, 7878.803029924297, 7923.484973476706, 1039.7296702857311, 1046.4872212615915, 1050.3277902142481, 1097.9945100274447, 1156.9768604627861, 27987.580186297222, 28245.0, 28747.56878646822, 28787.85606071998, 28832.855835720686, 1044.38903241603, 1106.8450416941635, 1129.9887001130048, 1139.549877798265, 1183.6567395455393, 13416.0, 13502.582228866024, 13679.794803077963, 14178.929105354408, 14413.135211887111, 327040.0, 327655.0, 330610.0, 331788.0, 331865.0, 51207.74396127996, 51810.74094629503, 51854.48145518572, 51884.22173667398, 51922.48077519176, 1327.3694994877355, 1329.0829327764006, 1329.5413082486527, 1342.2550484481012, 1346.6633341664583, 26018.219453416426, 26604.600930985664, 26786.0, 26865.597016044383, 27021.86489067542, 44677.55322446734, 44707.32939005983, 44831.10337793291, 44893.55106448894, 45008.09983800371, 299964.0, 302227.0, 302715.0, 303404.0, 304942.0, 27622.0, 27851.164465066082, 31189.53215701721, 31462.213444664067, 31886.202844929063, 41463.1707365857, 41578.0, 42042.789786050875, 42546.36180457358, 42577.787111064245, 34622.82688586541, 34739.305213896085, 35003.0, 35630.64369356377, 36183.91448256557, 29224.415511690073, 33694.49458258079, 34533.97182316592, 35097.122571935906, 35352.823235883654, 32703.509447358785, 32713.672863272015, 33151.336973260884, 33253.0, 33444.49833252455, 29692.0, 32031.039068827973, 33626.49560256647, 33632.327353453285, 33716.325673486885, 24001.0, 25584.0, 25610.231693049238, 25643.0, 25979.74020259773], "unit": "ms", "name": "ext.html", "value": 18457.18961676436}, {"lowerIsBetter": false, "replicates": [352.24443568545473, 353.18766836276563, 353.3815822310956, 355.12461781708083, 357.35675783589534, 14040.71918561628, 14237.71524569508, 16562.83437165628, 17744.467665970027, 18801.81198188016, 12980.156289841154, 14093.92953035236, 15461.22693865305, 16245.26896289667, 16253.18734063298, 5108.821191258297, 5261.447548007462, 5266.710330931797, 5266.973665131669, 5270.1040823060175, 3875.0312421894528, 3998.6201310875567, 5243.973780131094, 5279.815206467755, 5725.7995970141055, 5820.796272130477, 5824.301083869942, 5854.063349864023, 5862.706864656771, 5881.500072493822, 5691.06097493913, 5834.0, 5837.066069428893, 5843.678597677126, 5844.152597873306, 5144.202648589475, 5152.0, 5154.896902061938, 5160.406553246387, 5160.587153027749, 19748.210071597117, 20347.287844925504, 20368.490787730425, 20697.793022069738, 20891.477713057295, 2224.5995720770256, 2267.183813827021, 2273.488465095357, 2312.583734927712, 2369.7985671217966, 13277.933610331984, 13337.933310333483, 13357.599272021855, 13552.0, 13626.659333516742, 1770.672425601255, 1791.9193636286398, 1839.5033340997857, 1853.0, 1864.4220291709614, 56629.0, 59242.40757592455, 59985.40014599842, 60584.697076514334, 61110.0833487498, 9313.627454901864, 9577.760555986017, 9623.0, 9643.08390702882, 9691.51542422876, 12509.812352814715, 12568.05739569536, 12577.622671319876, 12579.5597154101, 12601.684957876127, 7151.392131668867, 7153.105861767279, 7205.063341765537, 7270.345668889825, 7328.706851725978, 6972.337627925339, 7028.894566581505, 7038.225795162528, 7067.469939754539, 7099.0, 5780.450857168562, 5824.854378640483, 5868.413158684183, 5876.529877609781, 5909.29088509373, 1660.8256133106092, 1687.3419366447094, 1701.3279754497153, 1701.906395148254, 1713.366054559821, 930.6975233049286, 941.0730430525927, 962.0331566775346, 1039.4542864995788, 1046.9267151299337, 16358.0, 16925.7461138083, 17300.95241866824, 17976.550586235448, 18184.545386365447, 928.8838895138108, 943.42451104826, 959.3955807841015, 998.0767789794411, 1040.147079394889, 10038.949805251073, 10509.31689440184, 10514.737131571772, 10542.52558634848, 10559.554260107432, 556557.0, 558678.0, 559133.0, 589789.0, 599071.0, 74159.62920185365, 78310.60844695853, 78634.0, 79516.60241698755, 80313.19686803057, 1131.5926266544202, 1133.9149563782748, 1191.184038933316, 1203.975920481603, 1221.9389030548436, 14853.108813471226, 15258.0, 15262.23688815577, 15289.694206116037, 15597.454089106826, 33091.0, 33266.83366583152, 33392.49911251382, 33779.4933076009, 34805.47791783074, 44892.55107448884, 45195.32207016832, 45369.77315113403, 45551.54448455473, 46503.30245046395, 8891.688790892547, 8984.23633991118, 9188.586513607037, 9532.761680958032, 9544.761380965532, 11046.613368532062, 11079.667609971713, 11308.43457827089, 16888.83111168922, 17875.28498860031, 14857.925710371379, 14886.10683359002, 14988.250587470366, 15010.399584016517, 15169.24153792328, 14474.131552106906, 14477.0, 14608.561743147722, 14653.780193296896, 14986.47547335838, 14160.079594826715, 14447.566573002827, 14473.131612103307, 14635.634109147357, 15492.612684682974, 8626.0, 8871.733847984571, 8879.556022198993, 9380.859287110563, 9721.951390243286, 14718.190499522629, 14765.852341476877, 14768.261586920826, 15087.773683394977, 15209.087454752751], "unit": "ms", "name": "jquery.html", "value": 8952.29493563431}, {"lowerIsBetter": false, "replicates": [1352.5942217334798, 1684.5283320670212, 1729.9999999999995, 1753.1234382808595, 1774.3434929076236, 6624.171978502687, 7712.383009359251, 7857.214278572154, 7862.646180921858, 7868.055833300012, 7622.237776222375, 7811.960940195291, 7821.7653470395835, 7824.061112666488, 7829.530228186299, 1946.2993322403922, 1973.9506512337164, 1976.0317444452187, 2263.196565219348, 2299.4826164113024, 2270.1713874435795, 2327.150590034642, 2327.5461285049423, 2327.6159433693506, 2329.4409341758023, 2218.667199920009, 2228.4317499037725, 2287.6911616931666, 2290.450291929933, 2291.59897018022, 1972.7928567500421, 2008.9899550502228, 2010.9195632174697, 2232.162938897913, 2265.9660105098437, 2294.0, 2300.0799680127916, 2301.7698230177016, 2302.861828290308, 2303.9654405183937, 7679.846403071963, 7775.4168437367425, 7782.455228134033, 7789.221077892222, 7790.065192176975, 4647.512011238839, 4661.627069834405, 4680.859574212745, 4700.788464519105, 4717.858464246078, 3187.1713354527756, 3245.4158251514955, 3259.348130373911, 3291.1113999220074, 3311.586051743532, 1065.8934106589356, 1068.1241382066708, 1073.4257172412722, 1094.9945250273777, 1096.703889949717, 10331.638392656221, 10361.119304859021, 10370.481475926174, 10384.636537721219, 10388.37669739818, 4137.689673274516, 4146.108586653823, 4165.791710414467, 4251.829926802893, 4310.31035034393, 8887.73336799897, 8900.065493123258, 8903.287736981027, 8953.955230223808, 9046.954765226132, 4648.651351148677, 4652.348671186037, 4689.788959496831, 4694.671373003857, 4694.835680751157, 4216.177845320133, 4230.767307798101, 4244.681648876347, 4262.445882035316, 4270.402143699823, 3593.1196856769734, 3663.8534458621357, 3699.2786406650985, 3710.22085362077, 3719.8512059517857, 1173.923694959842, 1182.3378907811652, 1184.3426898071652, 1191.5591231244496, 1194.737157825277, 1008.7074748323051, 1035.166690813887, 1035.3942943378058, 1167.4279602994472, 1339.0091332413945, 7246.058012458347, 7268.164161121539, 7341.559506429631, 7342.412606991428, 7344.963275183697, 500.7546302311841, 505.92664063710555, 509.997450012755, 511.58291532454894, 511.9616028797855, 5354.41101478837, 5516.200150978086, 5861.736221870026, 5970.014947533695, 5974.761009559656, 9035.909640903506, 9042.321825863086, 9295.163435290857, 9314.115159059878, 9428.811423771485, 9175.816483670289, 9201.125893040151, 9294.535273236312, 9322.114399132071, 9333.859992100124, 7061.152661680529, 7066.893996590055, 7126.322999315057, 7172.928270717331, 7174.461915356474, 7124.323189297009, 7146.428285737024, 7149.320814522612, 7151.356377926011, 7160.820979475347, 7761.883571746315, 7860.292573668397, 7872.134065252703, 8009.199080091944, 8028.317593004426, 7963.6814527418255, 8289.626966786629, 8368.456050356708, 8411.53736544496, 8416.4950102994, 6686.632235227109, 6745.089412929192, 6796.082528858541, 6827.419669327964, 6843.452523797984, 7431.182569917413, 7484.887726684213, 7485.1766305707415, 7488.737894173678, 7496.25037496246, 6901.309869013058, 6975.0, 7020.473464490082, 7052.33002864727, 7149.499535032498, 6864.141982252218, 6869.862602748017, 6897.275786042594, 6946.895796562954, 6966.895496567657, 6794.966025169842, 6957.304269573002, 6960.5127641066665, 7031.472639551952, 7032.964835175791, 6546.5417420782, 6554.868902622016, 6565.474762019123, 6624.436922861418, 6626.900596491153, 5877.794277200277, 6037.215162028908, 6590.242122155822, 6676.399124078856, 6711.46308295345], "unit": "ms", "name": "mootools.html", "value": 4364.604822528333}, {"lowerIsBetter": false, "replicates": [342.385418174377, 347.0542770949163, 349.8215909885959, 355.28942115768444, 356.20743844944985, 5815.127730840376, 5900.468957793798, 5933.3473317934995, 5956.88086238275, 5980.521558275338, 5757.309122905257, 5891.587588868781, 5895.439933206349, 5903.970480147604, 5909.290885093784, 4563.634909207272, 4566.954330456686, 4577.656675749332, 4578.313253012058, 4582.427196600425, 4392.582704643054, 4483.215437298476, 4486.484054333744, 4559.133764584735, 4569.177548041352, 4540.682152249344, 4553.954460455386, 4561.087782443508, 4567.155076310876, 4572.634189264867, 4525.411696479437, 4547.249703798886, 4558.225101732697, 4569.9314510282375, 4577.519360467137, 4024.396340548941, 4025.3559430491273, 4025.416314634391, 4025.959740402617, 4034.011667141569, 5490.972545137249, 5600.775968961237, 5615.6630602164005, 5616.269884914935, 5656.0, 5216.0, 5221.451747566527, 5223.6343455958095, 5224.268602395668, 5229.37247530295, 5934.198883150762, 6250.1874756281395, 6252.093446450239, 6256.186695729526, 6260.467860231885, 4183.288840897055, 4272.0, 4325.545817689129, 4333.328334108218, 4335.653147748204, 6369.71336289868, 6467.223933128054, 6471.449926756231, 6478.352164783484, 6481.0, 4434.068845542472, 4445.0, 4446.5553444655925, 4448.065906159678, 4448.132614140276, 4370.67219958498, 4490.730556166641, 4496.482904465963, 4498.145352383037, 4533.591976722111, 5207.062728708793, 5219.608529360313, 5221.686698798084, 5222.843314700565, 5229.267902493654, 6255.249370075529, 6297.6536290503545, 6309.305976342599, 6314.463270621956, 6319.0837328587095, 1551.1468692219235, 1592.0845513829595, 1592.6655402365634, 1592.7610858371338, 1593.6573636668172, 1063.3301020357126, 1063.739468729558, 1071.7213524483534, 1091.3124731419155, 1097.8078836203633, 6088.908666370008, 6099.207103076572, 6103.05402662588, 6110.877782444415, 6115.449609535074, 6461.838454038592, 6462.806115816533, 6467.967660161669, 6469.094326794253, 6469.644169570719, 6086.543509236825, 6090.208272924491, 6100.938990610037, 6101.908471372933, 6102.786402475892, 3455.1880308128075, 3532.116970757311, 3534.8939531814085, 3541.716662667032, 3545.556805399325, 6716.496262780213, 6723.495737819585, 6727.899081513781, 6748.730050798012, 6748.763793267212, 6684.331566843374, 6698.0, 6698.497612679069, 6702.832429189211, 6702.932970670328, 6571.244306904671, 6737.663116844138, 6737.797866064026, 6738.764143254963, 6742.325767423218, 6287.025511045796, 6458.418742313214, 6534.313897040743, 6535.901961470677, 6539.280679125197, 6357.80926572185, 6482.481401487964, 6499.0901273820755, 6504.642244676399, 6515.328715226734, 6748.021536877223, 6751.93248067513, 6756.4257038150345, 6760.22257440381, 6761.729530818712, 4345.283028300295, 4355.803988820448, 4362.0839623679385, 4366.104948485617, 4367.126574685012, 6511.186101737283, 6520.902186467112, 6524.608523488606, 6527.184101987252, 6531.542792004514, 6342.619442833445, 6343.524235682435, 6343.873122537616, 6346.397092276226, 6347.714352854225, 6202.41077097675, 6305.590136641287, 6327.810165695037, 6330.3669633036325, 6332.398422149889, 6123.112148738497, 6172.629642221481, 6181.041938499539, 6200.0, 6206.875862482635, 6314.747410103544, 6322.146510221061, 6330.715117819617, 6337.809865704036, 6340.809775706736, 6039.396060394101, 6177.2587289525545, 6184.0, 6194.659293738708, 6196.194494715838], "unit": "ms", "name": "prototype.html", "value": 4751.996492166828}, {"lowerIsBetter": false, "replicates": [874.5670892908012, 875.9737207883763, 876.5354362188041, 879.221659555803, 880.4541184465633, 13719.245441500725, 13946.581602551927, 13952.581422557314, 13962.58112256634, 13988.160710357386, 12925.74148517029, 12998.0, 13005.93497032516, 13009.674758131028, 13019.414126364312, 685.5817951049855, 697.9371856532911, 826.557791581504, 843.995780021099, 844.6410275632862, 786.3394748411333, 832.4672209785742, 838.5513750143674, 845.5222799118509, 846.4032856835915, 721.0482163544124, 883.6553744039829, 889.6797153024905, 890.2432932007774, 890.3011136258039, 729.482067731911, 729.8467321862415, 731.2979539641976, 732.150705181989, 859.6002858670686, 657.3492242679761, 657.5002997721718, 657.5857209957696, 657.7829316325601, 657.8454063295122, 13240.470381184741, 13366.732665346639, 13371.398287077105, 13371.799423008662, 13397.12918660284, 11624.0, 12219.389030548526, 12283.447244874002, 12329.198602090835, 12601.180923240052, 10164.94917525415, 10559.78880422395, 10631.255812093157, 10641.574337026586, 10701.14390848738, 3493.161641206092, 3543.5924868639922, 3546.8935931922083, 3550.254446566224, 3647.015305867401, 48862.511374886155, 48894.7555262225, 48997.75501122472, 49028.754856225845, 49082.263766043536, 10086.29395942277, 10216.131628811634, 10403.84394234087, 10492.0, 10579.206559508068, 17082.572935676708, 17219.569510762332, 17230.396936107176, 17275.654486910193, 17393.65212695739, 7426.777196684108, 7528.0, 7653.15815260321, 7804.6878124874365, 7915.683372665144, 4297.914041719148, 4330.501992270866, 4381.605655490957, 4387.27609944362, 4485.865424037284, 3039.893603723859, 3071.846407679607, 3073.6465306489595, 3074.9692503074684, 3076.138681169276, 545.1033050631768, 567.7785663591203, 579.6290374160541, 580.8489792653941, 586.7330364684059, 515.4150039704962, 588.3616276340133, 630.334996578614, 642.6304874696974, 646.1922596754057, 8728.432651877607, 8955.41789783662, 8974.28205743539, 9055.637774488947, 9157.49633770149, 587.8471597384711, 624.1729708136737, 663.1611012069735, 683.4054372695787, 690.5683947532792, 4115.938260926088, 4135.214309281227, 4238.279492486285, 4290.163418133434, 4325.0, 55974.72012639911, 61241.0, 61301.0, 61363.0, 61425.07862382068, 50087.0, 51615.225771613455, 51721.48278517242, 51865.0, 53012.0, 46798.0, 47286.29070563944, 47320.290195647096, 48544.51455485471, 48680.756596216794, 8989.595468203815, 9153.908460915305, 9365.157135857533, 9386.296027798078, 9397.718068457958, 35754.28491430209, 35820.820895895355, 35986.4602030975, 36282.637173627925, 36825.81587092047, 40414.191716164925, 46349.76825115853, 46522.0, 46626.76686616545, 46638.533614664775, 10417.114545263434, 10463.79072418532, 10673.0, 10690.893091069302, 10737.516811743333, 12017.278963262232, 12045.819312710491, 12413.813792793297, 12715.491380344683, 13143.211407315592, 11900.821487677866, 11918.642440726791, 11974.161808673309, 12339.50641974311, 12362.134650574733, 10763.838542421714, 10858.565657373616, 11129.944350278196, 11715.589954351557, 11965.880341196476, 10127.291089623655, 10634.202434817265, 10673.199510036624, 10702.73243168927, 10844.240903136704, 10019.0, 10130.898691012995, 10135.0, 10542.314749541256, 10703.625373111903, 8259.326366998626, 9284.489353085375, 9322.254219662547, 9394.483303418105, 9416.38793478449], "unit": "ms", "name": "yui.html", "value": 6359.096052806659}], "name": "dromaeo_css", "value": 7008.866056301768}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 112.0, 109.0, 110.0, 99.0, 109.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 147.0, 162.0, 134.0, 155.0, 148.0, 125.0, 162.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 167.0, 160.0, 163.0, 162.0, 159.0, 160.0, 161.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 161.9}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 82.0, 91.0, 73.0, 72.0, 70.0, 99.0, 94.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 79.6}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 136.0, 92.0, 93.0, 92.0, 137.0, 93.0, 93.0, 142.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 108.0, 110.0, 111.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 59.0, 58.0, 60.0, 58.0, 60.0, 58.0, 58.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 58.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 71.0, 72.0, 71.0, 71.0, 70.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 71.0}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 86.0, 86.0, 86.0, 88.0, 89.0, 86.0, 85.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.1}, {"lowerIsBetter": true, "replicates": [119.0, 116.0, 117.0, 117.0, 117.0, 117.0, 126.0, 120.0, 123.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.5}, {"lowerIsBetter": true, "replicates": [140.0, 150.0, 162.0, 158.0, 142.0, 139.0, 158.0, 150.0, 149.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.5}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.5}], "name": "kraken", "value": 1499.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.7000828500414], "unit": "score", "name": "Richards", "value": 213.04756999999998}, {"lowerIsBetter": false, "replicates": [194.55252918287937], "unit": "score", "name": "DeltaBlue", "value": 339.84651999999994}, {"lowerIsBetter": false, "replicates": [283.28611898017], "unit": "score", "name": "Encrypt", "value": 939.6189299999998}, {"lowerIsBetter": false, "replicates": [5159.79381443299], "unit": "score", "name": "Decrypt", "value": 51.587526473526495}, {"lowerIsBetter": false, "replicates": [1998.0039920159682], "unit": "score", "name": "RayTrace", "value": 370.36412487512496}, {"lowerIsBetter": false, "replicates": [798.7220447284345], "unit": "score", "name": "Earley", "value": 834.4116759999996}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [26025.641025641027], "unit": "score", "name": "RegExp", "value": 35.003364532019674}, {"lowerIsBetter": false, "replicates": [658.0811332904057], "unit": "score", "name": "Splay", "value": 123.8312358121331}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 19547.462564495512}]} 07:01:46 INFO - RETURN: dromaeo_css: 7008.87 07:01:46 INFO - RETURN: kraken: 1499.80 07:01:46 INFO - RETURN: v8_7: 19547.46 07:01:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19547.46"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1499.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "7008.87"}}} 07:01:46 INFO - Return code: 0 07:01:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 07:01:46 INFO - # TBPL SUCCESS # 07:01:46 INFO - Running post-action listener: _resource_record_post_action 07:01:46 INFO - Running post-run listener: _resource_record_post_run 07:01:48 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 510590976; Read time: 12; Write time: 396288 07:01:48 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 235802624; Read time: 0; Write time: 211512 07:01:48 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 263847936; Read time: 12; Write time: 181460 07:01:48 INFO - Running post-run listener: _upload_blobber_files 07:01:48 INFO - Blob upload gear active. 07:01:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:01:48 INFO - Copying logs to upload dir... 07:01:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.775448 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-11-25 07:01:46.702850) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 07:01:46.706561) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009909 build_url: 'https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 07:01:46.758137) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:01:46.758471) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448461965.442631-660122105 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:01:46.807953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 07:01:46.808296) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 07:01:46.808605) ========= ========= Total master_lag: 0.21 =========