builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-013 starttime: 1448462440.58 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.576353) ========= 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.576927) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.577281) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006682 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.598867) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.599327) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.612816) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.613218) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004666 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.662828) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.663136) ========= 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.663486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.663748) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 06:40:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2015-11-25 06:40:41 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.197738 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.873455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.873752) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013327 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.922597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:40.922915) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 06:40:41,140 truncating revision to first 12 chars 2015-11-25 06:40:41,140 Setting DEBUG logging. 2015-11-25 06:40:41,140 attempt 1/10 2015-11-25 06:40:41,140 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,375 unpacking tar archive at: mozilla-central-1835baed2a38/testing/mozharness/ program finished with exit code 0 elapsedTime=0.544344 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:41.480242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:41.480548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:41.490632) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 06:40:41.490980) ========= 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:41.491580) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 58 secs) (at 2015-11-25 06:40:41.492260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:40:41 INFO - MultiFileLogger online at 20151125 06:40:41 in /builds/slave/test-pgo 06:40:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:41 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:40:41 INFO - {'append_to_log': False, 06:40:41 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:40:41 INFO - 'blob_upload_branch': 'Firefox', 06:40:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:40:41 INFO - 'branch': 'Firefox', 06:40:41 INFO - 'buildbot_json_path': 'buildprops.json', 06:40:41 INFO - 'config_files': ('talos/linux_config.py',), 06:40:41 INFO - 'default_actions': ('clobber', 06:40:41 INFO - 'read-buildbot-config', 06:40:41 INFO - 'download-and-extract', 06:40:41 INFO - 'populate-webroot', 06:40:41 INFO - 'create-virtualenv', 06:40:41 INFO - 'install', 06:40:41 INFO - 'run-tests'), 06:40:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:40:41 INFO - 'download_minidump_stackwalk': True, 06:40:41 INFO - 'download_symbols': 'ondemand', 06:40:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:40:41 INFO - 'tooltool.py': '/tools/tooltool.py', 06:40:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:40:41 INFO - '/tools/misc-python/virtualenv.py')}, 06:40:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:40:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:40:41 INFO - 'installer_path': 'installer.exe', 06:40:41 INFO - 'log_level': 'info', 06:40:41 INFO - 'log_name': 'talos', 06:40:41 INFO - 'log_to_console': True, 06:40:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:40:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:40:41 INFO - 'opt_config_files': (), 06:40:41 INFO - 'pip_index': False, 06:40:41 INFO - 'sps_profile': False, 06:40:41 INFO - 'sps_profile_interval': 0, 06:40:41 INFO - 'suite': 'dromaeojs-e10s', 06:40:41 INFO - 'system_bits': '64', 06:40:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:40:41 INFO - 'title': 'talos-linux64-ix-013', 06:40:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:40:41 INFO - 'use_talos_json': True, 06:40:41 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:40:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:40:41 INFO - 'work_dir': 'build'} 06:40:41 INFO - ##### 06:40:41 INFO - ##### Running clobber step. 06:40:41 INFO - ##### 06:40:41 INFO - Running pre-action listener: _resource_record_pre_action 06:40:41 INFO - Running main action method: clobber 06:40:41 INFO - rmtree: /builds/slave/test-pgo/build 06:40:41 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": 562, 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-e10s", 06:40:42 INFO - "repository": "", 06:40:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 06:40:42 INFO - "buildid": "20151125043001", 06:40:42 INFO - "slavename": "talos-linux64-ix-013", 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:50 INFO - Downloaded 1302 bytes. 06:40:50 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:40:50 INFO - Using the following test package requirements: 06:40:50 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:50 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:40:50 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'jsshell-linux-x86_64.zip'], 06:40:50 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:40:50 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:50 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:40:50 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:40:50 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:40:50 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:50 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:50 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:40:50 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:40:50 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:50 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:50 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:50 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:50 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:41:02 INFO - Downloaded 22753997 bytes. 06:41:02 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:41:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:41:02 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:41:03 INFO - Return code: 0 06:41:03 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: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 - 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:41:03 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:41:03 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:41:03 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:41:03 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:41:06 INFO - Downloaded 22753997 bytes. 06:41:06 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:41:06 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:41:06 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:41:12 INFO - Return code: 0 06:41:12 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:12 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:41:12 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:41:12 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:41:12 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:41:12 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:41:14 INFO - Downloaded 11131717 bytes. 06:41:14 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:41:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:41:14 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:41:14 INFO - Return code: 0 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 - 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:41:14 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:41:14 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:41:14 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:41:14 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:15 INFO - Downloaded 58391096 bytes. 06:41:15 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:15 INFO - mkdir: /builds/slave/test-pgo/properties 06:41:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:41:15 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:41:15 INFO - Contents: 06:41:15 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:15 INFO - Running post-action listener: _resource_record_post_action 06:41:15 INFO - Running post-action listener: set_extra_try_arguments 06:41:15 INFO - ##### 06:41:15 INFO - ##### Running populate-webroot step. 06:41:15 INFO - ##### 06:41:15 INFO - Running pre-action listener: _resource_record_pre_action 06:41:15 INFO - Running main action method: populate_webroot 06:41:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:41:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:41:15 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 06:41:15 INFO - '../fennec_ids.txt'], 06:41:15 INFO - 'tests': ['tcheck2']}, 06:41:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:41:15 INFO - '--tppagecycles', 06:41:15 INFO - '7'], 06:41:15 INFO - 'tests': ['tsvgm']}}, 06:41:15 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:41:15 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:41:15 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:41:15 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:41:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:41:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:41:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'tests': ['damp', 'tps']}, 06:41:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['damp', 'tps']}, 06:41:15 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:41:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:41:15 INFO - 'other': {'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'other_l64': {'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'other_nol64': {'tests': ['a11yr', 06:41:15 INFO - 'ts_paint', 06:41:15 INFO - 'tpaint', 06:41:15 INFO - 'sessionrestore', 06:41:15 INFO - 'sessionrestore_no_auto_restore']}, 06:41:15 INFO - 'svgr': {'tests': ['tsvgx', 06:41:15 INFO - 'tsvgr_opacity', 06:41:15 INFO - 'tart', 06:41:15 INFO - 'tscrollx', 06:41:15 INFO - 'cart']}, 06:41:15 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['tsvgx', 06:41:15 INFO - 'tsvgr_opacity', 06:41:15 INFO - 'tart', 06:41:15 INFO - 'tscrollx', 06:41:15 INFO - 'cart']}, 06:41:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'tests': ['tp5o']}, 06:41:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'talos_options': ['--e10s'], 06:41:15 INFO - 'tests': ['tp5o']}, 06:41:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'talos_options': ['--xperf_path', 06:41:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:41:15 INFO - 'tests': ['tp5n']}, 06:41:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:41:15 INFO - 'talos_options': ['--e10s', 06:41:15 INFO - '--xperf_path', 06:41:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:41:15 INFO - 'tests': ['tp5n']}}, 06:41:15 INFO - 'talos.zip': {'path': '', 06:41:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:41:15 INFO - Running post-action listener: _resource_record_post_action 06:41:15 INFO - ##### 06:41:15 INFO - ##### Running create-virtualenv step. 06:41:15 INFO - ##### 06:41:15 INFO - Running pre-action listener: _resource_record_pre_action 06:41:15 INFO - Running main action method: create_virtualenv 06:41:15 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:41:15 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:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:41:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:41:15 INFO - Using real prefix '/usr' 06:41:15 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:41:16 INFO - Installing distribute.............................................................................................................................................................................................done. 06:41:18 INFO - Installing pip.................done. 06:41:18 INFO - Return code: 0 06:41:18 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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', 'pip>=1.5'] 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 pip>=1.5 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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 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 - 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:18 INFO - Cleaning up... 06:41:18 INFO - Return code: 0 06:41:18 INFO - Installing psutil>=0.7.1 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', '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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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', 'psutil>=0.7.1'] 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 psutil>=0.7.1 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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 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 psutil>=0.7.1 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:20 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 06:41:20 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:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:41:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:41:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:41:20 INFO - Installing collected packages: psutil 06:41:20 INFO - Running setup.py install for psutil 06:41:20 INFO - building 'psutil._psutil_linux' extension 06:41:20 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:20 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:20 INFO - building 'psutil._psutil_posix' extension 06:41:20 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:20 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:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:41:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:41:21 INFO - Successfully installed psutil 06:41:21 INFO - Cleaning up... 06:41:21 INFO - Return code: 0 06:41:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:41:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:21 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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 06:41:21 INFO - 'HOME': '/home/cltbld', 06:41:21 INFO - 'LANG': 'en_US.UTF-8', 06:41:21 INFO - 'LANGUAGE': 'en_US:en', 06:41:21 INFO - 'LOGNAME': 'cltbld', 06:41:21 INFO - 'MAIL': '/var/mail/cltbld', 06:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:21 INFO - 'MOZ_NO_REMOTE': '1', 06:41:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:21 INFO - 'NO_EM_RESTART': '1', 06:41:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:21 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:21 INFO - 'SHELL': '/bin/bash', 06:41:21 INFO - 'SHLVL': '1', 06:41:21 INFO - 'TERM': 'linux', 06:41:21 INFO - 'TMOUT': '86400', 06:41:21 INFO - 'USER': 'cltbld', 06:41:21 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:21 INFO - '_': '/tools/buildbot/bin/python'} 06:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:41:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:41:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:41:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:41:22 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:22 INFO - Installing collected packages: mozsystemmonitor 06:41:22 INFO - Running setup.py install for mozsystemmonitor 06:41:23 INFO - Successfully installed mozsystemmonitor 06:41:23 INFO - Cleaning up... 06:41:23 INFO - Return code: 0 06:41:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:41:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:23 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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:23 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:23 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:23 INFO - Using env: {'DISPLAY': ':0', 06:41:23 INFO - 'HOME': '/home/cltbld', 06:41:23 INFO - 'LANG': 'en_US.UTF-8', 06:41:23 INFO - 'LANGUAGE': 'en_US:en', 06:41:23 INFO - 'LOGNAME': 'cltbld', 06:41:23 INFO - 'MAIL': '/var/mail/cltbld', 06:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:23 INFO - 'MOZ_NO_REMOTE': '1', 06:41:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:23 INFO - 'NO_EM_RESTART': '1', 06:41:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:23 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:23 INFO - 'SHELL': '/bin/bash', 06:41:23 INFO - 'SHLVL': '1', 06:41:23 INFO - 'TERM': 'linux', 06:41:23 INFO - 'TMOUT': '86400', 06:41:23 INFO - 'USER': 'cltbld', 06:41:23 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:23 INFO - '_': '/tools/buildbot/bin/python'} 06:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:23 INFO - Downloading/unpacking blobuploader==1.2.4 06:41:23 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:23 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:23 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:23 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:23 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:23 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:24 INFO - Downloading blobuploader-1.2.4.tar.gz 06:41:24 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:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:41:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:41:25 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:25 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:25 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:25 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:25 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:25 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:25 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:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:41:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:41:25 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:25 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:25 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:25 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:25 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:25 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:25 INFO - Downloading docopt-0.6.1.tar.gz 06:41:25 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:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:41:25 INFO - Installing collected packages: blobuploader, requests, docopt 06:41:25 INFO - Running setup.py install for blobuploader 06:41:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:41:25 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:41:25 INFO - Running setup.py install for requests 06:41:26 INFO - Running setup.py install for docopt 06:41:26 INFO - Successfully installed blobuploader requests docopt 06:41:26 INFO - Cleaning up... 06:41:28 INFO - Return code: 0 06:41:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:28 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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:28 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:28 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:28 INFO - Using env: {'DISPLAY': ':0', 06:41:28 INFO - 'HOME': '/home/cltbld', 06:41:28 INFO - 'LANG': 'en_US.UTF-8', 06:41:28 INFO - 'LANGUAGE': 'en_US:en', 06:41:28 INFO - 'LOGNAME': 'cltbld', 06:41:28 INFO - 'MAIL': '/var/mail/cltbld', 06:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:28 INFO - 'MOZ_NO_REMOTE': '1', 06:41:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:28 INFO - 'NO_EM_RESTART': '1', 06:41:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:28 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:28 INFO - 'SHELL': '/bin/bash', 06:41:28 INFO - 'SHLVL': '1', 06:41:28 INFO - 'TERM': 'linux', 06:41:28 INFO - 'TMOUT': '86400', 06:41:28 INFO - 'USER': 'cltbld', 06:41:28 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:28 INFO - '_': '/tools/buildbot/bin/python'} 06:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:28 INFO - Running setup.py (path:/tmp/pip-3ZgwBV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:28 INFO - Running setup.py (path:/tmp/pip-eeDMEd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:28 INFO - Running setup.py (path:/tmp/pip-cs0Lql-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:28 INFO - Running setup.py (path:/tmp/pip-jRymwA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:28 INFO - Running setup.py (path:/tmp/pip-ZKD_yW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:28 INFO - Running setup.py (path:/tmp/pip-HVYhQw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:28 INFO - Running setup.py (path:/tmp/pip-SyYUnE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:28 INFO - Running setup.py (path:/tmp/pip-GcVOeO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:28 INFO - Running setup.py (path:/tmp/pip-qxhE25-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:28 INFO - Running setup.py (path:/tmp/pip-Ic5Aii-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:28 INFO - Running setup.py (path:/tmp/pip-4fYIIM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:29 INFO - Running setup.py (path:/tmp/pip-v7_ucz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:29 INFO - Running setup.py (path:/tmp/pip-vNLvqG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:29 INFO - Running setup.py (path:/tmp/pip-6Rh6Xn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:29 INFO - Running setup.py (path:/tmp/pip-6t3bT9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:29 INFO - Running setup.py (path:/tmp/pip-WgaLwm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:29 INFO - Running setup.py (path:/tmp/pip-AKpwEb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:41:29 INFO - Running setup.py install for manifestparser 06:41:29 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:41:29 INFO - Running setup.py install for mozcrash 06:41:29 INFO - Running setup.py install for mozdebug 06:41:29 INFO - Running setup.py install for mozdevice 06:41:29 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:41:29 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:41:29 INFO - Running setup.py install for mozfile 06:41:30 INFO - Running setup.py install for mozhttpd 06:41:30 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozinfo 06:41:30 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozInstall 06:41:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozleak 06:41:30 INFO - Running setup.py install for mozlog 06:41:30 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for moznetwork 06:41:30 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozprocess 06:41:30 INFO - Running setup.py install for mozprofile 06:41:30 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozrunner 06:41:30 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:41:30 INFO - Running setup.py install for mozscreenshot 06:41:31 INFO - Running setup.py install for moztest 06:41:31 INFO - Running setup.py install for mozversion 06:41:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:41:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:41:31 INFO - Cleaning up... 06:41:31 INFO - Return code: 0 06:41:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:31 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:31 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:31 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:31 INFO - Using env: {'DISPLAY': ':0', 06:41:31 INFO - 'HOME': '/home/cltbld', 06:41:31 INFO - 'LANG': 'en_US.UTF-8', 06:41:31 INFO - 'LANGUAGE': 'en_US:en', 06:41:31 INFO - 'LOGNAME': 'cltbld', 06:41:31 INFO - 'MAIL': '/var/mail/cltbld', 06:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:31 INFO - 'MOZ_NO_REMOTE': '1', 06:41:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:31 INFO - 'NO_EM_RESTART': '1', 06:41:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:31 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:31 INFO - 'SHELL': '/bin/bash', 06:41:31 INFO - 'SHLVL': '1', 06:41:31 INFO - 'TERM': 'linux', 06:41:31 INFO - 'TMOUT': '86400', 06:41:31 INFO - 'USER': 'cltbld', 06:41:31 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:31 INFO - '_': '/tools/buildbot/bin/python'} 06:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:31 INFO - Running setup.py (path:/tmp/pip-ZPhPXc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:31 INFO - Running setup.py (path:/tmp/pip-AdgKsn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:31 INFO - Running setup.py (path:/tmp/pip-qecpjM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:31 INFO - Running setup.py (path:/tmp/pip-_ZmPLM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:31 INFO - Running setup.py (path:/tmp/pip-TkjGHh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:31 INFO - Running setup.py (path:/tmp/pip-ALztBS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:31 INFO - Running setup.py (path:/tmp/pip-uN31z2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:41:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:31 INFO - Running setup.py (path:/tmp/pip-DJEIqu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:32 INFO - Running setup.py (path:/tmp/pip-yFFhww-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:32 INFO - Running setup.py (path:/tmp/pip-u_OirI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:32 INFO - Running setup.py (path:/tmp/pip-d0DT_N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:32 INFO - Running setup.py (path:/tmp/pip-qvu6BZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:32 INFO - Running setup.py (path:/tmp/pip-tYcXbA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:32 INFO - Running setup.py (path:/tmp/pip-czekM4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:32 INFO - Running setup.py (path:/tmp/pip-7rUf4D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:32 INFO - Running setup.py (path:/tmp/pip-N_LRDL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:41:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:32 INFO - Running setup.py (path:/tmp/pip-7I4lsX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:41:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 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:34 INFO - Downloading blessings-1.5.1.tar.gz 06:41:34 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:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:41:34 INFO - Installing collected packages: blessings 06:41:34 INFO - Running setup.py install for blessings 06:41:34 INFO - Successfully installed blessings 06:41:34 INFO - Cleaning up... 06:41:34 INFO - Return code: 0 06:41:34 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:41:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:41:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:41:34 INFO - Reading from file tmpfile_stdout 06:41:34 INFO - Current package versions: 06:41:34 INFO - argparse == 1.2.1 06:41:34 INFO - blessings == 1.5.1 06:41:34 INFO - blobuploader == 1.2.4 06:41:34 INFO - docopt == 0.6.1 06:41:34 INFO - manifestparser == 1.1 06:41:34 INFO - mozInstall == 1.12 06:41:34 INFO - mozcrash == 0.16 06:41:34 INFO - mozdebug == 0.1 06:41:34 INFO - mozdevice == 0.47 06:41:34 INFO - mozfile == 1.2 06:41:34 INFO - mozhttpd == 0.7 06:41:34 INFO - mozinfo == 0.9 06:41:34 INFO - mozleak == 0.1 06:41:34 INFO - mozlog == 3.1 06:41:34 INFO - moznetwork == 0.27 06:41:34 INFO - mozprocess == 0.22 06:41:34 INFO - mozprofile == 0.27 06:41:34 INFO - mozrunner == 6.11 06:41:34 INFO - mozscreenshot == 0.1 06:41:34 INFO - mozsystemmonitor == 0.0 06:41:34 INFO - moztest == 0.7 06:41:34 INFO - mozversion == 1.4 06:41:34 INFO - psutil == 3.1.1 06:41:34 INFO - requests == 1.2.3 06:41:34 INFO - wsgiref == 0.1.2 06:41:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:41:34 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:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:34 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:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:34 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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:34 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:34 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:34 INFO - Using env: {'DISPLAY': ':0', 06:41:34 INFO - 'HOME': '/home/cltbld', 06:41:34 INFO - 'LANG': 'en_US.UTF-8', 06:41:34 INFO - 'LANGUAGE': 'en_US:en', 06:41:34 INFO - 'LOGNAME': 'cltbld', 06:41:34 INFO - 'MAIL': '/var/mail/cltbld', 06:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:34 INFO - 'MOZ_NO_REMOTE': '1', 06:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:34 INFO - 'NO_EM_RESTART': '1', 06:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:34 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:34 INFO - 'SHELL': '/bin/bash', 06:41:34 INFO - 'SHLVL': '1', 06:41:34 INFO - 'TERM': 'linux', 06:41:34 INFO - 'TMOUT': '86400', 06:41:34 INFO - 'USER': 'cltbld', 06:41:34 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:34 INFO - '_': '/tools/buildbot/bin/python'} 06:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Cleaning up... 06:41:35 INFO - Return code: 0 06:41:35 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:35 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 0x2804030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a315e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', '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:35 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:35 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: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 - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:35 INFO - 'MOZ_NO_REMOTE': '1', 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': '/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 - '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': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:35 INFO - '_': '/tools/buildbot/bin/python'} 06:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:35 INFO - Downloading/unpacking jsonschema 06:41:35 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:35 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:35 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:35 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:35 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:35 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:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:41:36 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:36 INFO - Downloading/unpacking functools32 (from jsonschema) 06:41:36 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:36 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:36 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:36 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:36 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:36 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:37 INFO - Downloading functools32-3.2.3-2.tar.gz 06:41:37 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:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:41:37 INFO - warning: no files found matching '*.txt' 06:41:37 INFO - no previously-included directories found matching 'build' 06:41:37 INFO - no previously-included directories found matching 'dist' 06:41:37 INFO - no previously-included directories found matching '.git*' 06:41:37 INFO - Installing collected packages: jsonschema, functools32 06:41:37 INFO - Running setup.py install for functools32 06:41:37 INFO - warning: no files found matching '*.txt' 06:41:37 INFO - no previously-included directories found matching 'build' 06:41:37 INFO - no previously-included directories found matching 'dist' 06:41:37 INFO - no previously-included directories found matching '.git*' 06:41:37 INFO - Successfully installed jsonschema functools32 06:41:37 INFO - Cleaning up... 06:41:37 INFO - Return code: 0 06:41:37 INFO - Running post-action listener: _resource_record_post_action 06:41:37 INFO - Running post-action listener: _start_resource_monitoring 06:41:37 INFO - Starting resource monitoring. 06:41:37 INFO - ##### 06:41:37 INFO - ##### Running install step. 06:41:37 INFO - ##### 06:41:37 INFO - Running pre-action listener: _resource_record_pre_action 06:41:37 INFO - Running main action method: install 06:41:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:41:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:41:37 INFO - Reading from file tmpfile_stdout 06:41:37 INFO - Detecting whether we're running mozinstall >=1.0... 06:41:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:41:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:41:37 INFO - Reading from file tmpfile_stdout 06:41:37 INFO - Output received: 06:41:37 INFO - Usage: mozinstall [options] installer 06:41:37 INFO - Options: 06:41:37 INFO - -h, --help show this help message and exit 06:41:37 INFO - -d DEST, --destination=DEST 06:41:37 INFO - Directory to install application into. [default: 06:41:37 INFO - "/builds/slave/test-pgo"] 06:41:37 INFO - --app=APP Application being installed. [default: firefox] 06:41:37 INFO - mkdir: /builds/slave/test-pgo/build/application 06:41:37 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:37 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:59 INFO - Reading from file tmpfile_stdout 06:41:59 INFO - Output received: 06:41:59 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:41:59 INFO - Running post-action listener: _resource_record_post_action 06:41:59 INFO - ##### 06:41:59 INFO - ##### Running run-tests step. 06:41:59 INFO - ##### 06:41:59 INFO - Running pre-action listener: _resource_record_pre_action 06:41:59 INFO - Running main action method: run_tests 06:41:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:41:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:41:59 INFO - Python 2.7.3 06:41:59 INFO - Return code: 0 06:41:59 INFO - grabbing minidump binary from tooltool 06:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:41:59 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 0x2ac0450>, '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 0x2ac1350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac17e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:41:59 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:59 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:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:41:59 INFO - Return code: 0 06:41:59 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:41:59 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:41:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:41:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:41:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:41:59 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-e10s', '--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-013', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:41:59 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-e10s --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-013 --webServer localhost 06:41:59 INFO - Using env: {'DISPLAY': ':0', 06:41:59 INFO - 'HOME': '/home/cltbld', 06:41:59 INFO - 'LANG': 'en_US.UTF-8', 06:41:59 INFO - 'LANGUAGE': 'en_US:en', 06:41:59 INFO - 'LOGNAME': 'cltbld', 06:41:59 INFO - 'MAIL': '/var/mail/cltbld', 06:41:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:41:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:59 INFO - 'MOZ_NO_REMOTE': '1', 06:41:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:59 INFO - 'NO_EM_RESTART': '1', 06:41:59 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:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:41:59 INFO - 'PWD': '/builds/slave/test-pgo', 06:41:59 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:41:59 INFO - 'SHELL': '/bin/bash', 06:41:59 INFO - 'SHLVL': '1', 06:41:59 INFO - 'TERM': 'linux', 06:41:59 INFO - 'TMOUT': '86400', 06:41:59 INFO - 'USER': 'cltbld', 06:41:59 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586', 06:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:59 INFO - '_': '/tools/buildbot/bin/python'} 06:41:59 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-e10s', '--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-013', '--webServer', 'localhost'] with output_timeout 3600 06:41:59 INFO - mozversion INFO | application_buildid: 20151125043001 06:41:59 INFO - mozversion INFO | application_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 06:41:59 INFO - mozversion INFO | application_display_name: Nightly 06:41:59 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:41:59 INFO - mozversion INFO | application_name: Firefox 06:41:59 INFO - mozversion INFO | application_remotingname: firefox 06:41:59 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 06:41:59 INFO - mozversion INFO | application_vendor: Mozilla 06:41:59 INFO - mozversion INFO | application_version: 45.0a1 06:41:59 INFO - mozversion INFO | platform_buildid: 20151125043001 06:41:59 INFO - mozversion INFO | platform_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 06:41:59 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 06:41:59 INFO - mozversion INFO | platform_version: 45.0a1 06:41:59 INFO - 2015-11-25 06:41:59,350 DEBUG : using testdate: 1448462519 06:41:59 INFO - 2015-11-25 06:41:59,350 DEBUG : actual date: 1448462519 06:41:59 INFO - 2015-11-25 06:41:59,359 INFO : starting webserver on 'localhost:45375' 06:41:59 INFO - 2015-11-25 06:41:59,360 INFO : Starting test suite talos-linux64-ix-013.e 06:41:59 INFO - 2015-11-25 06:41:59,360 INFO : Starting test dromaeo_css 06:41:59 INFO - 2015-11-25 06:41:59,360 DEBUG : operating with platform_type : linux_ 06:41:59 INFO - 2015-11-25 06:41:59,361 INFO : Initialising browser for dromaeo_css test... 06:41:59 INFO - 2015-11-25 06:41:59,372 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6CIA0B/profile http://localhost:45375/getInfo.html 06:42:57 INFO - 2015-11-25 06:42:57,920 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:42:57 INFO - 2015-11-25 06:42:57,920 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:42:57 INFO - 2015-11-25 06:42:57,920 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:42:57 INFO - 2015-11-25 06:42:57,920 DEBUG : BROWSER_OUTPUT: __metrics 06:43:02 INFO - 2015-11-25 06:43:02,978 INFO : Browser initialized. 06:43:02 INFO - 2015-11-25 06:43:02,979 INFO : Running cycle 1/1 for dromaeo_css test... 06:43:02 INFO - 2015-11-25 06:43:02,979 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6CIA0B/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:43:15 INFO - 2015-11-25 06:43:15,018 DEBUG : BROWSER_OUTPUT: RSS: Main: 138506240 06:43:15 INFO - 2015-11-25 06:43:15,018 DEBUG : BROWSER_OUTPUT: 06:44:04 INFO - 2015-11-25 06:44:04,632 DEBUG : BROWSER_OUTPUT: 1448462644632 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:46:13 INFO - 2015-11-25 06:46:13,876 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45375/tests/dromaeo/cssquery-ext.html) 06:46:14 INFO - 2015-11-25 06:46:14,953 DEBUG : BROWSER_OUTPUT: RSS: Main: 147668992 06:46:14 INFO - 2015-11-25 06:46:14,953 DEBUG : BROWSER_OUTPUT: 06:49:16 INFO - 2015-11-25 06:49:16,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-ext.html (next: http://localhost:45375/tests/dromaeo/cssquery-jquery.html) 06:49:17 INFO - 2015-11-25 06:49:17,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 142630912 06:49:17 INFO - 2015-11-25 06:49:17,554 DEBUG : BROWSER_OUTPUT: 06:52:19 INFO - 2015-11-25 06:52:19,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45375/tests/dromaeo/cssquery-mootools.html) 06:52:19 INFO - 2015-11-25 06:52:19,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 143093760 06:52:19 INFO - 2015-11-25 06:52:19,814 DEBUG : BROWSER_OUTPUT: 06:55:21 INFO - 2015-11-25 06:55:21,466 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45375/tests/dromaeo/cssquery-prototype.html) 06:55:22 INFO - 2015-11-25 06:55:22,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 141914112 06:55:22 INFO - 2015-11-25 06:55:22,057 DEBUG : BROWSER_OUTPUT: 06:58:24 INFO - 2015-11-25 06:58:24,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45375/tests/dromaeo/cssquery-yui.html) 06:58:24 INFO - 2015-11-25 06:58:24,972 DEBUG : BROWSER_OUTPUT: RSS: Main: 142376960 06:58:24 INFO - 2015-11-25 06:58:24,972 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,318 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/dromaeo/cssquery-yui.html (next: http://localhost:45375/tests/dromaeo/cssquery-dojo.html) 07:01:27 INFO - 2015-11-25 07:01:27,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 144896000 07:01:27 INFO - 2015-11-25 07:01:27,569 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,569 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:01:27 INFO - 2015-11-25 07:01:27,569 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:01:27 INFO - 2015-11-25 07:01:27,569 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:01:27 INFO - 2015-11-25 07:01:27,569 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:01:27 INFO - 2015-11-25 07:01:27,571 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3213.196700824794;3251.7398608111353;3262.2822978944623;3270.1334146451186;3315.0220684897954;5262.736863156846;5493.066178749612;5533;5547.58393120516;5549.556035517159;5418.7019713915715;5445.891082178354;5448.210009548613;5587.832365029046;5602.1596760485945;4941.654084214116;5004.499550045003;5058.721770302632;5075.0357432087785;5093.363329583802;4965.205567109245;5005.824796132136;5041.1178043842365;5095.5923526118;5160.200168973815;4822.445418776851;4832.081904438145;4927.876803079916;5063.063333283336;5094.828189516414;4972.229304457779;5043.747812609355;5051.797928082872;5062.1900495920845;5068.26510156029;5016.673916195436;5026.874328141789;5028.396592408934;5034.974825125851;5041.546260836506;5074.568661663763;5178.938317644874;5229.660072095302;5335.759890804923;5366.624336296461;5878.588498805086;5905.114232865061;5915.201447804534;5926.081457374114;5932.851678708023;5960.403959604048;5960.97019514904;5969.761209551612;5975.073863551156;5979.043353063489;5393.865153371158;5493.340799104133;5518.862028449281;5571.025070612626;5628.887422251532;6024.728887200086;6039.8792024159275;6041.697915104227;6043.244594425697;6055.576109672281;5652.434756524314;5820.88358232833;5841.620294680833;5857.531397488191;5880.176775251468;5813.12803079533;5858.970705146532;5915.290165180206;5923.229980102559;5978.491828194565;5921.555883308769;5942.821715348547;5975.67133807636;5993.520518358521;5998.580099393088;5643.746031428595;5771.134329850556;6000.639961602318;6085.2393450818645;6088.178095957078;4489.169503641771;4540.114677637895;4547.272436410159;4567.703099298535;4580.152671755736;4096.037431203665;4112.280350938574;4135.875923722293;4136.31750761121;4172.582741725803;5400.324959380077;5546.63946843462;5564.360098588633;5697.572682048863;5759.913601295984;5945.881082378328;6084.178635884187;6109.083637454418;6116.143739876421;6131.049687298476;5777.595568310262;5800.796972105956;5801.53587712982;5802.361740208574;5820.272465941757;5021.6735912165595;5120.18077107661;5124.66689665178;5192.662476938987;5270.341207349082;6103.943827031755;6150.815475535741;6167.5682702210415;6177.722002509898;6183.474404675653;5977.820665380045;5978.671173085522;5980.073088671089;5984.850378740392;6023.216981792339;5873.148393482836;5928.051511758098;6138.232720909887;6152.938470615416;6197.4732147767945;5798.739056742541;5843.853903652443;5855.619384739978;5872.265966754156;5920.348761636131;5950.4644581986095;5955;5962;5966.880662386815;5985.40145985398;5561.304836895388;5647.519960803378;5689.34572524165;5724.084146536701;5787.189793428839;5706.172604972338;5727.599068065196;5729.684867332337;5767.192593036894;5819.388964158872;5480.862978425571;5665.461781130786;5689;5691.857703557279;5697.658140511582;5689.203511508309;5799.8550036249435;5850.824475265749;5927;5976.581639285208;5616;5784.334801497882;5785.218995435563;5787.594868359174;5819.359870414336 07:01:27 INFO - 2015-11-25 07:01:27,572 DEBUG : BROWSER_OUTPUT: |1;ext.html;600.6576251536624;613.8588124731311;614.6527212125148;620.209233227635;625.162282541395;39522.604773952255;39826.402603960894;40021.1995760085;40405;40418;40725.185496290054;40818.59181408185;40955;41081.38377924326;41182.176356472926;8505.532195729233;8720.215180633742;8772.429792063496;8786.384953053288;8792.956035219848;8534.189252021048;8595.5702214889;8597.398182127254;8603.397762156652;8616.526091064989;8624.525651089187;8691.217790398863;8741.781455463633;8746.081661425555;8761;8607.39748217625;8627.698030568963;8694.869576956351;8700.782480438009;8702.956485217564;8455.577221138918;8530.274926631244;8533.487990720576;8567.528785916773;8574.571271436465;38251.23497530034;38620.420693689615;38628;38746.22507549833;38802;3463.480477928306;3533.1873669056263;3590.9281814363826;3726.9440958385644;3927.509061367329;31569;34812.82593587041;34950;34971.4754278686;35005.649943500495;3855.04164982207;3945.447140742817;3976.2445135424464;3977.3835055566433;3981.5421226558738;265967;270710;271452;271779;273142;62547.6872615634;63263.36736632575;63319;63473.365266346744;63483.6825815868;63611.36388636147;64960.35039649543;75450;76672;77012;13985.230812305217;14064.2967851607;14174.929125354307;14237.21695306768;14279.714405711828;9982.900170998197;10033.09702126812;10059.798804024025;10118.898811011795;10128.898711012796;21245.78754212469;21558.78441215599;21589.13643454244;21644;21697.24059657936;8612.956935215409;8757.430767000125;8788.384813063152;8814.779630509289;8885.289176865836;996.7125447098925;1033.6692258477365;1100.4882729530646;1121.2340563612343;1165.7726743284977;29239.85380073086;29429.85285073604;29545.704542954725;29643.110706678835;29643.55534666982;1029.7322696098918;1053.9051485366354;1062.219268837404;1075.4891426572315;1079.3416016230094;15129.924350378178;15141.242937853063;15409.845901541064;15443.227838608025;16764.245608947625;369697;374035;374986;375240;375453;53868.191977120376;53874.730626346616;54125;54188.45811541913;54345.456545434834;1352.8511863694985;1366.6719987203198;1367.7811550152128;1370.7943808428615;1375.1955106262749;29193.562096568145;29878;30157.849210753804;30531;31347.68652313448;49239.7538012322;49837.252441214136;50025.749871251865;50184.49815501798;50250.74874625603;279354;282260;283090;283494;284067;42363.788181058895;46217.76891115523;46657.53342466532;47606;47737.76131119322;44194;44297.33553996757;44484.11031779411;45040;45322.77338613286;36693.26613467769;36879.631203688696;37522.2495550082;37908.62091379051;38169;36812.44781328336;36973.81513092417;38228.80885595665;38315.42526862155;38384.808075959445;36200;36391.45412818863;36393.81803090968;36599.26801464009;37937.24125517529;26468.470630587664;27927.860360698065;28570.14289571233;28938.13185604435;29347.266318342212;26468.470630587664;27531.83383910876;28522.42955140927;28661.772333719327;28814.423711526073 07:01:27 INFO - 2015-11-25 07:01:27,572 DEBUG : BROWSER_OUTPUT: |2;jquery.html;356.1274876552446;356.2643141911952;358.2405300762383;358.88336290705513;359.25813195750754;16658.500244992654;17018.744718829214;17751;17825.7326140108;18328.175232114554;16317.59206019847;16576.17119144041;16757.664846703057;17285.481435556954;17364.479065628053;4801.61587073035;4928.211486162215;4939.061578300111;4945.950540494603;4950.455449900508;4575.199340115483;4693.06138772247;4845;4883.365162528867;4894.730789806559;4803.03939212159;4811.518848115178;4818.277258411249;4829.082474329884;4839.298301746245;4780.426348838144;4811.975940120295;4831.299461578052;4843.515648435164;4857.708537487727;4722.386089808338;4760.523947605212;4767.475577686451;4774.355462012619;4777.355057067286;14240.93193010518;20201;20343.18627254908;20397.082131304127;20600.175992960263;2083.624947509448;2121.7135686682254;2184.1263494602126;2263.4680850000236;2267.2858049714287;8835.160659737314;9082.818343633091;9223.815523689556;11411.429428528623;13000.869991300155;1770.0795586295183;1829.5151784777045;1890.886546807196;1917.8753381030187;1928.556432020629;56144.71927640377;56970.715146424416;58241.41758582402;59022;59738;9670.032996700415;9997.650082247232;10049.899501004897;10143.797124057477;10144.340617859816;11812.232204906653;11982.34097124649;12424.503019879285;12589.118761686594;12984.805227921588;6891.896621550681;6994.160700715946;7009.509334346547;7016.5088443808445;7097.680604372816;6685.431738302202;6703.765368212089;6818.795436136825;6823.829404264834;6870.931290687129;4196.6990233027855;4249.660027197817;4353.824467393849;4356.194104090753;4418.315161150027;1818.3726614318305;1832.3403574713093;1857.3499275253553;1865.4030710172615;1868.7757469103794;405.6957282038471;933.5472295936465;936.9906300936904;947.5025611553851;964.8600952861796;9798.951005245071;10509.474526273656;16599.170041497877;17377;17521.64956700859;964.0889359555154;965.2760429677742;991.8413053911339;993.3890657245742;1070.1652710885521;9327.580258888367;9340.673076442292;9392.95303523478;9606.711798646053;9825.213982881496;442860;523250;530963;535828;603248;81185;82651.58674206711;82783.5860820692;82895.5855220732;83331;1139.3847322445786;1147.8966892979504;1148.8047032004747;1159.646307876089;1168;15860.52418427449;15948.601284967968;16075.758863616822;16090;16352.591185219988;33250.33499330048;35180.648193517736;35781.28437431289;36022.819885900404;36032.27935441329;44979.32531011972;45868.54131458776;47143.29285060658;47491.762541187076;47576.524234757206;9284.628614855601;9322.766930826783;9451.952740236255;9564;9567.665131720356;13712.725745485233;17820.91089544544;17923.641527169646;18369.908150459163;18402.63194736071;14819.925900370428;14945.850541494447;15178.392864285304;15199.848001519844;15290.464833730766;14193.503227386991;14218.217998010208;14614;14983.850161498245;15180.468683596022;14620.561383158521;14730.926345368205;14858.554243372717;14897.47858824936;14949.327280272631;8766.430182038148;8786.635871296754;8901;8949.731508054769;9040.954795225982;9365.953170234105;10971.396573188233;14217.573472795833;14442.205678687353;14671.63320916943 07:01:27 INFO - 2015-11-25 07:01:27,573 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1493.8356780754116;1547.1645311531774;1622.780924575182;1676.297984154447;1774.3700986149909;6623.768168114117;6907.343802338783;7262.745803896864;7686.615669216545;7731;7224.422046236302;7432;7477.73827916023;7631.1605723370385;7646.273604007624;2290.0725206291436;2301.413139649387;2310.364649721323;2311.2141871763597;2314.305708287507;2284.794368506834;2299.655051742244;2306.550222706573;2312.4681323295654;2323.3726893738767;2253.9323820285417;2262.536180083079;2283.668868014137;2298.321995011476;2298.7816157465013;2266.1841736974675;2296;2333.801626861719;2333.9299821005397;2339.333290012347;2326.3137374474572;2333.125078095714;2351.670766092748;2352.870592117433;2357.39886328985;7578.355839753627;7581.886271705929;7602;7673.846523069563;7679.884801727979;4621.607163391116;4709.952900470986;4712.69367491112;4713.222318317474;4717.056588682277;3137.2000139964393;3164.224764932583;3166.9524957125664;3167.3823604397153;3206.6472688004533;968.331851022792;989.2382865193832;989.2580564576567;994.5425104451961;1004.1414590525069;10226.284160108795;10259.743506412326;10316.742081447948;10386.480675966171;10412.479376031244;4055.1889622075705;4078.469798926121;4122.649574786117;4124.381342798544;4178.78815143605;8334.958325208456;8382.83234335322;8403.789905252295;8429.28351090152;8439.535825529654;4640.32715256286;4658.464276608166;4678.6491013174145;4678.929816052694;4688.2733176357715;4194.706370554093;4195.895102622459;4264.445622069111;4265.3815196796295;4299.14017196562;3471.861125555;3483.4846841623976;3487.9476807847905;3503.7547371683736;3517;1077.256692881909;1086.0334302470808;1091.1598069486452;1095.2552264460085;1104.1829046505527;1359.5173713331822;1362.7615167345675;1368.835739711241;1389.9861001389856;1395.9022868399318;7110.893336599956;7227.313405226495;7230;7231.168415632164;7252.166000909858;473.8767920340736;481.33816002995883;483.2654365364696;484.51790468484086;503.35319114937863;5646.491815736604;5653.604247702621;5661.830145095653;5673.461021202979;5714.05718056516;8688.174623410765;8752.168543988311;8770.166834150745;8776.648934042567;8822.470651760786;8715.041345451995;8729.738107856647;8737.169968852948;8762.649494020296;8820.691275805317;6968.372846443743;6971.8605627887155;6986.54587451814;6992.2658120897595;7044.859102817915;6991.230964593992;7001.159860816734;7086.114235720535;7114.893276600958;7121.359077683033;7757.340626046624;7921.762347129596;8042.477238979448;8100.756977290691;8104.189581041848;8056.758297251092;8113.959430202811;8122.837543248984;8172.38707096982;8190.959045204736;6757.121574195323;6787.117674702257;6873.140857392826;6881.10545629065;6981.301869812978;7292.307230813064;7303.707851685873;7318.231585683427;7341.669624866787;7383.150937642025;6896.793096206921;7087.078679771596;7102.360787529147;7127.572345659277;7168.820779480555;6780.457563395015;6817.863642727217;6862.862742745017;6934.098567186234;6962.164540255202;6887.758928437481;7041.929580704127;7060.082189315356;7091;7096.787096387116;6334.809955701337;6371.840703982438;6392.840178995562;6396.200474940632;6398.744050237939;6495.350464953656;6547.901781473186;6580.473562114924;6585.111010013587;6600.306967768315 07:01:27 INFO - 2015-11-25 07:01:27,574 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.7962382758431;340.4246822869351;349.84431927792133;352.3463974327624;356.6540455757914;6232.439080482755;6239.282482514513;6258.655773932438;6260.342664020275;6342.334054924236;6211.47202487789;6215.440610345068;6243.219597550306;6262.9686851565675;6270.811875643727;4652.34867118602;4674.368960190365;4679.74261415622;4683.648726345529;4689.2966055091665;4675.228587283094;4686.203345431268;4690.226112691402;4694.788734506938;4699.624030077585;4549.590536851682;4572.8399506017295;4634.189016922042;4650.2559590465535;4650.953490465085;4623.029163875573;4638.9536104638855;4673.065386922629;4697.53024697531;4700.29495575663;4031.9596804031876;4042.413849991735;4050.189962007581;4052.1693052924375;4060.9796951015055;5865.501432378253;5898.439648233454;5904.822855314304;5918.99377105893;5922.200502932135;5342.893142137174;5500.202470641734;5515.338159420855;5520.254765606632;5540.030494663418;6576.210854697419;6591.670416479157;6603.108580341688;6608.140941677551;6608.603483790988;4533.048059907391;4536.523665015159;4544.545545445462;4545.136424079415;4545.840895568673;6694.163229596301;6784.966075169642;6798.184217893835;6836.589804611739;6858.14273215853;4603.171429142786;4647.279671650899;4650.627949764011;4655.441347038377;4668.089722504079;4684.718916864999;4710.7880145392855;4713.033828065239;4714.622830173578;4724.149653062482;5444.101723215705;5482;5482.80810171642;5494.313210848644;5496.258005169331;6430.807075787734;6625.668716564249;6626.403623673892;6660.467162626978;6667.933320666828;1635.8445947634955;1657.3619156624545;1658.2703610411381;1658.9751153732705;1660.900345979245;1072.14228617106;1072.4798472740715;1099.0603034405628;1106.2477515289681;1117.8826223246604;6377.617342959437;6412.967935160387;6431.807045788634;6438.7102580384;6440.098386225932;6846.965765171142;6855.485838562127;6861.759838405632;6879.140107486564;6880.036794848726;6369.20384951881;6421.775237866652;6440.323766004502;6494.214091124884;6511.153550038464;3756.2111956489443;3763.1532905096133;3764.3412402829395;3764.7364684472373;3766.303233901737;7225.169105552824;7234.855302893912;7240.637968101679;7250.347468727839;7268.382187514015;6965.965170174117;7090.503664743522;7109.324614161646;7121.786346409616;7146.7141314346845;7247.9637601811655;7279.67241474135;7280.745173918887;7291.270872912666;7298.379637730853;6894.517383783087;6962.895556566555;6977.406920411619;6977.616231107338;6977.860442791014;6701.765438209639;6861.176658800976;6865.176178858569;6890.345417185359;6892.207396149507;7248.746293879689;7260.49176557657;7261.891071634035;7264.164621068433;7268.236835132235;4565.6119229865835;4650.302454631832;4655;4656.138614356355;4656.627469802475;6806.795796125926;6941.548799328028;6964.129483814523;6973.232944376013;6975.372216500336;6719;6745.190577130776;6761.628110453776;6770.052192693125;6772.187337519529;6559.278479367361;6710.16122984627;6738.831529211809;6749.42629876466;6761.22245941723;6558.049082883051;6603.339666033358;6611.867762644816;6615.206175259;6627.668616569249;6738.35985581369;6754.12196414463;6758.155230596176;6759.662016899234;6774.966125169342;6465.418112369909;6553.2136143663065;6556.770513032021;6609.966950165219;6619.867602647823 07:01:27 INFO - 2015-11-25 07:01:27,574 DEBUG : BROWSER_OUTPUT: |5;yui.html;798.3972101063696;824.8267863748613;856.3705676327902;870.8476016697077;902.5171533229724;13131.803022954664;13157.802632960489;13194.53819116331;13264.403101860404;13268.270245136513;13194.868051319483;13273.402696878664;13311.400986955587;13338.999575031887;13348.599542013755;673.1249375811437;684.1448189762797;813.9145389734082;831.0152469323871;831.5177197225595;818.292177266666;825.0511911301991;826.1408135539062;826.2522417212402;830.1200727229157;868.4224990381421;869.2090197919894;873.698573991975;874.6151693254948;875.155474966657;863.5207459859776;863.8272345530856;864.1833467373335;864.7405778266495;864.9697260595912;640.8494003909078;641.1472741254121;642.7557528139324;643.5527308520581;645.141961191614;12608.74782504354;12637.178583392142;12783.61649150527;12798.872011279862;12844.871551284461;12387.75224495514;12550.1842380245;12874.613761587074;12892.677683057998;12930;10113.08982191599;10217.599808176496;10259.127974122208;10283.286681357085;10456.424896630682;3570.357335679577;3617.8371973261264;3688.1332886771856;3765.3787125124322;3778.3387907116144;48051.27923081157;48520.51479485231;48613.513864861256;49133.26300105501;49324.013519729764;10059.29584929048;10175.592976280866;10185;10347.844782328271;10479.47602619866;17929.37247196342;17969.550761231072;18075.54811129732;18087.72868406975;18089.095545222684;7471.140818805907;7623.69505219785;7641.197674244236;7738.961305193438;7846.450748447668;4301.09676967834;4354.608085272341;4369.4101296325225;4386.320120381346;4413.889652758707;3109.378124375134;3121.1104835121882;3149.9055028349185;3151.07043422191;3182.0931034655014;588.4057102326595;601.362555690969;606.8240210338952;609.871926895357;615.9322474527798;652.3965332067818;658.6706646676662;674.9055132281485;678.4640134293951;692.425287011779;8857.512836793909;9100.635974560944;9598.712038638852;9632.75918102053;9757.316987810927;543.6351869368295;681.2574294019543;685.907402500666;691.8062942376142;701;4303;4350.564943505624;4370.475542934868;4407.977960110243;4501.099780044005;56246.156307655416;56987;57306.71346643323;57558.42441575614;58002.70998645064;50447.74776126096;51220.231696524585;51694;52243;52319.476805232225;46060.76969615131;46906.530934690214;46914.061718765435;46915.76542117336;47001.529984699715;8536.786580335542;8631.87052194205;8736.519491428033;8743.081976392641;8777.868331975153;33580.328393431504;34111.317773644885;34133;35791.82104089463;35981.1004724884;43659.34510982401;44493.77753111214;44924.77537612291;45899;46125;10883.782324353628;11152.60490997056;11214.102871770401;11218.158638102012;11239.157063220127;12059.15585909013;12106.636800895987;12189.878101218874;12561.87438125607;12760.523191427832;11195.776084478102;11672.182947193614;11700.941495292469;12219.816702749644;12245.142840001114;11090.445477726244;11314.717132071764;11352.148588855704;11438.084953203557;11735.882641173821;11109.388983605979;11208.439578021229;11226.21416500837;11239;11629.011534019703;9607.567659455202;10055;10117.696469105938;10273.67668310414;10488.790224195322;8903.688370906988;9157.49633770149;10152.289339746147;10225.335353202017;10227.539760710637 07:01:27 INFO - 2015-11-25 07:01:27,574 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:01:27 INFO - 2015-11-25 07:01:27,574 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,301 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463687567__endTimestamp 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,575 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5503.67 Median:5724.08 stddev:622.60 (10.9%) stddev-sans-first:598.02 07:01:27 INFO - 2015-11-25 07:01:27,576 DEBUG : BROWSER_OUTPUT: Values: 3213.2 3251.7 3262.3 3270.1 3315.0 5262.7 5493.1 5533.0 5547.6 5549.6 5418.7 5445.9 5448.2 5587.8 5602.2 4941.7 5004.5 5058.7 5075.0 5093.4 4965.2 5005.8 5041.1 5095.6 5160.2 4822.4 4832.1 4927.9 5063.1 5094.8 4972.2 5043.7 5051.8 5062.2 5068.3 5016.7 5026.9 5028.4 5035.0 5041.5 5074.6 5178.9 5229.7 5335.8 5366.6 5878.6 5905.1 5915.2 5926.1 5932.9 5960.4 5961.0 5969.8 5975.1 5979.0 5393.9 5493.3 5518.9 5571.0 5628.9 6024.7 6039.9 6041.7 6043.2 6055.6 5652.4 5820.9 5841.6 5857.5 5880.2 5813.1 5859.0 5915.3 5923.2 5978.5 5921.6 5942.8 5975.7 5993.5 5998.6 5643.7 5771.1 6000.6 6085.2 6088.2 4489.2 4540.1 4547.3 4567.7 4580.2 4096.0 4112.3 4135.9 4136.3 4172.6 5400.3 5546.6 5564.4 5697.6 5759.9 5945.9 6084.2 6109.1 6116.1 6131.0 5777.6 5800.8 5801.5 5802.4 5820.3 5021.7 5120.2 5124.7 5192.7 5270.3 6103.9 6150.8 6167.6 6177.7 6183.5 5977.8 5978.7 5980.1 5984.9 6023.2 5873.1 5928.1 6138.2 6152.9 6197.5 5798.7 5843.9 5855.6 5872.3 5920.3 5950.5 5955.0 5962.0 5966.9 5985.4 5561.3 5647.5 5689.3 5724.1 5787.2 5706.2 5727.6 5729.7 5767.2 5819.4 5480.9 5665.5 5689.0 5691.9 5697.7 5689.2 5799.9 5850.8 5927.0 5976.6 5616.0 5784.3 5785.2 5787.6 5819.4 07:01:27 INFO - 2015-11-25 07:01:27,577 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,577 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48978.88 Median:38283.33 stddev:81898.39 (213.9%) stddev-sans-first:82047.58 07:01:27 INFO - 2015-11-25 07:01:27,577 DEBUG : BROWSER_OUTPUT: Values: 600.7 613.9 614.7 620.2 625.2 39522.6 39826.4 40021.2 40405.0 40418.0 40725.2 40818.6 40955.0 41081.4 41182.2 8505.5 8720.2 8772.4 8786.4 8793.0 8534.2 8595.6 8597.4 8603.4 8616.5 8624.5 8691.2 8741.8 8746.1 8761.0 8607.4 8627.7 8694.9 8700.8 8703.0 8455.6 8530.3 8533.5 8567.5 8574.6 38251.2 38620.4 38628.0 38746.2 38802.0 3463.5 3533.2 3590.9 3726.9 3927.5 31569.0 34812.8 34950.0 34971.5 35005.6 3855.0 3945.4 3976.2 3977.4 3981.5 265967.0 270710.0 271452.0 271779.0 273142.0 62547.7 63263.4 63319.0 63473.4 63483.7 63611.4 64960.4 75450.0 76672.0 77012.0 13985.2 14064.3 14174.9 14237.2 14279.7 9982.9 10033.1 10059.8 10118.9 10128.9 21245.8 21558.8 21589.1 21644.0 21697.2 8613.0 8757.4 8788.4 8814.8 8885.3 996.7 1033.7 1100.5 1121.2 1165.8 29239.9 29429.9 29545.7 29643.1 29643.6 1029.7 1053.9 1062.2 1075.5 1079.3 15129.9 15141.2 15409.8 15443.2 16764.2 369697.0 374035.0 374986.0 375240.0 375453.0 53868.2 53874.7 54125.0 54188.5 54345.5 1352.9 1366.7 1367.8 1370.8 1375.2 29193.6 29878.0 30157.8 30531.0 31347.7 49239.8 49837.3 50025.7 50184.5 50250.7 279354.0 282260.0 283090.0 283494.0 284067.0 42363.8 46217.8 46657.5 47606.0 47737.8 44194.0 44297.3 44484.1 45040.0 45322.8 36693.3 36879.6 37522.2 37908.6 38169.0 36812.4 36973.8 38228.8 38315.4 38384.8 36200.0 36391.5 36393.8 36599.3 37937.2 26468.5 27927.9 28570.1 28938.1 29347.3 26468.5 27531.8 28522.4 28661.8 28814.4 07:01:27 INFO - 2015-11-25 07:01:27,577 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,578 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28336.10 Median:4223.18 stddev:86635.58 (2051.4%) stddev-sans-first:86853.14 07:01:27 INFO - 2015-11-25 07:01:27,578 DEBUG : BROWSER_OUTPUT: Values: 356.1 356.3 358.2 358.9 359.3 16658.5 17018.7 17751.0 17825.7 18328.2 16317.6 16576.2 16757.7 17285.5 17364.5 4801.6 4928.2 4939.1 4946.0 4950.5 4575.2 4693.1 4845.0 4883.4 4894.7 4803.0 4811.5 4818.3 4829.1 4839.3 4780.4 4812.0 4831.3 4843.5 4857.7 4722.4 4760.5 4767.5 4774.4 4777.4 14240.9 20201.0 20343.2 20397.1 20600.2 2083.6 2121.7 2184.1 2263.5 2267.3 8835.2 9082.8 9223.8 11411.4 13000.9 1770.1 1829.5 1890.9 1917.9 1928.6 56144.7 56970.7 58241.4 59022.0 59738.0 9670.0 9997.7 10049.9 10143.8 10144.3 11812.2 11982.3 12424.5 12589.1 12984.8 6891.9 6994.2 7009.5 7016.5 7097.7 6685.4 6703.8 6818.8 6823.8 6870.9 4196.7 4249.7 4353.8 4356.2 4418.3 1818.4 1832.3 1857.3 1865.4 1868.8 405.7 933.5 937.0 947.5 964.9 9799.0 10509.5 16599.2 17377.0 17521.6 964.1 965.3 991.8 993.4 1070.2 9327.6 9340.7 9393.0 9606.7 9825.2 442860.0 523250.0 530963.0 535828.0 603248.0 81185.0 82651.6 82783.6 82895.6 83331.0 1139.4 1147.9 1148.8 1159.6 1168.0 15860.5 15948.6 16075.8 16090.0 16352.6 33250.3 35180.6 35781.3 36022.8 36032.3 44979.3 45868.5 47143.3 47491.8 47576.5 9284.6 9322.8 9452.0 9564.0 9567.7 13712.7 17820.9 17923.6 18369.9 18402.6 14819.9 14945.9 15178.4 15199.8 15290.5 14193.5 14218.2 14614.0 14983.9 15180.5 14620.6 14730.9 14858.6 14897.5 14949.3 8766.4 8786.6 8901.0 8949.7 9041.0 9366.0 10971.4 14217.6 14442.2 14671.6 07:01:27 INFO - 2015-11-25 07:01:27,578 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,578 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5312.07 Median:6447.05 stddev:2695.25 (41.8%) stddev-sans-first:2687.54 07:01:27 INFO - 2015-11-25 07:01:27,579 DEBUG : BROWSER_OUTPUT: Values: 1493.8 1547.2 1622.8 1676.3 1774.4 6623.8 6907.3 7262.7 7686.6 7731.0 7224.4 7432.0 7477.7 7631.2 7646.3 2290.1 2301.4 2310.4 2311.2 2314.3 2284.8 2299.7 2306.6 2312.5 2323.4 2253.9 2262.5 2283.7 2298.3 2298.8 2266.2 2296.0 2333.8 2333.9 2339.3 2326.3 2333.1 2351.7 2352.9 2357.4 7578.4 7581.9 7602.0 7673.8 7679.9 4621.6 4710.0 4712.7 4713.2 4717.1 3137.2 3164.2 3167.0 3167.4 3206.6 968.3 989.2 989.3 994.5 1004.1 10226.3 10259.7 10316.7 10386.5 10412.5 4055.2 4078.5 4122.6 4124.4 4178.8 8335.0 8382.8 8403.8 8429.3 8439.5 4640.3 4658.5 4678.6 4678.9 4688.3 4194.7 4195.9 4264.4 4265.4 4299.1 3471.9 3483.5 3487.9 3503.8 3517.0 1077.3 1086.0 1091.2 1095.3 1104.2 1359.5 1362.8 1368.8 1390.0 1395.9 7110.9 7227.3 7230.0 7231.2 7252.2 473.9 481.3 483.3 484.5 503.4 5646.5 5653.6 5661.8 5673.5 5714.1 8688.2 8752.2 8770.2 8776.6 8822.5 8715.0 8729.7 8737.2 8762.6 8820.7 6968.4 6971.9 6986.5 6992.3 7044.9 6991.2 7001.2 7086.1 7114.9 7121.4 7757.3 7921.8 8042.5 8100.8 8104.2 8056.8 8114.0 8122.8 8172.4 8191.0 6757.1 6787.1 6873.1 6881.1 6981.3 7292.3 7303.7 7318.2 7341.7 7383.2 6896.8 7087.1 7102.4 7127.6 7168.8 6780.5 6817.9 6862.9 6934.1 6962.2 6887.8 7041.9 7060.1 7091.0 7096.8 6334.8 6371.8 6392.8 6396.2 6398.7 6495.4 6547.9 6580.5 6585.1 6600.3 07:01:27 INFO - 2015-11-25 07:01:27,580 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,580 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5560.07 Median:6373.41 stddev:1715.83 (26.9%) stddev-sans-first:1675.25 07:01:27 INFO - 2015-11-25 07:01:27,580 DEBUG : BROWSER_OUTPUT: Values: 336.8 340.4 349.8 352.3 356.7 6232.4 6239.3 6258.7 6260.3 6342.3 6211.5 6215.4 6243.2 6263.0 6270.8 4652.3 4674.4 4679.7 4683.6 4689.3 4675.2 4686.2 4690.2 4694.8 4699.6 4549.6 4572.8 4634.2 4650.3 4651.0 4623.0 4639.0 4673.1 4697.5 4700.3 4032.0 4042.4 4050.2 4052.2 4061.0 5865.5 5898.4 5904.8 5919.0 5922.2 5342.9 5500.2 5515.3 5520.3 5540.0 6576.2 6591.7 6603.1 6608.1 6608.6 4533.0 4536.5 4544.5 4545.1 4545.8 6694.2 6785.0 6798.2 6836.6 6858.1 4603.2 4647.3 4650.6 4655.4 4668.1 4684.7 4710.8 4713.0 4714.6 4724.1 5444.1 5482.0 5482.8 5494.3 5496.3 6430.8 6625.7 6626.4 6660.5 6667.9 1635.8 1657.4 1658.3 1659.0 1660.9 1072.1 1072.5 1099.1 1106.2 1117.9 6377.6 6413.0 6431.8 6438.7 6440.1 6847.0 6855.5 6861.8 6879.1 6880.0 6369.2 6421.8 6440.3 6494.2 6511.2 3756.2 3763.2 3764.3 3764.7 3766.3 7225.2 7234.9 7240.6 7250.3 7268.4 6966.0 7090.5 7109.3 7121.8 7146.7 7248.0 7279.7 7280.7 7291.3 7298.4 6894.5 6962.9 6977.4 6977.6 6977.9 6701.8 6861.2 6865.2 6890.3 6892.2 7248.7 7260.5 7261.9 7264.2 7268.2 4565.6 4650.3 4655.0 4656.1 4656.6 6806.8 6941.5 6964.1 6973.2 6975.4 6719.0 6745.2 6761.6 6770.1 6772.2 6559.3 6710.2 6738.8 6749.4 6761.2 6558.0 6603.3 6611.9 6615.2 6627.7 6738.4 6754.1 6758.2 6759.7 6775.0 6465.4 6553.2 6556.8 6610.0 6619.9 07:01:27 INFO - 2015-11-25 07:01:27,580 DEBUG : BROWSER_OUTPUT: 07:01:27 INFO - 2015-11-25 07:01:27,580 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13900.20 Median:4410.93 stddev:15983.66 (362.4%) stddev-sans-first:15998.21 07:01:27 INFO - 2015-11-25 07:01:27,582 DEBUG : BROWSER_OUTPUT: Values: 798.4 824.8 856.4 870.8 902.5 13131.8 13157.8 13194.5 13264.4 13268.3 13194.9 13273.4 13311.4 13339.0 13348.6 673.1 684.1 813.9 831.0 831.5 818.3 825.1 826.1 826.3 830.1 868.4 869.2 873.7 874.6 875.2 863.5 863.8 864.2 864.7 865.0 640.8 641.1 642.8 643.6 645.1 12608.7 12637.2 12783.6 12798.9 12844.9 12387.8 12550.2 12874.6 12892.7 12930.0 10113.1 10217.6 10259.1 10283.3 10456.4 3570.4 3617.8 3688.1 3765.4 3778.3 48051.3 48520.5 48613.5 49133.3 49324.0 10059.3 10175.6 10185.0 10347.8 10479.5 17929.4 17969.6 18075.5 18087.7 18089.1 7471.1 7623.7 7641.2 7739.0 7846.5 4301.1 4354.6 4369.4 4386.3 4413.9 3109.4 3121.1 3149.9 3151.1 3182.1 588.4 601.4 606.8 609.9 615.9 652.4 658.7 674.9 678.5 692.4 8857.5 9100.6 9598.7 9632.8 9757.3 543.6 681.3 685.9 691.8 701.0 4303.0 4350.6 4370.5 4408.0 4501.1 56246.2 56987.0 57306.7 57558.4 58002.7 50447.7 51220.2 51694.0 52243.0 52319.5 46060.8 46906.5 46914.1 46915.8 47001.5 8536.8 8631.9 8736.5 8743.1 8777.9 33580.3 34111.3 34133.0 35791.8 35981.1 43659.3 44493.8 44924.8 45899.0 46125.0 10883.8 11152.6 11214.1 11218.2 11239.2 12059.2 12106.6 12189.9 12561.9 12760.5 11195.8 11672.2 11700.9 12219.8 12245.1 11090.4 11314.7 11352.1 11438.1 11735.9 11109.4 11208.4 11226.2 11239.0 11629.0 9607.6 10055.0 10117.7 10273.7 10488.8 8903.7 9157.5 10152.3 10225.3 10227.5 07:01:27 INFO - 2015-11-25 07:01:27,582 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:01:27 INFO - 2015-11-25 07:01:27,582 DEBUG : BROWSER_OUTPUT: 07:01:28 INFO - 2015-11-25 07:01:28,830 INFO : Browser exited with error code: 0 07:01:28 INFO - 2015-11-25 07:01:28,849 INFO : Completed test dromaeo_css (00:19:29) 07:01:28 INFO - 2015-11-25 07:01:28,849 INFO : Starting test kraken 07:01:28 INFO - 2015-11-25 07:01:28,849 DEBUG : operating with platform_type : linux_ 07:01:28 INFO - 2015-11-25 07:01:28,849 INFO : Initialising browser for kraken test... 07:01:28 INFO - 2015-11-25 07:01:28,855 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaLiVRc/profile http://localhost:45375/getInfo.html 07:01:46 INFO - 2015-11-25 07:01:46,616 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:01:46 INFO - 2015-11-25 07:01:46,616 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:01:46 INFO - 2015-11-25 07:01:46,616 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:01:46 INFO - 2015-11-25 07:01:46,616 DEBUG : BROWSER_OUTPUT: __metrics 07:01:47 INFO - 2015-11-25 07:01:47,350 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 07:01:49 INFO - 2015-11-25 07:01:49,626 INFO : Browser initialized. 07:01:49 INFO - 2015-11-25 07:01:49,627 INFO : Running cycle 1/1 for kraken test... 07:01:49 INFO - 2015-11-25 07:01:49,627 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaLiVRc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:02:01 INFO - 2015-11-25 07:02:01,345 DEBUG : BROWSER_OUTPUT: RSS: Main: 139288576 07:02:01 INFO - 2015-11-25 07:02:01,346 DEBUG : BROWSER_OUTPUT: 07:02:03 INFO - 2015-11-25 07:02:03,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45375/tests/kraken/driver.html?testName=audio-beat-detection) 07:02:04 INFO - 2015-11-25 07:02:04,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 140181504 07:02:04 INFO - 2015-11-25 07:02:04,397 DEBUG : BROWSER_OUTPUT: 07:02:07 INFO - 2015-11-25 07:02:07,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45375/tests/kraken/driver.html?testName=audio-dft) 07:02:07 INFO - 2015-11-25 07:02:07,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 140447744 07:02:07 INFO - 2015-11-25 07:02:07,655 DEBUG : BROWSER_OUTPUT: 07:02:10 INFO - 2015-11-25 07:02:10,503 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45375/tests/kraken/driver.html?testName=audio-fft) 07:02:11 INFO - 2015-11-25 07:02:11,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 136704000 07:02:11 INFO - 2015-11-25 07:02:11,076 DEBUG : BROWSER_OUTPUT: 07:02:13 INFO - 2015-11-25 07:02:13,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45375/tests/kraken/driver.html?testName=audio-oscillator) 07:02:13 INFO - 2015-11-25 07:02:13,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 137256960 07:02:13 INFO - 2015-11-25 07:02:13,679 DEBUG : BROWSER_OUTPUT: 07:02:15 INFO - 2015-11-25 07:02:15,884 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45375/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:02:16 INFO - 2015-11-25 07:02:16,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 137052160 07:02:16 INFO - 2015-11-25 07:02:16,466 DEBUG : BROWSER_OUTPUT: 07:02:19 INFO - 2015-11-25 07:02:19,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45375/tests/kraken/driver.html?testName=imaging-darkroom) 07:02:20 INFO - 2015-11-25 07:02:20,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 137658368 07:02:20 INFO - 2015-11-25 07:02:20,029 DEBUG : BROWSER_OUTPUT: 07:02:23 INFO - 2015-11-25 07:02:23,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45375/tests/kraken/driver.html?testName=imaging-desaturate) 07:02:24 INFO - 2015-11-25 07:02:24,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 137732096 07:02:24 INFO - 2015-11-25 07:02:24,042 DEBUG : BROWSER_OUTPUT: 07:02:26 INFO - 2015-11-25 07:02:26,871 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45375/tests/kraken/driver.html?testName=json-parse-financial) 07:02:27 INFO - 2015-11-25 07:02:27,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 138354688 07:02:27 INFO - 2015-11-25 07:02:27,457 DEBUG : BROWSER_OUTPUT: 07:02:29 INFO - 2015-11-25 07:02:29,033 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45375/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:02:29 INFO - 2015-11-25 07:02:29,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 138616832 07:02:29 INFO - 2015-11-25 07:02:29,607 DEBUG : BROWSER_OUTPUT: 07:02:31 INFO - 2015-11-25 07:02:31,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:02:32 INFO - 2015-11-25 07:02:32,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 138280960 07:02:32 INFO - 2015-11-25 07:02:32,051 DEBUG : BROWSER_OUTPUT: 07:02:34 INFO - 2015-11-25 07:02:34,123 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:02:34 INFO - 2015-11-25 07:02:34,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 138178560 07:02:34 INFO - 2015-11-25 07:02:34,710 DEBUG : BROWSER_OUTPUT: 07:02:37 INFO - 2015-11-25 07:02:37,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:02:37 INFO - 2015-11-25 07:02:37,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 138178560 07:02:37 INFO - 2015-11-25 07:02:37,739 DEBUG : BROWSER_OUTPUT: 07:02:41 INFO - 2015-11-25 07:02:41,290 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:02:41 INFO - 2015-11-25 07:02:41,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 136957952 07:02:41 INFO - 2015-11-25 07:02:41,877 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,550 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45375/tests/kraken/driver.html?testName=ai-astar) 07:02:43 INFO - 2015-11-25 07:02:43,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 137670656 07:02:43 INFO - 2015-11-25 07:02:43,801 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,802 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:02:43 INFO - 2015-11-25 07:02:43,802 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;105;112;111;112;124;110;110;111;109 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;128;159;164;128;156;155;132;160;125 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;162;164;164;163;162;161;164;173;164 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;70;74;72;72;139;72;73;71 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;130;93;93;93;139;93;93;134 07:02:43 INFO - 2015-11-25 07:02:43,803 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;110;110;109;109 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;148;146;147;147;146;147;146;146;147 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;108;98;96;99;96;96 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;64;63;62;63;64;65;62;62;63 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;71;69;70;71;69;69;71;70;69 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;85;86;87;86;89;85;86;85;85 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;117;123;118;122;133;118;118;125;123 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;151;143;163;155;161;157;155;152;138 07:02:43 INFO - 2015-11-25 07:02:43,804 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;69;68;68;70;67;70;69;67;66 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,716 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463763801__endTimestamp 07:02:43 INFO - 2015-11-25 07:02:43,805 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:4.86 (4.4%) stddev-sans-first:5.13 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: Values: 110.0 105.0 112.0 111.0 112.0 124.0 110.0 110.0 111.0 109.0 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.70 Median:155.50 stddev:15.53 (10.0%) stddev-sans-first:16.39 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: Values: 150.0 128.0 159.0 164.0 128.0 156.0 155.0 132.0 160.0 125.0 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:3.31 (2.0%) stddev-sans-first:3.52 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: Values: 164.0 162.0 164.0 164.0 163.0 162.0 161.0 164.0 173.0 164.0 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.60 Median:72.00 stddev:21.25 (29.5%) stddev-sans-first:22.41 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 70.0 74.0 72.0 72.0 139.0 72.0 73.0 71.0 07:02:43 INFO - 2015-11-25 07:02:43,809 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.50 Median:93.00 stddev:20.01 (21.5%) stddev-sans-first:20.71 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 130.0 93.0 93.0 93.0 139.0 93.0 93.0 134.0 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.99 (0.7%) stddev-sans-first:0.71 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: Values: 149.0 148.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.70 Median:96.00 stddev:3.77 (3.9%) stddev-sans-first:3.95 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 108.0 98.0 96.0 99.0 96.0 96.0 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:1.05 (1.7%) stddev-sans-first:1.05 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 62.0 63.0 64.0 65.0 62.0 62.0 63.0 07:02:43 INFO - 2015-11-25 07:02:43,810 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: Values: 69.0 71.0 69.0 70.0 71.0 69.0 69.0 71.0 70.0 69.0 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.32 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: Values: 86.0 85.0 86.0 87.0 86.0 89.0 85.0 86.0 85.0 85.0 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:123.00 stddev:4.82 (3.9%) stddev-sans-first:5.06 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: Values: 124.0 117.0 123.0 118.0 122.0 133.0 118.0 118.0 125.0 123.0 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:155.00 stddev:8.59 (5.5%) stddev-sans-first:8.04 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: Values: 140.0 151.0 143.0 163.0 155.0 161.0 157.0 155.0 152.0 138.0 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.37 (2.0%) stddev-sans-first:1.39 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 68.0 68.0 70.0 67.0 70.0 69.0 67.0 66.0 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:02:43 INFO - 2015-11-25 07:02:43,811 DEBUG : BROWSER_OUTPUT: 07:02:46 INFO - 2015-11-25 07:02:46,422 INFO : Browser exited with error code: 0 07:02:46 INFO - 2015-11-25 07:02:46,473 INFO : Completed test kraken (00:01:17) 07:02:46 INFO - 2015-11-25 07:02:46,473 INFO : Starting test v8_7 07:02:46 INFO - 2015-11-25 07:02:46,473 DEBUG : operating with platform_type : linux_ 07:02:46 INFO - 2015-11-25 07:02:46,473 INFO : Initialising browser for v8_7 test... 07:02:46 INFO - 2015-11-25 07:02:46,479 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpH33Gbp/profile http://localhost:45375/getInfo.html 07:03:03 INFO - 2015-11-25 07:03:03,020 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:03:03 INFO - 2015-11-25 07:03:03,020 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:03:03 INFO - 2015-11-25 07:03:03,020 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:03:03 INFO - 2015-11-25 07:03:03,020 DEBUG : BROWSER_OUTPUT: __metrics 07:03:05 INFO - 2015-11-25 07:03:05,918 INFO : Browser initialized. 07:03:05 INFO - 2015-11-25 07:03:05,919 INFO : Running cycle 1/1 for v8_7 test... 07:03:05 INFO - 2015-11-25 07:03:05,919 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpH33Gbp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:03:17 INFO - 2015-11-25 07:03:17,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 142897152 07:03:17 INFO - 2015-11-25 07:03:17,604 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,025 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45375/tests/v8_7/run.html (next: http://localhost:45375/tests/v8_7/run.html) 07:03:39 INFO - 2015-11-25 07:03:39,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 139898880 07:03:39 INFO - 2015-11-25 07:03:39,275 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,275 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:03:39 INFO - 2015-11-25 07:03:39,275 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:03:39 INFO - 2015-11-25 07:03:39,275 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |0;Richards;167.6727028839705 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.42555164101614 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.74115665356544 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1096.4912280701753 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |5;Earley;746.2686567164179 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |6;Boyer;12108.43373493976 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |7;RegExp;25375 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |8;Splay;655.307994757536 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4864.077669902913 07:03:39 INFO - 2015-11-25 07:03:39,276 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: __startTimestamp1448463819275__endTimestamp 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.67 Median:167.67 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: Values: 167.7 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.43 Median:185.43 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: Values: 185.4 07:03:39 INFO - 2015-11-25 07:03:39,277 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,278 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.74 Median:280.74 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,278 DEBUG : BROWSER_OUTPUT: Values: 280.7 07:03:39 INFO - 2015-11-25 07:03:39,278 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,279 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,279 DEBUG : BROWSER_OUTPUT: Values: 5170.1 07:03:39 INFO - 2015-11-25 07:03:39,279 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1096.49 Median:1096.49 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: Values: 1096.5 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:746.27 Median:746.27 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: Values: 746.3 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,280 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12108.43 Median:12108.43 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: Values: 12108.4 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25375.00 Median:25375.00 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: Values: 25375.0 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:655.31 Median:655.31 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,281 DEBUG : BROWSER_OUTPUT: Values: 655.3 07:03:39 INFO - 2015-11-25 07:03:39,282 DEBUG : BROWSER_OUTPUT: 07:03:39 INFO - 2015-11-25 07:03:39,282 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4864.08 Median:4864.08 stddev:0.00 (0.0%) 07:03:39 INFO - 2015-11-25 07:03:39,282 DEBUG : BROWSER_OUTPUT: Values: 4864.1 07:03:39 INFO - 2015-11-25 07:03:39,282 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:03:39 INFO - 2015-11-25 07:03:39,282 DEBUG : BROWSER_OUTPUT: 07:03:40 INFO - 2015-11-25 07:03:40,562 INFO : Browser exited with error code: 0 07:03:40 INFO - 2015-11-25 07:03:40,579 INFO : Completed test v8_7 (00:00:54) 07:03:40 INFO - 2015-11-25 07:03:40,918 INFO : Completed test suite (00:21:41) 07:03:40 INFO - 2015-11-25 07:03:40,918 DEBUG : Working with test: dromaeo_css 07:03:40 INFO - 2015-11-25 07:03:40,918 DEBUG : Generating results file: dromaeo_css 07:03:40 INFO - 2015-11-25 07:03:40,919 DEBUG : Working with test: kraken 07:03:40 INFO - 2015-11-25 07:03:40,919 DEBUG : Generating results file: kraken 07:03:40 INFO - 2015-11-25 07:03:40,920 INFO : javascript benchmark 07:03:40 INFO - 2015-11-25 07:03:40,920 DEBUG : Working with test: v8_7 07:03:40 INFO - 2015-11-25 07:03:40,920 DEBUG : Generating results file: v8_7 07:03:40 INFO - 2015-11-25 07:03:40,920 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:40 INFO - 2015-11-25 07:03:40,987 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 07:03:40 INFO - 2015-11-25 07:03:40,987 DEBUG : process_Request line: dromaeo_css 7082.27 graph.html#tests=[[72,1,43]] 07:03:40 INFO - 2015-11-25 07:03:40,987 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:41 INFO - 2015-11-25 07:03:41,022 DEBUG : process_Request line: kraken 1520.30 graph.html#tests=[[232,1,43]] 07:03:41 INFO - 2015-11-25 07:03:41,022 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:41 INFO - 2015-11-25 07:03:41,055 DEBUG : process_Request line: v8_7 21068.47 graph.html#tests=[[230,1,43]] 07:03:41 INFO - 2015-11-25 07:03:41,056 INFO : javascript benchmark 07:03:41 INFO - 2015-11-25 07:03:41,059 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3213.196700824794, 3251.7398608111353, 3262.2822978944623, 3270.1334146451186, 3315.0220684897954, 5262.736863156846, 5493.066178749612, 5533.0, 5547.58393120516, 5549.556035517159, 5418.7019713915715, 5445.891082178354, 5448.210009548613, 5587.832365029046, 5602.1596760485945, 4941.654084214116, 5004.499550045003, 5058.721770302632, 5075.0357432087785, 5093.363329583802, 4965.205567109245, 5005.824796132136, 5041.1178043842365, 5095.5923526118, 5160.200168973815, 4822.445418776851, 4832.081904438145, 4927.876803079916, 5063.063333283336, 5094.828189516414, 4972.229304457779, 5043.747812609355, 5051.797928082872, 5062.1900495920845, 5068.26510156029, 5016.673916195436, 5026.874328141789, 5028.396592408934, 5034.974825125851, 5041.546260836506, 5074.568661663763, 5178.938317644874, 5229.660072095302, 5335.759890804923, 5366.624336296461, 5878.588498805086, 5905.114232865061, 5915.201447804534, 5926.081457374114, 5932.851678708023, 5960.403959604048, 5960.97019514904, 5969.761209551612, 5975.073863551156, 5979.043353063489, 5393.865153371158, 5493.340799104133, 5518.862028449281, 5571.025070612626, 5628.887422251532, 6024.728887200086, 6039.8792024159275, 6041.697915104227, 6043.244594425697, 6055.576109672281, 5652.434756524314, 5820.88358232833, 5841.620294680833, 5857.531397488191, 5880.176775251468, 5813.12803079533, 5858.970705146532, 5915.290165180206, 5923.229980102559, 5978.491828194565, 5921.555883308769, 5942.821715348547, 5975.67133807636, 5993.520518358521, 5998.580099393088, 5643.746031428595, 5771.134329850556, 6000.639961602318, 6085.2393450818645, 6088.178095957078, 4489.169503641771, 4540.114677637895, 4547.272436410159, 4567.703099298535, 4580.152671755736, 4096.037431203665, 4112.280350938574, 4135.875923722293, 4136.31750761121, 4172.582741725803, 5400.324959380077, 5546.63946843462, 5564.360098588633, 5697.572682048863, 5759.913601295984, 5945.881082378328, 6084.178635884187, 6109.083637454418, 6116.143739876421, 6131.049687298476, 5777.595568310262, 5800.796972105956, 5801.53587712982, 5802.361740208574, 5820.272465941757, 5021.6735912165595, 5120.18077107661, 5124.66689665178, 5192.662476938987, 5270.341207349082, 6103.943827031755, 6150.815475535741, 6167.5682702210415, 6177.722002509898, 6183.474404675653, 5977.820665380045, 5978.671173085522, 5980.073088671089, 5984.850378740392, 6023.216981792339, 5873.148393482836, 5928.051511758098, 6138.232720909887, 6152.938470615416, 6197.4732147767945, 5798.739056742541, 5843.853903652443, 5855.619384739978, 5872.265966754156, 5920.348761636131, 5950.4644581986095, 5955.0, 5962.0, 5966.880662386815, 5985.40145985398, 5561.304836895388, 5647.519960803378, 5689.34572524165, 5724.084146536701, 5787.189793428839, 5706.172604972338, 5727.599068065196, 5729.684867332337, 5767.192593036894, 5819.388964158872, 5480.862978425571, 5665.461781130786, 5689.0, 5691.857703557279, 5697.658140511582, 5689.203511508309, 5799.8550036249435, 5850.824475265749, 5927.0, 5976.581639285208, 5616.0, 5784.334801497882, 5785.218995435563, 5787.594868359174, 5819.359870414336], "unit": "ms", "name": "dojo.html", "value": 5462.720146462773}, {"lowerIsBetter": false, "replicates": [600.6576251536624, 613.8588124731311, 614.6527212125148, 620.209233227635, 625.162282541395, 39522.604773952255, 39826.402603960894, 40021.1995760085, 40405.0, 40418.0, 40725.185496290054, 40818.59181408185, 40955.0, 41081.38377924326, 41182.176356472926, 8505.532195729233, 8720.215180633742, 8772.429792063496, 8786.384953053288, 8792.956035219848, 8534.189252021048, 8595.5702214889, 8597.398182127254, 8603.397762156652, 8616.526091064989, 8624.525651089187, 8691.217790398863, 8741.781455463633, 8746.081661425555, 8761.0, 8607.39748217625, 8627.698030568963, 8694.869576956351, 8700.782480438009, 8702.956485217564, 8455.577221138918, 8530.274926631244, 8533.487990720576, 8567.528785916773, 8574.571271436465, 38251.23497530034, 38620.420693689615, 38628.0, 38746.22507549833, 38802.0, 3463.480477928306, 3533.1873669056263, 3590.9281814363826, 3726.9440958385644, 3927.509061367329, 31569.0, 34812.82593587041, 34950.0, 34971.4754278686, 35005.649943500495, 3855.04164982207, 3945.447140742817, 3976.2445135424464, 3977.3835055566433, 3981.5421226558738, 265967.0, 270710.0, 271452.0, 271779.0, 273142.0, 62547.6872615634, 63263.36736632575, 63319.0, 63473.365266346744, 63483.6825815868, 63611.36388636147, 64960.35039649543, 75450.0, 76672.0, 77012.0, 13985.230812305217, 14064.2967851607, 14174.929125354307, 14237.21695306768, 14279.714405711828, 9982.900170998197, 10033.09702126812, 10059.798804024025, 10118.898811011795, 10128.898711012796, 21245.78754212469, 21558.78441215599, 21589.13643454244, 21644.0, 21697.24059657936, 8612.956935215409, 8757.430767000125, 8788.384813063152, 8814.779630509289, 8885.289176865836, 996.7125447098925, 1033.6692258477365, 1100.4882729530646, 1121.2340563612343, 1165.7726743284977, 29239.85380073086, 29429.85285073604, 29545.704542954725, 29643.110706678835, 29643.55534666982, 1029.7322696098918, 1053.9051485366354, 1062.219268837404, 1075.4891426572315, 1079.3416016230094, 15129.924350378178, 15141.242937853063, 15409.845901541064, 15443.227838608025, 16764.245608947625, 369697.0, 374035.0, 374986.0, 375240.0, 375453.0, 53868.191977120376, 53874.730626346616, 54125.0, 54188.45811541913, 54345.456545434834, 1352.8511863694985, 1366.6719987203198, 1367.7811550152128, 1370.7943808428615, 1375.1955106262749, 29193.562096568145, 29878.0, 30157.849210753804, 30531.0, 31347.68652313448, 49239.7538012322, 49837.252441214136, 50025.749871251865, 50184.49815501798, 50250.74874625603, 279354.0, 282260.0, 283090.0, 283494.0, 284067.0, 42363.788181058895, 46217.76891115523, 46657.53342466532, 47606.0, 47737.76131119322, 44194.0, 44297.33553996757, 44484.11031779411, 45040.0, 45322.77338613286, 36693.26613467769, 36879.631203688696, 37522.2495550082, 37908.62091379051, 38169.0, 36812.44781328336, 36973.81513092417, 38228.80885595665, 38315.42526862155, 38384.808075959445, 36200.0, 36391.45412818863, 36393.81803090968, 36599.26801464009, 37937.24125517529, 26468.470630587664, 27927.860360698065, 28570.14289571233, 28938.13185604435, 29347.266318342212, 26468.470630587664, 27531.83383910876, 28522.42955140927, 28661.772333719327, 28814.423711526073], "unit": "ms", "name": "ext.html", "value": 19209.595015643696}, {"lowerIsBetter": false, "replicates": [356.1274876552446, 356.2643141911952, 358.2405300762383, 358.88336290705513, 359.25813195750754, 16658.500244992654, 17018.744718829214, 17751.0, 17825.7326140108, 18328.175232114554, 16317.59206019847, 16576.17119144041, 16757.664846703057, 17285.481435556954, 17364.479065628053, 4801.61587073035, 4928.211486162215, 4939.061578300111, 4945.950540494603, 4950.455449900508, 4575.199340115483, 4693.06138772247, 4845.0, 4883.365162528867, 4894.730789806559, 4803.03939212159, 4811.518848115178, 4818.277258411249, 4829.082474329884, 4839.298301746245, 4780.426348838144, 4811.975940120295, 4831.299461578052, 4843.515648435164, 4857.708537487727, 4722.386089808338, 4760.523947605212, 4767.475577686451, 4774.355462012619, 4777.355057067286, 14240.93193010518, 20201.0, 20343.18627254908, 20397.082131304127, 20600.175992960263, 2083.624947509448, 2121.7135686682254, 2184.1263494602126, 2263.4680850000236, 2267.2858049714287, 8835.160659737314, 9082.818343633091, 9223.815523689556, 11411.429428528623, 13000.869991300155, 1770.0795586295183, 1829.5151784777045, 1890.886546807196, 1917.8753381030187, 1928.556432020629, 56144.71927640377, 56970.715146424416, 58241.41758582402, 59022.0, 59738.0, 9670.032996700415, 9997.650082247232, 10049.899501004897, 10143.797124057477, 10144.340617859816, 11812.232204906653, 11982.34097124649, 12424.503019879285, 12589.118761686594, 12984.805227921588, 6891.896621550681, 6994.160700715946, 7009.509334346547, 7016.5088443808445, 7097.680604372816, 6685.431738302202, 6703.765368212089, 6818.795436136825, 6823.829404264834, 6870.931290687129, 4196.6990233027855, 4249.660027197817, 4353.824467393849, 4356.194104090753, 4418.315161150027, 1818.3726614318305, 1832.3403574713093, 1857.3499275253553, 1865.4030710172615, 1868.7757469103794, 405.6957282038471, 933.5472295936465, 936.9906300936904, 947.5025611553851, 964.8600952861796, 9798.951005245071, 10509.474526273656, 16599.170041497877, 17377.0, 17521.64956700859, 964.0889359555154, 965.2760429677742, 991.8413053911339, 993.3890657245742, 1070.1652710885521, 9327.580258888367, 9340.673076442292, 9392.95303523478, 9606.711798646053, 9825.213982881496, 442860.0, 523250.0, 530963.0, 535828.0, 603248.0, 81185.0, 82651.58674206711, 82783.5860820692, 82895.5855220732, 83331.0, 1139.3847322445786, 1147.8966892979504, 1148.8047032004747, 1159.646307876089, 1168.0, 15860.52418427449, 15948.601284967968, 16075.758863616822, 16090.0, 16352.591185219988, 33250.33499330048, 35180.648193517736, 35781.28437431289, 36022.819885900404, 36032.27935441329, 44979.32531011972, 45868.54131458776, 47143.29285060658, 47491.762541187076, 47576.524234757206, 9284.628614855601, 9322.766930826783, 9451.952740236255, 9564.0, 9567.665131720356, 13712.725745485233, 17820.91089544544, 17923.641527169646, 18369.908150459163, 18402.63194736071, 14819.925900370428, 14945.850541494447, 15178.392864285304, 15199.848001519844, 15290.464833730766, 14193.503227386991, 14218.217998010208, 14614.0, 14983.850161498245, 15180.468683596022, 14620.561383158521, 14730.926345368205, 14858.554243372717, 14897.47858824936, 14949.327280272631, 8766.430182038148, 8786.635871296754, 8901.0, 8949.731508054769, 9040.954795225982, 9365.953170234105, 10971.396573188233, 14217.573472795833, 14442.205678687353, 14671.63320916943], "unit": "ms", "name": "jquery.html", "value": 8654.219876461415}, {"lowerIsBetter": false, "replicates": [1493.8356780754116, 1547.1645311531774, 1622.780924575182, 1676.297984154447, 1774.3700986149909, 6623.768168114117, 6907.343802338783, 7262.745803896864, 7686.615669216545, 7731.0, 7224.422046236302, 7432.0, 7477.73827916023, 7631.1605723370385, 7646.273604007624, 2290.0725206291436, 2301.413139649387, 2310.364649721323, 2311.2141871763597, 2314.305708287507, 2284.794368506834, 2299.655051742244, 2306.550222706573, 2312.4681323295654, 2323.3726893738767, 2253.9323820285417, 2262.536180083079, 2283.668868014137, 2298.321995011476, 2298.7816157465013, 2266.1841736974675, 2296.0, 2333.801626861719, 2333.9299821005397, 2339.333290012347, 2326.3137374474572, 2333.125078095714, 2351.670766092748, 2352.870592117433, 2357.39886328985, 7578.355839753627, 7581.886271705929, 7602.0, 7673.846523069563, 7679.884801727979, 4621.607163391116, 4709.952900470986, 4712.69367491112, 4713.222318317474, 4717.056588682277, 3137.2000139964393, 3164.224764932583, 3166.9524957125664, 3167.3823604397153, 3206.6472688004533, 968.331851022792, 989.2382865193832, 989.2580564576567, 994.5425104451961, 1004.1414590525069, 10226.284160108795, 10259.743506412326, 10316.742081447948, 10386.480675966171, 10412.479376031244, 4055.1889622075705, 4078.469798926121, 4122.649574786117, 4124.381342798544, 4178.78815143605, 8334.958325208456, 8382.83234335322, 8403.789905252295, 8429.28351090152, 8439.535825529654, 4640.32715256286, 4658.464276608166, 4678.6491013174145, 4678.929816052694, 4688.2733176357715, 4194.706370554093, 4195.895102622459, 4264.445622069111, 4265.3815196796295, 4299.14017196562, 3471.861125555, 3483.4846841623976, 3487.9476807847905, 3503.7547371683736, 3517.0, 1077.256692881909, 1086.0334302470808, 1091.1598069486452, 1095.2552264460085, 1104.1829046505527, 1359.5173713331822, 1362.7615167345675, 1368.835739711241, 1389.9861001389856, 1395.9022868399318, 7110.893336599956, 7227.313405226495, 7230.0, 7231.168415632164, 7252.166000909858, 473.8767920340736, 481.33816002995883, 483.2654365364696, 484.51790468484086, 503.35319114937863, 5646.491815736604, 5653.604247702621, 5661.830145095653, 5673.461021202979, 5714.05718056516, 8688.174623410765, 8752.168543988311, 8770.166834150745, 8776.648934042567, 8822.470651760786, 8715.041345451995, 8729.738107856647, 8737.169968852948, 8762.649494020296, 8820.691275805317, 6968.372846443743, 6971.8605627887155, 6986.54587451814, 6992.2658120897595, 7044.859102817915, 6991.230964593992, 7001.159860816734, 7086.114235720535, 7114.893276600958, 7121.359077683033, 7757.340626046624, 7921.762347129596, 8042.477238979448, 8100.756977290691, 8104.189581041848, 8056.758297251092, 8113.959430202811, 8122.837543248984, 8172.38707096982, 8190.959045204736, 6757.121574195323, 6787.117674702257, 6873.140857392826, 6881.10545629065, 6981.301869812978, 7292.307230813064, 7303.707851685873, 7318.231585683427, 7341.669624866787, 7383.150937642025, 6896.793096206921, 7087.078679771596, 7102.360787529147, 7127.572345659277, 7168.820779480555, 6780.457563395015, 6817.863642727217, 6862.862742745017, 6934.098567186234, 6962.164540255202, 6887.758928437481, 7041.929580704127, 7060.082189315356, 7091.0, 7096.787096387116, 6334.809955701337, 6371.840703982438, 6392.840178995562, 6396.200474940632, 6398.744050237939, 6495.350464953656, 6547.901781473186, 6580.473562114924, 6585.111010013587, 6600.306967768315], "unit": "ms", "name": "mootools.html", "value": 4333.665974317726}, {"lowerIsBetter": false, "replicates": [336.7962382758431, 340.4246822869351, 349.84431927792133, 352.3463974327624, 356.6540455757914, 6232.439080482755, 6239.282482514513, 6258.655773932438, 6260.342664020275, 6342.334054924236, 6211.47202487789, 6215.440610345068, 6243.219597550306, 6262.9686851565675, 6270.811875643727, 4652.34867118602, 4674.368960190365, 4679.74261415622, 4683.648726345529, 4689.2966055091665, 4675.228587283094, 4686.203345431268, 4690.226112691402, 4694.788734506938, 4699.624030077585, 4549.590536851682, 4572.8399506017295, 4634.189016922042, 4650.2559590465535, 4650.953490465085, 4623.029163875573, 4638.9536104638855, 4673.065386922629, 4697.53024697531, 4700.29495575663, 4031.9596804031876, 4042.413849991735, 4050.189962007581, 4052.1693052924375, 4060.9796951015055, 5865.501432378253, 5898.439648233454, 5904.822855314304, 5918.99377105893, 5922.200502932135, 5342.893142137174, 5500.202470641734, 5515.338159420855, 5520.254765606632, 5540.030494663418, 6576.210854697419, 6591.670416479157, 6603.108580341688, 6608.140941677551, 6608.603483790988, 4533.048059907391, 4536.523665015159, 4544.545545445462, 4545.136424079415, 4545.840895568673, 6694.163229596301, 6784.966075169642, 6798.184217893835, 6836.589804611739, 6858.14273215853, 4603.171429142786, 4647.279671650899, 4650.627949764011, 4655.441347038377, 4668.089722504079, 4684.718916864999, 4710.7880145392855, 4713.033828065239, 4714.622830173578, 4724.149653062482, 5444.101723215705, 5482.0, 5482.80810171642, 5494.313210848644, 5496.258005169331, 6430.807075787734, 6625.668716564249, 6626.403623673892, 6660.467162626978, 6667.933320666828, 1635.8445947634955, 1657.3619156624545, 1658.2703610411381, 1658.9751153732705, 1660.900345979245, 1072.14228617106, 1072.4798472740715, 1099.0603034405628, 1106.2477515289681, 1117.8826223246604, 6377.617342959437, 6412.967935160387, 6431.807045788634, 6438.7102580384, 6440.098386225932, 6846.965765171142, 6855.485838562127, 6861.759838405632, 6879.140107486564, 6880.036794848726, 6369.20384951881, 6421.775237866652, 6440.323766004502, 6494.214091124884, 6511.153550038464, 3756.2111956489443, 3763.1532905096133, 3764.3412402829395, 3764.7364684472373, 3766.303233901737, 7225.169105552824, 7234.855302893912, 7240.637968101679, 7250.347468727839, 7268.382187514015, 6965.965170174117, 7090.503664743522, 7109.324614161646, 7121.786346409616, 7146.7141314346845, 7247.9637601811655, 7279.67241474135, 7280.745173918887, 7291.270872912666, 7298.379637730853, 6894.517383783087, 6962.895556566555, 6977.406920411619, 6977.616231107338, 6977.860442791014, 6701.765438209639, 6861.176658800976, 6865.176178858569, 6890.345417185359, 6892.207396149507, 7248.746293879689, 7260.49176557657, 7261.891071634035, 7264.164621068433, 7268.236835132235, 4565.6119229865835, 4650.302454631832, 4655.0, 4656.138614356355, 4656.627469802475, 6806.795796125926, 6941.548799328028, 6964.129483814523, 6973.232944376013, 6975.372216500336, 6719.0, 6745.190577130776, 6761.628110453776, 6770.052192693125, 6772.187337519529, 6559.278479367361, 6710.16122984627, 6738.831529211809, 6749.42629876466, 6761.22245941723, 6558.049082883051, 6603.339666033358, 6611.867762644816, 6615.206175259, 6627.668616569249, 6738.35985581369, 6754.12196414463, 6758.155230596176, 6759.662016899234, 6774.966125169342, 6465.418112369909, 6553.2136143663065, 6556.770513032021, 6609.966950165219, 6619.867602647823], "unit": "ms", "name": "prototype.html", "value": 4998.895657569071}, {"lowerIsBetter": false, "replicates": [798.3972101063696, 824.8267863748613, 856.3705676327902, 870.8476016697077, 902.5171533229724, 13131.803022954664, 13157.802632960489, 13194.53819116331, 13264.403101860404, 13268.270245136513, 13194.868051319483, 13273.402696878664, 13311.400986955587, 13338.999575031887, 13348.599542013755, 673.1249375811437, 684.1448189762797, 813.9145389734082, 831.0152469323871, 831.5177197225595, 818.292177266666, 825.0511911301991, 826.1408135539062, 826.2522417212402, 830.1200727229157, 868.4224990381421, 869.2090197919894, 873.698573991975, 874.6151693254948, 875.155474966657, 863.5207459859776, 863.8272345530856, 864.1833467373335, 864.7405778266495, 864.9697260595912, 640.8494003909078, 641.1472741254121, 642.7557528139324, 643.5527308520581, 645.141961191614, 12608.74782504354, 12637.178583392142, 12783.61649150527, 12798.872011279862, 12844.871551284461, 12387.75224495514, 12550.1842380245, 12874.613761587074, 12892.677683057998, 12930.0, 10113.08982191599, 10217.599808176496, 10259.127974122208, 10283.286681357085, 10456.424896630682, 3570.357335679577, 3617.8371973261264, 3688.1332886771856, 3765.3787125124322, 3778.3387907116144, 48051.27923081157, 48520.51479485231, 48613.513864861256, 49133.26300105501, 49324.013519729764, 10059.29584929048, 10175.592976280866, 10185.0, 10347.844782328271, 10479.47602619866, 17929.37247196342, 17969.550761231072, 18075.54811129732, 18087.72868406975, 18089.095545222684, 7471.140818805907, 7623.69505219785, 7641.197674244236, 7738.961305193438, 7846.450748447668, 4301.09676967834, 4354.608085272341, 4369.4101296325225, 4386.320120381346, 4413.889652758707, 3109.378124375134, 3121.1104835121882, 3149.9055028349185, 3151.07043422191, 3182.0931034655014, 588.4057102326595, 601.362555690969, 606.8240210338952, 609.871926895357, 615.9322474527798, 652.3965332067818, 658.6706646676662, 674.9055132281485, 678.4640134293951, 692.425287011779, 8857.512836793909, 9100.635974560944, 9598.712038638852, 9632.75918102053, 9757.316987810927, 543.6351869368295, 681.2574294019543, 685.907402500666, 691.8062942376142, 701.0, 4303.0, 4350.564943505624, 4370.475542934868, 4407.977960110243, 4501.099780044005, 56246.156307655416, 56987.0, 57306.71346643323, 57558.42441575614, 58002.70998645064, 50447.74776126096, 51220.231696524585, 51694.0, 52243.0, 52319.476805232225, 46060.76969615131, 46906.530934690214, 46914.061718765435, 46915.76542117336, 47001.529984699715, 8536.786580335542, 8631.87052194205, 8736.519491428033, 8743.081976392641, 8777.868331975153, 33580.328393431504, 34111.317773644885, 34133.0, 35791.82104089463, 35981.1004724884, 43659.34510982401, 44493.77753111214, 44924.77537612291, 45899.0, 46125.0, 10883.782324353628, 11152.60490997056, 11214.102871770401, 11218.158638102012, 11239.157063220127, 12059.15585909013, 12106.636800895987, 12189.878101218874, 12561.87438125607, 12760.523191427832, 11195.776084478102, 11672.182947193614, 11700.941495292469, 12219.816702749644, 12245.142840001114, 11090.445477726244, 11314.717132071764, 11352.148588855704, 11438.084953203557, 11735.882641173821, 11109.388983605979, 11208.439578021229, 11226.21416500837, 11239.0, 11629.011534019703, 9607.567659455202, 10055.0, 10117.696469105938, 10273.67668310414, 10488.790224195322, 8903.688370906988, 9157.49633770149, 10152.289339746147, 10225.335353202017, 10227.539760710637], "unit": "ms", "name": "yui.html", "value": 6413.7522012704185}], "name": "dromaeo_css", "value": 7082.272857926337}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 105.0, 112.0, 111.0, 112.0, 124.0, 110.0, 110.0, 111.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 111.4}, {"lowerIsBetter": true, "replicates": [150.0, 128.0, 159.0, 164.0, 128.0, 156.0, 155.0, 132.0, 160.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.7}, {"lowerIsBetter": true, "replicates": [164.0, 162.0, 164.0, 164.0, 163.0, 162.0, 161.0, 164.0, 173.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 164.1}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 70.0, 74.0, 72.0, 72.0, 139.0, 72.0, 73.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 78.6}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 130.0, 93.0, 93.0, 93.0, 139.0, 93.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.5}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [149.0, 148.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 108.0, 98.0, 96.0, 99.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 97.7}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 62.0, 63.0, 64.0, 65.0, 62.0, 62.0, 63.0], "unit": "ms", "name": "json-parse-financial", "value": 63.0}, {"lowerIsBetter": true, "replicates": [69.0, 71.0, 69.0, 70.0, 71.0, 69.0, 69.0, 71.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 86.0, 87.0, 86.0, 89.0, 85.0, 86.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [124.0, 117.0, 123.0, 118.0, 122.0, 133.0, 118.0, 118.0, 125.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.1}, {"lowerIsBetter": true, "replicates": [140.0, 151.0, 143.0, 163.0, 155.0, 161.0, 157.0, 155.0, 152.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 151.5}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 68.0, 68.0, 70.0, 67.0, 70.0, 69.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.1}], "name": "kraken", "value": 1520.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.6727028839705], "unit": "score", "name": "Richards", "value": 210.54112800000007}, {"lowerIsBetter": false, "replicates": [185.42555164101614], "unit": "score", "name": "DeltaBlue", "value": 356.5743740000001}, {"lowerIsBetter": false, "replicates": [280.74115665356544], "unit": "score", "name": "Encrypt", "value": 948.136722}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1096.4912280701753], "unit": "score", "name": "RayTrace", "value": 674.869968}, {"lowerIsBetter": false, "replicates": [746.2686567164179], "unit": "score", "name": "Earley", "value": 893.0604200000004}, {"lowerIsBetter": false, "replicates": [12108.43373493976], "unit": "score", "name": "Boyer", "value": 55.0412228855721}, {"lowerIsBetter": false, "replicates": [25375.0], "unit": "score", "name": "RegExp", "value": 35.90088669950742}, {"lowerIsBetter": false, "replicates": [655.307994757536], "unit": "score", "name": "Splay", "value": 124.35526599999999}, {"lowerIsBetter": false, "replicates": [4864.077669902913], "unit": "score", "name": "NavierStokes", "value": 305.0938123752493}], "name": "v8_7", "value": 21068.46624509113}]} 07:03:41 INFO - RETURN: dromaeo_css: 7082.27 07:03:41 INFO - RETURN: kraken: 1520.30 07:03:41 INFO - RETURN: v8_7: 21068.47 07:03:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21068.47"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1520.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "7082.27"}}} 07:03:41 INFO - Return code: 0 07:03:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 07:03:41 INFO - # TBPL SUCCESS # 07:03:41 INFO - Running post-action listener: _resource_record_post_action 07:03:41 INFO - Running post-run listener: _resource_record_post_run 07:03:42 INFO - Total resource usage - Wall time: 1323s; CPU: 14.0%; Read bytes: 4096; Write bytes: 508997632; Read time: 11444; Write time: 6595396 07:03:42 INFO - install - Wall time: 22s; CPU: 18.0%; Read bytes: 0; Write bytes: 157601792; Read time: 0; Write time: 1266376 07:03:42 INFO - run-tests - Wall time: 1302s; CPU: 14.0%; Read bytes: 4096; Write bytes: 342843392; Read time: 11444; Write time: 5202316 07:03:42 INFO - Running post-run listener: _upload_blobber_files 07:03:42 INFO - Blob upload gear active. 07:03:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:03:42 INFO - Copying logs to upload dir... 07:03:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1380.629558 ========= master_lag: 118.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 58 secs) (at 2015-11-25 07:05:40.475676) ========= ========= Started set props: build_url (results: 0, elapsed: 18 secs) (at 2015-11-25 07:05:40.479588) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 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.010097 build_url: 'https://queue.taskcluster.net/v1/task/1wUR3H2fQkeKiD7duAeERw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 18.18 ========= ========= Finished set props: build_url (results: 0, elapsed: 18 secs) (at 2015-11-25 07:05:58.666535) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:05:58.669679) ========= 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=256b35f542ca8bede9e27f1f00000291-1448462067.391594-2001093586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004363 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:05:58.705932) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 07:05:58.706331) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 07:05:58.707668) ========= ========= Total master_lag: 136.68 =========