builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1456252433.57 results: success (0) buildid: 20160223081052 builduid: 7edbb97efd624dfcbe0adab514a9889d revision: 30d48874cea4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.571767) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.572281) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.572617) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006598 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.631339) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.631696) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.644953) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.645330) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004678 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.694777) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.695111) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.695502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.695840) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-23 10:33:54-- 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% 225M=0s 2016-02-23 10:33:54 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155715 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.864195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.864547) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022562 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.914734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:53.915117) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30d48874cea4 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30d48874cea4 --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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-23 10:33:54,635 Setting DEBUG logging. 2016-02-23 10:33:54,635 attempt 1/10 2016-02-23 10:33:54,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/30d48874cea4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-23 10:33:55,026 unpacking tar archive at: mozilla-beta-30d48874cea4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.749938 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:54.678818) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:54.679221) ========= echo 30d48874cea4 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'30d48874cea4'] 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 30d48874cea4 program finished with exit code 0 elapsedTime=0.003831 script_repo_revision: '30d48874cea4' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:54.729001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:54.729344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-23 10:33:54.739191) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-23 10:33:54.739586) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:33:55 INFO - MultiFileLogger online at 20160223 10:33:55 in /builds/slave/test-pgo 10:33:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:33:55 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:33:55 INFO - {'append_to_log': False, 10:33:55 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:33:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:33:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:33:55 INFO - 'branch': 'Mozilla-Beta', 10:33:55 INFO - 'buildbot_json_path': 'buildprops.json', 10:33:55 INFO - 'config_files': ('talos/linux_config.py',), 10:33:55 INFO - 'default_actions': ('clobber', 10:33:55 INFO - 'read-buildbot-config', 10:33:55 INFO - 'download-and-extract', 10:33:55 INFO - 'populate-webroot', 10:33:55 INFO - 'create-virtualenv', 10:33:55 INFO - 'install', 10:33:55 INFO - 'run-tests'), 10:33:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:33:55 INFO - 'download_minidump_stackwalk': True, 10:33:55 INFO - 'download_symbols': 'ondemand', 10:33:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:33:55 INFO - 'tooltool.py': '/tools/tooltool.py', 10:33:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:33:55 INFO - '/tools/misc-python/virtualenv.py')}, 10:33:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:33:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:33:55 INFO - 'installer_path': 'installer.exe', 10:33:55 INFO - 'log_level': 'info', 10:33:55 INFO - 'log_name': 'talos', 10:33:55 INFO - 'log_to_console': True, 10:33:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:33:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:33:55 INFO - 'opt_config_files': (), 10:33:55 INFO - 'pip_index': False, 10:33:55 INFO - 'sps_profile': False, 10:33:55 INFO - 'sps_profile_interval': 0, 10:33:55 INFO - 'suite': 'dromaeojs', 10:33:55 INFO - 'system_bits': '32', 10:33:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:33:55 INFO - 'title': 'talos-linux64-ix-002', 10:33:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:33:55 INFO - 'use_talos_json': True, 10:33:55 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:33:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:33:55 INFO - 'work_dir': 'build'} 10:33:55 INFO - ##### 10:33:55 INFO - ##### Running clobber step. 10:33:55 INFO - ##### 10:33:55 INFO - Running pre-action listener: _resource_record_pre_action 10:33:55 INFO - Running main action method: clobber 10:33:55 INFO - rmtree: /builds/slave/test-pgo/build 10:33:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:33:56 INFO - Running post-action listener: _resource_record_post_action 10:33:56 INFO - ##### 10:33:56 INFO - ##### Running read-buildbot-config step. 10:33:56 INFO - ##### 10:33:56 INFO - Running pre-action listener: _resource_record_pre_action 10:33:56 INFO - Running main action method: read_buildbot_config 10:33:56 INFO - Using buildbot properties: 10:33:56 INFO - { 10:33:56 INFO - "properties": { 10:33:56 INFO - "buildnumber": 133, 10:33:56 INFO - "product": "firefox", 10:33:56 INFO - "script_repo_revision": "production", 10:33:56 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:33:56 INFO - "repository": "", 10:33:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:33:56 INFO - "buildid": "20160223081052", 10:33:56 INFO - "slavename": "talos-linux64-ix-002", 10:33:56 INFO - "pgo_build": "True", 10:33:56 INFO - "basedir": "/builds/slave/test-pgo", 10:33:56 INFO - "project": "", 10:33:56 INFO - "platform": "ubuntu64_hw", 10:33:56 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:33:56 INFO - "slavebuilddir": "test", 10:33:56 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:33:56 INFO - "branch": "mozilla-beta", 10:33:56 INFO - "repo_path": "releases/mozilla-beta", 10:33:56 INFO - "stage_platform": "linux64-pgo", 10:33:56 INFO - "builduid": "7edbb97efd624dfcbe0adab514a9889d", 10:33:56 INFO - "revision": "30d48874cea4" 10:33:56 INFO - }, 10:33:56 INFO - "sourcestamp": { 10:33:56 INFO - "repository": "", 10:33:56 INFO - "hasPatch": false, 10:33:56 INFO - "project": "", 10:33:56 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:33:56 INFO - "changes": [ 10:33:56 INFO - { 10:33:56 INFO - "category": null, 10:33:56 INFO - "files": [ 10:33:56 INFO - { 10:33:56 INFO - "url": null, 10:33:56 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:33:56 INFO - } 10:33:56 INFO - ], 10:33:56 INFO - "repository": "", 10:33:56 INFO - "rev": "30d48874cea4", 10:33:56 INFO - "who": "sendchange", 10:33:56 INFO - "when": 1456252251, 10:33:56 INFO - "number": 7641171, 10:33:56 INFO - "comments": "Backed out changeset 8ade38cf0509 (Bug 1089695) to revert sanitize to a sanitize-wont-timeout-abort status. a=sylvestre", 10:33:56 INFO - "project": "", 10:33:56 INFO - "at": "Tue 23 Feb 2016 10:30:51", 10:33:56 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:33:56 INFO - "revlink": "", 10:33:56 INFO - "properties": [ 10:33:56 INFO - [ 10:33:56 INFO - "buildid", 10:33:56 INFO - "20160223081052", 10:33:56 INFO - "Change" 10:33:56 INFO - ], 10:33:56 INFO - [ 10:33:56 INFO - "builduid", 10:33:56 INFO - "7edbb97efd624dfcbe0adab514a9889d", 10:33:56 INFO - "Change" 10:33:56 INFO - ], 10:33:56 INFO - [ 10:33:56 INFO - "pgo_build", 10:33:56 INFO - "True", 10:33:56 INFO - "Change" 10:33:56 INFO - ] 10:33:56 INFO - ], 10:33:56 INFO - "revision": "30d48874cea4" 10:33:56 INFO - } 10:33:56 INFO - ], 10:33:56 INFO - "revision": "30d48874cea4" 10:33:56 INFO - } 10:33:56 INFO - } 10:33:56 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:33:56 INFO - Running post-action listener: _resource_record_post_action 10:33:56 INFO - ##### 10:33:56 INFO - ##### Running download-and-extract step. 10:33:56 INFO - ##### 10:33:56 INFO - Running pre-action listener: _resource_record_pre_action 10:33:56 INFO - Running main action method: download_and_extract 10:33:56 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:33:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/test_packages.json 10:33:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:33:56 INFO - Downloaded 1270 bytes. 10:33:56 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:33:56 INFO - Using the following test package requirements: 10:33:56 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:33:56 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:33:56 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'jsshell-linux-x86_64.zip'], 10:33:56 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:33:56 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:33:56 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:33:56 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:33:56 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:33:56 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:33:56 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:33:56 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:33:56 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:33:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:33:56 INFO - Downloaded 21583186 bytes. 10:33:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:33:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:33:57 INFO - Return code: 0 10:33:57 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:33:57 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')]} 10:33:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:33:57 INFO - Downloaded 21583186 bytes. 10:33:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:33:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:33:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:33:59 INFO - Return code: 0 10:33: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')]} 10:33:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:33:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:33:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:33:59 INFO - Downloaded 11132038 bytes. 10:33:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 10:33:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:33:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 10:33:59 INFO - Return code: 0 10:33: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')]} 10:33:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:33:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:33:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:34:00 INFO - Downloaded 57944666 bytes. 10:34:00 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:34:00 INFO - mkdir: /builds/slave/test-pgo/properties 10:34:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:34:00 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:34:00 INFO - Contents: 10:34:00 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:34:00 INFO - Running post-action listener: _resource_record_post_action 10:34:00 INFO - Running post-action listener: set_extra_try_arguments 10:34:00 INFO - ##### 10:34:00 INFO - ##### Running populate-webroot step. 10:34:00 INFO - ##### 10:34:00 INFO - Running pre-action listener: _resource_record_pre_action 10:34:00 INFO - Running main action method: populate_webroot 10:34:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:34:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:34:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:34:00 INFO - '--tppagecycles', 10:34:00 INFO - '7'], 10:34:00 INFO - 'tests': ['tsvgm']}}, 10:34:00 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:34:00 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:34:00 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:34:00 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:34:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:34:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:34:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'tests': ['damp', 'tps']}, 10:34:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['damp', 'tps']}, 10:34:00 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:34:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:34:00 INFO - 'other': {'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'other_l64': {'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'other_nol64': {'tests': ['a11yr', 10:34:00 INFO - 'ts_paint', 10:34:00 INFO - 'tpaint', 10:34:00 INFO - 'sessionrestore', 10:34:00 INFO - 'sessionrestore_no_auto_restore']}, 10:34:00 INFO - 'svgr': {'tests': ['tsvgx', 10:34:00 INFO - 'tsvgr_opacity', 10:34:00 INFO - 'tart', 10:34:00 INFO - 'tscrollx', 10:34:00 INFO - 'cart']}, 10:34:00 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['tsvgx', 10:34:00 INFO - 'tsvgr_opacity', 10:34:00 INFO - 'tart', 10:34:00 INFO - 'tscrollx', 10:34:00 INFO - 'cart']}, 10:34:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'tests': ['tp5o']}, 10:34:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'talos_options': ['--e10s'], 10:34:00 INFO - 'tests': ['tp5o']}, 10:34:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'talos_options': ['--xperf_path', 10:34:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:34:00 INFO - 'tests': ['tp5n']}, 10:34:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:00 INFO - 'talos_options': ['--e10s', 10:34:00 INFO - '--xperf_path', 10:34:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:34:00 INFO - 'tests': ['tp5n']}}, 10:34:00 INFO - 'talos.zip': {'path': '', 10:34:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:34:00 INFO - Running post-action listener: _resource_record_post_action 10:34:00 INFO - ##### 10:34:00 INFO - ##### Running create-virtualenv step. 10:34:00 INFO - ##### 10:34:00 INFO - Running pre-action listener: _resource_record_pre_action 10:34:00 INFO - Running main action method: create_virtualenv 10:34:00 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:34:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 10:34:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:34:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:34:00 INFO - Using real prefix '/usr' 10:34:00 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:34:01 INFO - Installing distribute.............................................................................................................................................................................................done. 10:34:03 INFO - Installing pip.................done. 10:34:03 INFO - Return code: 0 10:34:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:34:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:34:03 INFO - Using env: {'DISPLAY': ':0', 10:34:03 INFO - 'HOME': '/home/cltbld', 10:34:03 INFO - 'LANG': 'en_US.UTF-8', 10:34:03 INFO - 'LANGUAGE': 'en_US:en', 10:34:03 INFO - 'LOGNAME': 'cltbld', 10:34:03 INFO - 'MAIL': '/var/mail/cltbld', 10:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:03 INFO - 'MOZ_NO_REMOTE': '1', 10:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:03 INFO - 'NO_EM_RESTART': '1', 10:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:03 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:03 INFO - 'SHELL': '/bin/bash', 10:34:03 INFO - 'SHLVL': '1', 10:34:03 INFO - 'TERM': 'linux', 10:34:03 INFO - 'TMOUT': '86400', 10:34:03 INFO - 'USER': 'cltbld', 10:34:03 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:03 INFO - '_': '/tools/buildbot/bin/python'} 10:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:34:03 INFO - Cleaning up... 10:34:03 INFO - Return code: 0 10:34:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 10:34:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:34:03 INFO - Using env: {'DISPLAY': ':0', 10:34:03 INFO - 'HOME': '/home/cltbld', 10:34:03 INFO - 'LANG': 'en_US.UTF-8', 10:34:03 INFO - 'LANGUAGE': 'en_US:en', 10:34:03 INFO - 'LOGNAME': 'cltbld', 10:34:03 INFO - 'MAIL': '/var/mail/cltbld', 10:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:03 INFO - 'MOZ_NO_REMOTE': '1', 10:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:03 INFO - 'NO_EM_RESTART': '1', 10:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:03 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:03 INFO - 'SHELL': '/bin/bash', 10:34:03 INFO - 'SHLVL': '1', 10:34:03 INFO - 'TERM': 'linux', 10:34:03 INFO - 'TMOUT': '86400', 10:34:03 INFO - 'USER': 'cltbld', 10:34:03 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:03 INFO - '_': '/tools/buildbot/bin/python'} 10:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:03 INFO - Downloading/unpacking psutil>=0.7.1 10:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:34:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:34:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:34:05 INFO - Installing collected packages: psutil 10:34:05 INFO - Running setup.py install for psutil 10:34:05 INFO - building 'psutil._psutil_linux' extension 10:34:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:34:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:34:05 INFO - building 'psutil._psutil_posix' extension 10:34:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:34:06 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 10:34:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:34:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:34:06 INFO - Successfully installed psutil 10:34:06 INFO - Cleaning up... 10:34:06 INFO - Return code: 0 10:34:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:34:06 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')]} 10:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:06 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')]} 10:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:34:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:34:06 INFO - Using env: {'DISPLAY': ':0', 10:34:06 INFO - 'HOME': '/home/cltbld', 10:34:06 INFO - 'LANG': 'en_US.UTF-8', 10:34:06 INFO - 'LANGUAGE': 'en_US:en', 10:34:06 INFO - 'LOGNAME': 'cltbld', 10:34:06 INFO - 'MAIL': '/var/mail/cltbld', 10:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:06 INFO - 'MOZ_NO_REMOTE': '1', 10:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:06 INFO - 'NO_EM_RESTART': '1', 10:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:06 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:06 INFO - 'SHELL': '/bin/bash', 10:34:06 INFO - 'SHLVL': '1', 10:34:06 INFO - 'TERM': 'linux', 10:34:06 INFO - 'TMOUT': '86400', 10:34:06 INFO - 'USER': 'cltbld', 10:34:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:06 INFO - '_': '/tools/buildbot/bin/python'} 10:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:34:06 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 10:34:06 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 10:34:06 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 10:34:06 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 10:34:06 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 10:34:06 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 10:34:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:34:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:34:08 INFO - Installing collected packages: mozsystemmonitor 10:34:08 INFO - Running setup.py install for mozsystemmonitor 10:34:08 INFO - Successfully installed mozsystemmonitor 10:34:08 INFO - Cleaning up... 10:34:08 INFO - Return code: 0 10:34:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:34:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 10:34:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:34:08 INFO - Using env: {'DISPLAY': ':0', 10:34:08 INFO - 'HOME': '/home/cltbld', 10:34:08 INFO - 'LANG': 'en_US.UTF-8', 10:34:08 INFO - 'LANGUAGE': 'en_US:en', 10:34:08 INFO - 'LOGNAME': 'cltbld', 10:34:08 INFO - 'MAIL': '/var/mail/cltbld', 10:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:08 INFO - 'MOZ_NO_REMOTE': '1', 10:34:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:08 INFO - 'NO_EM_RESTART': '1', 10:34:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:08 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:08 INFO - 'SHELL': '/bin/bash', 10:34:08 INFO - 'SHLVL': '1', 10:34:08 INFO - 'TERM': 'linux', 10:34:08 INFO - 'TMOUT': '86400', 10:34:08 INFO - 'USER': 'cltbld', 10:34:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:08 INFO - '_': '/tools/buildbot/bin/python'} 10:34:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:10 INFO - Downloading blobuploader-1.2.4.tar.gz 10:34:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:34:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:34:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:11 INFO - Downloading docopt-0.6.1.tar.gz 10:34:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:34:11 INFO - Installing collected packages: blobuploader, requests, docopt 10:34:11 INFO - Running setup.py install for blobuploader 10:34:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:34:11 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:34:11 INFO - Running setup.py install for requests 10:34:11 INFO - Running setup.py install for docopt 10:34:11 INFO - Successfully installed blobuploader requests docopt 10:34:11 INFO - Cleaning up... 10:34:11 INFO - Return code: 0 10:34:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:34:11 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')]} 10:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:11 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')]} 10:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:34:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:34:11 INFO - Using env: {'DISPLAY': ':0', 10:34:11 INFO - 'HOME': '/home/cltbld', 10:34:11 INFO - 'LANG': 'en_US.UTF-8', 10:34:11 INFO - 'LANGUAGE': 'en_US:en', 10:34:11 INFO - 'LOGNAME': 'cltbld', 10:34:11 INFO - 'MAIL': '/var/mail/cltbld', 10:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:11 INFO - 'MOZ_NO_REMOTE': '1', 10:34:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:11 INFO - 'NO_EM_RESTART': '1', 10:34:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:11 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:11 INFO - 'SHELL': '/bin/bash', 10:34:11 INFO - 'SHLVL': '1', 10:34:11 INFO - 'TERM': 'linux', 10:34:11 INFO - 'TMOUT': '86400', 10:34:11 INFO - 'USER': 'cltbld', 10:34:11 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:11 INFO - '_': '/tools/buildbot/bin/python'} 10:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:34:11 INFO - Running setup.py (path:/tmp/pip-MmzScE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:34:12 INFO - Running setup.py (path:/tmp/pip-qW4fO7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:34:12 INFO - Running setup.py (path:/tmp/pip-Pvfyn0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:34:12 INFO - Running setup.py (path:/tmp/pip-DGC7Nh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:34:12 INFO - Running setup.py (path:/tmp/pip-llj4mz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:34:12 INFO - Running setup.py (path:/tmp/pip-p8lck0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:34:12 INFO - Running setup.py (path:/tmp/pip-DP_F7j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:34:12 INFO - Running setup.py (path:/tmp/pip-vlZ7Jk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:34:12 INFO - Running setup.py (path:/tmp/pip-TWn0qN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:34:12 INFO - Running setup.py (path:/tmp/pip-zJHPGW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:34:12 INFO - Running setup.py (path:/tmp/pip-QLwdum-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:34:12 INFO - Running setup.py (path:/tmp/pip-h18TYu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:34:12 INFO - Running setup.py (path:/tmp/pip-sHvRFt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:34:12 INFO - Running setup.py (path:/tmp/pip-9krEaI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:34:13 INFO - Running setup.py (path:/tmp/pip-ErjHFi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:34:13 INFO - Running setup.py (path:/tmp/pip-yZjvge-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:34:13 INFO - Running setup.py (path:/tmp/pip-B1a1Gp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:34:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:34:13 INFO - Running setup.py install for manifestparser 10:34:13 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:34:13 INFO - Running setup.py install for mozcrash 10:34:13 INFO - Running setup.py install for mozdebug 10:34:13 INFO - Running setup.py install for mozdevice 10:34:13 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:34:13 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:34:13 INFO - Running setup.py install for mozfile 10:34:13 INFO - Running setup.py install for mozhttpd 10:34:13 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:34:13 INFO - Running setup.py install for mozinfo 10:34:13 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:34:13 INFO - Running setup.py install for mozInstall 10:34:14 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Running setup.py install for mozleak 10:34:14 INFO - Running setup.py install for mozlog 10:34:14 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Running setup.py install for moznetwork 10:34:14 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Running setup.py install for mozprocess 10:34:14 INFO - Running setup.py install for mozprofile 10:34:14 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Running setup.py install for mozrunner 10:34:14 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Running setup.py install for mozscreenshot 10:34:14 INFO - Running setup.py install for moztest 10:34:14 INFO - Running setup.py install for mozversion 10:34:14 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:34:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:34:14 INFO - Cleaning up... 10:34:14 INFO - Return code: 0 10:34:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:34:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:34:14 INFO - Using env: {'DISPLAY': ':0', 10:34:14 INFO - 'HOME': '/home/cltbld', 10:34:14 INFO - 'LANG': 'en_US.UTF-8', 10:34:14 INFO - 'LANGUAGE': 'en_US:en', 10:34:14 INFO - 'LOGNAME': 'cltbld', 10:34:14 INFO - 'MAIL': '/var/mail/cltbld', 10:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:14 INFO - 'MOZ_NO_REMOTE': '1', 10:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:14 INFO - 'NO_EM_RESTART': '1', 10:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:14 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:14 INFO - 'SHELL': '/bin/bash', 10:34:14 INFO - 'SHLVL': '1', 10:34:14 INFO - 'TERM': 'linux', 10:34:14 INFO - 'TMOUT': '86400', 10:34:14 INFO - 'USER': 'cltbld', 10:34:14 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:14 INFO - '_': '/tools/buildbot/bin/python'} 10:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:34:15 INFO - Running setup.py (path:/tmp/pip-0lL1cR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:34:15 INFO - Running setup.py (path:/tmp/pip-wFmmG2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:34:15 INFO - Running setup.py (path:/tmp/pip-k3kL_u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:34:15 INFO - Running setup.py (path:/tmp/pip-9zIwFV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:34:15 INFO - Running setup.py (path:/tmp/pip-gxIR04-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:34:15 INFO - Running setup.py (path:/tmp/pip-rkQvqx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:34:15 INFO - Running setup.py (path:/tmp/pip-sWX6Yo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:34:15 INFO - Running setup.py (path:/tmp/pip-TKcPht-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:34:15 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)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:34:15 INFO - Running setup.py (path:/tmp/pip-yoItrt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:34:15 INFO - Running setup.py (path:/tmp/pip-zhaWVS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:34:15 INFO - Running setup.py (path:/tmp/pip-B8Rkay-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:34:16 INFO - Running setup.py (path:/tmp/pip-TD_MO_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:34:16 INFO - Running setup.py (path:/tmp/pip-YFc2oY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:34:16 INFO - Running setup.py (path:/tmp/pip-zKkV9b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:34:16 INFO - Running setup.py (path:/tmp/pip-TXIk7o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:34:16 INFO - Running setup.py (path:/tmp/pip-Uozl5r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:34:16 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)) 10:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:34:16 INFO - Running setup.py (path:/tmp/pip-Uhd1T8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:34:16 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)) 10:34:16 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)) 10:34:16 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)) 10:34:16 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)) 10:34:16 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)) 10:34:16 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)) 10:34:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:18 INFO - Downloading blessings-1.6.tar.gz 10:34:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:34:18 INFO - Installing collected packages: blessings 10:34:18 INFO - Running setup.py install for blessings 10:34:18 INFO - Successfully installed blessings 10:34:18 INFO - Cleaning up... 10:34:18 INFO - Return code: 0 10:34:18 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:34:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:34:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:34:18 INFO - Reading from file tmpfile_stdout 10:34:18 INFO - Current package versions: 10:34:18 INFO - argparse == 1.2.1 10:34:18 INFO - blessings == 1.6 10:34:18 INFO - blobuploader == 1.2.4 10:34:18 INFO - docopt == 0.6.1 10:34:18 INFO - manifestparser == 1.1 10:34:18 INFO - mozInstall == 1.12 10:34:18 INFO - mozcrash == 0.16 10:34:18 INFO - mozdebug == 0.1 10:34:18 INFO - mozdevice == 0.47 10:34:18 INFO - mozfile == 1.2 10:34:18 INFO - mozhttpd == 0.7 10:34:18 INFO - mozinfo == 0.9 10:34:18 INFO - mozleak == 0.1 10:34:18 INFO - mozlog == 3.1 10:34:18 INFO - moznetwork == 0.27 10:34:18 INFO - mozprocess == 0.22 10:34:18 INFO - mozprofile == 0.27 10:34:18 INFO - mozrunner == 6.11 10:34:18 INFO - mozscreenshot == 0.1 10:34:18 INFO - mozsystemmonitor == 0.0 10:34:18 INFO - moztest == 0.7 10:34:18 INFO - mozversion == 1.4 10:34:18 INFO - psutil == 3.1.1 10:34:18 INFO - requests == 1.2.3 10:34:18 INFO - wsgiref == 0.1.2 10:34:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 10:34:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:34:18 INFO - Using env: {'DISPLAY': ':0', 10:34:18 INFO - 'HOME': '/home/cltbld', 10:34:18 INFO - 'LANG': 'en_US.UTF-8', 10:34:18 INFO - 'LANGUAGE': 'en_US:en', 10:34:18 INFO - 'LOGNAME': 'cltbld', 10:34:18 INFO - 'MAIL': '/var/mail/cltbld', 10:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:18 INFO - 'MOZ_NO_REMOTE': '1', 10:34:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:18 INFO - 'NO_EM_RESTART': '1', 10:34:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:18 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:18 INFO - 'SHELL': '/bin/bash', 10:34:18 INFO - 'SHLVL': '1', 10:34:18 INFO - 'TERM': 'linux', 10:34:18 INFO - 'TMOUT': '86400', 10:34:18 INFO - 'USER': 'cltbld', 10:34:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:18 INFO - '_': '/tools/buildbot/bin/python'} 10:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:34:18 INFO - Cleaning up... 10:34:18 INFO - Return code: 0 10:34:18 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:34:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:34:18 INFO - Using env: {'DISPLAY': ':0', 10:34:18 INFO - 'HOME': '/home/cltbld', 10:34:18 INFO - 'LANG': 'en_US.UTF-8', 10:34:18 INFO - 'LANGUAGE': 'en_US:en', 10:34:18 INFO - 'LOGNAME': 'cltbld', 10:34:18 INFO - 'MAIL': '/var/mail/cltbld', 10:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:18 INFO - 'MOZ_NO_REMOTE': '1', 10:34:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:18 INFO - 'NO_EM_RESTART': '1', 10:34:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:18 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:18 INFO - 'SHELL': '/bin/bash', 10:34:18 INFO - 'SHLVL': '1', 10:34:18 INFO - 'TERM': 'linux', 10:34:18 INFO - 'TMOUT': '86400', 10:34:18 INFO - 'USER': 'cltbld', 10:34:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:18 INFO - '_': '/tools/buildbot/bin/python'} 10:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:19 INFO - Downloading/unpacking jsonschema 10:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:34:20 INFO - Downloading/unpacking functools32 (from jsonschema) 10:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:34:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:34:21 INFO - warning: no files found matching '*.txt' 10:34:21 INFO - no previously-included directories found matching 'build' 10:34:21 INFO - no previously-included directories found matching 'dist' 10:34:21 INFO - no previously-included directories found matching '.git*' 10:34:21 INFO - Installing collected packages: jsonschema, functools32 10:34:21 INFO - Running setup.py install for functools32 10:34:21 INFO - warning: no files found matching '*.txt' 10:34:21 INFO - no previously-included directories found matching 'build' 10:34:21 INFO - no previously-included directories found matching 'dist' 10:34:21 INFO - no previously-included directories found matching '.git*' 10:34:21 INFO - Successfully installed jsonschema functools32 10:34:21 INFO - Cleaning up... 10:34:21 INFO - Return code: 0 10:34:21 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:34: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')]} 10:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc23f93ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', '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 10:34:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:34:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:34:21 INFO - Using env: {'DISPLAY': ':0', 10:34:21 INFO - 'HOME': '/home/cltbld', 10:34:21 INFO - 'LANG': 'en_US.UTF-8', 10:34:21 INFO - 'LANGUAGE': 'en_US:en', 10:34:21 INFO - 'LOGNAME': 'cltbld', 10:34:21 INFO - 'MAIL': '/var/mail/cltbld', 10:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:21 INFO - 'MOZ_NO_REMOTE': '1', 10:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:21 INFO - 'NO_EM_RESTART': '1', 10:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:21 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:21 INFO - 'SHELL': '/bin/bash', 10:34:21 INFO - 'SHLVL': '1', 10:34:21 INFO - 'TERM': 'linux', 10:34:21 INFO - 'TMOUT': '86400', 10:34:21 INFO - 'USER': 'cltbld', 10:34:21 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:21 INFO - '_': '/tools/buildbot/bin/python'} 10:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:21 INFO - Downloading/unpacking flake8 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:34:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:34:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34: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 10:34:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:34:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:34:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:34:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:34:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:34:24 INFO - no previously-included directories found matching 'docs/_build' 10:34:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:34:24 INFO - Running setup.py install for pep8 10:34:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:34:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:34:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:34:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:34:24 INFO - no previously-included directories found matching 'docs/_build' 10:34:24 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:34:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:34:24 INFO - Cleaning up... 10:34:24 INFO - Return code: 0 10:34:24 INFO - Running post-action listener: _resource_record_post_action 10:34:24 INFO - Running post-action listener: _start_resource_monitoring 10:34:24 INFO - Starting resource monitoring. 10:34:24 INFO - ##### 10:34:24 INFO - ##### Running install step. 10:34:24 INFO - ##### 10:34:24 INFO - Running pre-action listener: _resource_record_pre_action 10:34:24 INFO - Running main action method: install 10:34:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:34:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:34:24 INFO - Reading from file tmpfile_stdout 10:34:24 INFO - Detecting whether we're running mozinstall >=1.0... 10:34:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:34:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:34:24 INFO - Reading from file tmpfile_stdout 10:34:24 INFO - Output received: 10:34:24 INFO - Usage: mozinstall [options] installer 10:34:24 INFO - Options: 10:34:24 INFO - -h, --help show this help message and exit 10:34:24 INFO - -d DEST, --destination=DEST 10:34:24 INFO - Directory to install application into. [default: 10:34:24 INFO - "/builds/slave/test-pgo"] 10:34:24 INFO - --app=APP Application being installed. [default: firefox] 10:34:24 INFO - mkdir: /builds/slave/test-pgo/build/application 10:34:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:34:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:34:39 INFO - Reading from file tmpfile_stdout 10:34:39 INFO - Output received: 10:34:39 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:34:39 INFO - Running post-action listener: _resource_record_post_action 10:34:39 INFO - ##### 10:34:39 INFO - ##### Running run-tests step. 10:34:39 INFO - ##### 10:34:39 INFO - Running pre-action listener: _resource_record_pre_action 10:34:39 INFO - Running main action method: run_tests 10:34:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:34:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:34:39 INFO - Python 2.7.3 10:34:39 INFO - Return code: 0 10:34:39 INFO - grabbing minidump binary from tooltool 10:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:34:39 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 0x2035350>, '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 0x2036250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20366e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:34:39 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 10:34:39 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 10:34:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:34:39 INFO - Return code: 0 10:34:39 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:34:39 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:34:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:34:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:34:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:34:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:34:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:34:40 INFO - Return code: 0 10:34:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:34:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost 10:34:40 INFO - Using env: {'DISPLAY': ':0', 10:34:40 INFO - 'HOME': '/home/cltbld', 10:34:40 INFO - 'LANG': 'en_US.UTF-8', 10:34:40 INFO - 'LANGUAGE': 'en_US:en', 10:34:40 INFO - 'LOGNAME': 'cltbld', 10:34:40 INFO - 'MAIL': '/var/mail/cltbld', 10:34:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:34:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:40 INFO - 'MOZ_NO_REMOTE': '1', 10:34:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:34:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:34:40 INFO - 'NO_EM_RESTART': '1', 10:34:40 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:34:40 INFO - 'PWD': '/builds/slave/test-pgo', 10:34:40 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:34:40 INFO - 'SHELL': '/bin/bash', 10:34:40 INFO - 'SHLVL': '1', 10:34:40 INFO - 'TERM': 'linux', 10:34:40 INFO - 'TMOUT': '86400', 10:34:40 INFO - 'USER': 'cltbld', 10:34:40 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817', 10:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:40 INFO - '_': '/tools/buildbot/bin/python'} 10:34:40 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost'] with output_timeout 3600 10:34:40 INFO - mozversion INFO | application_buildid: 20160223081052 10:34:40 INFO - mozversion INFO | application_changeset: 30d48874cea4923cfe0e34c466894e353ef48aa2 10:34:40 INFO - mozversion INFO | application_display_name: Nightly 10:34:40 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:34:40 INFO - mozversion INFO | application_name: Firefox 10:34:40 INFO - mozversion INFO | application_remotingname: firefox 10:34:40 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:34:40 INFO - mozversion INFO | application_vendor: Mozilla 10:34:40 INFO - mozversion INFO | application_version: 45.0 10:34:40 INFO - mozversion INFO | platform_buildid: 20160223081052 10:34:40 INFO - mozversion INFO | platform_changeset: 30d48874cea4923cfe0e34c466894e353ef48aa2 10:34:40 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:34:40 INFO - mozversion INFO | platform_version: 45.0 10:34:40 INFO - 2016-02-23 10:34:40,512 DEBUG : using testdate: 1456252480 10:34:40 INFO - 2016-02-23 10:34:40,512 DEBUG : actual date: 1456252480 10:34:40 INFO - 2016-02-23 10:34:40,520 INFO : starting webserver on 'localhost:37296' 10:34:40 INFO - 2016-02-23 10:34:40,521 INFO : Starting test suite talos-linux64-ix-002 10:34:40 INFO - 2016-02-23 10:34:40,522 INFO : Starting test dromaeo_css 10:34:40 INFO - 2016-02-23 10:34:40,522 DEBUG : operating with platform_type : linux_ 10:34:40 INFO - 2016-02-23 10:34:40,522 INFO : Initialising browser for dromaeo_css test... 10:34:40 INFO - 2016-02-23 10:34:40,537 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRli8MU/profile http://localhost:37296/getInfo.html 10:34:44 INFO - 2016-02-23 10:34:44,602 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:34:44 INFO - 2016-02-23 10:34:44,602 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:34:44 INFO - 2016-02-23 10:34:44,602 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:34:44 INFO - 2016-02-23 10:34:44,602 DEBUG : BROWSER_OUTPUT: __metrics 10:34:44 INFO - 2016-02-23 10:34:44,759 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:34:45 INFO - 2016-02-23 10:34:45,695 INFO : Browser initialized. 10:34:45 INFO - 2016-02-23 10:34:45,696 INFO : Running cycle 1/1 for dromaeo_css test... 10:34:45 INFO - 2016-02-23 10:34:45,697 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRli8MU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:34:57 INFO - 2016-02-23 10:34:57,422 DEBUG : BROWSER_OUTPUT: RSS: Main: 132521984 10:34:57 INFO - 2016-02-23 10:34:57,423 DEBUG : BROWSER_OUTPUT: 10:35:48 INFO - 2016-02-23 10:35:48,192 DEBUG : BROWSER_OUTPUT: 1456252548191 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] 10:37:56 INFO - 2016-02-23 10:37:56,631 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37296/tests/dromaeo/cssquery-ext.html) 10:37:57 INFO - 2016-02-23 10:37:57,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 250404864 10:37:57 INFO - 2016-02-23 10:37:57,841 DEBUG : BROWSER_OUTPUT: 10:40:59 INFO - 2016-02-23 10:40:59,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-ext.html (next: http://localhost:37296/tests/dromaeo/cssquery-jquery.html) 10:41:00 INFO - 2016-02-23 10:41:00,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 210235392 10:41:00 INFO - 2016-02-23 10:41:00,534 DEBUG : BROWSER_OUTPUT: 10:44:02 INFO - 2016-02-23 10:44:02,364 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37296/tests/dromaeo/cssquery-mootools.html) 10:44:02 INFO - 2016-02-23 10:44:02,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 206241792 10:44:02 INFO - 2016-02-23 10:44:02,931 DEBUG : BROWSER_OUTPUT: 10:47:04 INFO - 2016-02-23 10:47:04,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37296/tests/dromaeo/cssquery-prototype.html) 10:47:05 INFO - 2016-02-23 10:47:05,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 197320704 10:47:05 INFO - 2016-02-23 10:47:05,186 DEBUG : BROWSER_OUTPUT: 10:50:07 INFO - 2016-02-23 10:50:07,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37296/tests/dromaeo/cssquery-yui.html) 10:50:08 INFO - 2016-02-23 10:50:08,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 200966144 10:50:08 INFO - 2016-02-23 10:50:08,095 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,313 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/dromaeo/cssquery-yui.html (next: http://localhost:37296/tests/dromaeo/cssquery-dojo.html) 10:53:10 INFO - 2016-02-23 10:53:10,571 DEBUG : BROWSER_OUTPUT: RSS: Main: 241688576 10:53:10 INFO - 2016-02-23 10:53:10,571 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3288.490284005979;3353.463445848665;3358.462645976644;3372.2749608834097;3380.66193380662;5530;5563.499285064348;5572.359178694452;5580.91628625571;5616.943830561693;5566.4990150886415;5593.049181639121;5606.719664016803;5607.243022192003;5625.52183064439;4953.900921981576;5042.168042273021;5098.362704661908;5112.182050871861;5172.534471897528;5022.171341728628;5069.239614057902;5074.2895994560795;5081.949180508184;5084.262781896623;5025.597952163836;5047.570956468705;5077.441481437039;5080.669756465836;5171.301874246966;5081.872953176182;5182.352205974254;5185.507376799217;5189.532942035197;5191.714455704935;5123.333966584358;5153.845384638467;5269.86825329366;5276.92084618731;5304.787808487695;5253.6322457428;5282.550983166436;5355.1699486579255;5419.5393391561765;5520.171974203861;5964.493018093467;6023.668698221596;6023.668698221596;6030.909536356959;6032.125341825411;5966.761329546813;6041.033434650435;6045.244344456943;6048.0020796568515;6048.274207095133;5396.811111611075;5490.478404551562;5627.324721033461;5720.885582288414;5770.596058275881;6185.783497577563;6187.566870319034;6191.597546159485;6196.47329976948;6197.101420294032;5693.3168019837885;6006.459418652343;6011.308699499614;6038.33578306386;6038.547108966845;5890.764369425175;5980;6062.333143354227;6063.636181829016;6090.725917333731;5997.310309314485;6022.307434645072;6036.215292012098;6037.1246169305205;6047.15339852421;5652.689102099424;5694.088945768657;6016.217891674054;6019.067044608093;6023.879522409527;4453.888652783707;4529.569690879362;4535.841245556389;4545.522720114406;4573.6569757268335;4114.567970363129;4132.5454200037975;4139.399787030924;4153.480814898137;4172.061286210639;5343.198520221998;5754.568407369464;5768.451997060272;5778.3643799182055;5779.537636989031;5905.822825315161;5911.645301281937;5983.760649573968;5993.550483713739;6020.187274717945;5759.0209664356325;5791.681457519792;5795.652260864277;5798.217240672539;5801.100829371454;4977.228529577921;5070.112730272188;5241;5285.524302812765;5291.232771248147;6060.878782424327;6122.39815511198;6131.080337949344;6133.355997620275;6141.385861413913;6089.847753806013;6118.388161184024;6119.4798442132915;6120.1125836752535;6121.755129794937;5915.497182739516;6029.969850150721;6174.748976760268;6280.7487700491465;6282.654454005074;5969.492593129459;6084.33072362031;6089.5737298388685;6109.84725381869;6117.663528505975;6083.66539840313;6116.204893363834;6138.386161383826;6145.600535965327;6148.4773794227995;5691.317041954826;5998.25021872266;6000.729967151401;6001;6007.098935159586;5693.259876216065;5874.676892770768;5983.910241346471;5988.820335389945;6011.458968692838;5800.59395842287;5805.738741756547;5811.767529298781;5813.128030795415;5815.912761308668;5661.320641523043;5768.653880767168;5915.112733089899;5915.142304365929;5916.437938395846;5736.110902810071;5812;5814.709264536841;5823.621464604787;5852.122181672613 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: |1;ext.html;574.7787101965741;618.7617767159643;622.1290193728779;627.203451616447;637.4517914593451;32245.51631725523;42863;42907.14185716284;43012.13975720484;43102.7844860776;46949;47331.526684733144;47365;47427.28859067117;47595;7781.455298129134;7891.565963871984;7895.210478952116;9110.954445227764;9287.767805804875;10882.183836212276;11014.44927753613;11021.724456888562;11060.281081729701;11067.72330691731;10997.285176463503;11170.609028683997;11198;11199.048080913131;11203;11047.502862371173;11148.331100134017;11162.330260184415;11164.776704465947;11190.38452885091;10743.785124297548;10909.890901090967;10912.399818010006;10914.890851091466;10916.181286403473;42610.360844587354;42957.785211073744;43019.354709679064;43019.78490107561;43078;3427.8114703691285;3480.868717666743;3571.357155711971;3664.1572438339063;3961.58403367648;31044.844775776204;31677;32111.51832722511;34551;36055.81972090149;4293.957060429387;4359.782010899474;4363.738175709467;4368.148211098837;4371.956280437218;316906;319272;319365;320055;320259;60058.69970650206;67629.3237067623;67920;67993.3200667987;68032;53531.46468535343;53740.73129634327;79242;79562;79674;18542.629147417247;18638.720419193724;18672.253109875724;18821.529461763566;18836.529086772665;14394.784078238834;14472.348744306531;14551.636209094857;14581.85418145805;14595.197264150574;21930.574512656625;22283.22008729719;22305.665415018786;22312.21907233239;22369.217077402212;7973.72091976778;8029.7189598363775;8095.635696393676;8151.103378628346;8322.250997410141;968.6028728221395;1096.4572536594437;1215.769003889258;1227.34337129637;1236.7526494701096;29808.701912981025;29971.70028299689;30611;30791.692083078884;30798.384032319675;1114.9318015082067;1137.4142316706902;1172.6013155527162;1236.6970092327258;1240.6588188248304;14322.498712545012;15731.842681573267;16284.43004494837;17029.574260643334;18469.445916622524;452827;458194;458847;458961;459345;61299.08051379233;61353.38646613566;61725;61913;62009.68995154995;1442.898997070195;1443.7150935667264;1451.506487794134;1458.0522660270908;1483.9258037098102;31899.84050079735;32039.839800800848;32244.516332255505;33890.66109338875;35235.29529409449;55596.220188991196;56703.71648141733;56717.43282567122;56738.14892776694;56766.43233567777;330646;332666;333103;333933;334835;39569.60430395659;40280.59719402886;46369.304460432446;49766;52324;47208;47279;48249.75875120602;48725.26912096392;48872.51127488822;39864.402033970095;40477.190456190125;40760.79619601883;41269.793651032756;41274.58725412707;38604.42093368658;40312;40380;41010.79494602508;44757.55242447534;39475.80262098671;39642.80178599089;39712.80143599264;40291.395629065;40344.1931161381;34894.476582851785;35373.64626353703;35491.64508354987;36348.45477317895;36382.454263186606;27917.441651167268;28462.715372846837;30866.84566577153;31163.688363116078;31177.37645247128 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.4846454215895;387.72471545202893;391.2644228849762;392.21361170852435;393.990150246244;18247.635047299045;19678.60642787147;20023.1990720371;20051.398458046246;20114.798852011474;18384.08079596018;18477.907610461963;18656.347027854026;18896.811031889676;18903.716444253314;5499.450054994499;5616.494515493605;5629.662220266797;5635.8591035224035;5638.915416268739;5497.340319161706;5576.470235327658;5582.7766889324375;5588.105903055512;5610.495055445009;4076.062505623708;4173.290540608104;5535.280413546233;5540.889182216353;5543.750531226105;5843;5956.821295361146;6008.279006519202;6018.939810601903;6020.548458865603;5271.578273738091;5330.253764472977;5346.518813306782;5364.249005139283;5366.4633536646415;12760.6809829755;16847.241874115694;18861.811381886142;20753.5849283015;21267.46831329214;2213.1700612270397;2275.9317220483244;2322.372959300996;2346.9530609387716;2631;9816.361936474177;10066.496675166212;10263.43551104689;12309.630711078682;13182.934085329607;1919.2323070771663;1936.0997136331532;1958.4712127725434;1995.102204008202;2023.514356554431;63682.68158659223;66725.33274667288;67358;67524.49392510756;67554;11218.326900386002;11237.550497980154;11261.943690281496;11291.153163512605;11298.774024519627;14969.101853888802;14984.550463486114;15064.623384415258;15076.472323468626;15136;8627.180417860418;8652.783680407914;8730.21428071464;8784.472931624123;8944.150305720947;8362.749117526484;8371.539565323845;8410.663573457115;8464.365172612079;8466.068732439428;4467.5755803198645;4470.843520476833;4622.422197225347;4722.244440889442;4903.190973489334;1875.367255271061;1919.7408349872867;1937.1282922684848;1944.5819148883058;1966.3019628031843;1055.7096798380508;1075.9515821788038;1086.750047489082;1104.6078642082107;1125.994370028161;15300.464483743015;17494.825051749573;17594.472165835046;17938.91030544839;18181.00004499739;985.443000059918;999.4652860719481;1007.9193664506822;1027.3476342522551;1042.9843552346722;10185.847212291821;10265.281430299956;10322.948385258176;10362.326448780956;10798.406087665255;452670;457319;510846;514083;518028;82459.17540824634;88593;88911;89864;89954.55022724844;1159.5535718748342;1205.2828567002618;1217.8843009914044;1224.0819385460904;1226.8773122687837;15896.920515397349;15923.44267950616;15929.522114336589;16025.038497690175;16335.101569413797;37566.624333756314;37729.81135094307;38309;38392;39418.802905986435;50626;51289.48710512996;52760.73619631877;53705.46294537161;54018.35081741733;10188.235882308709;10293.330933489298;10420.166386688896;10421.114205292635;10456.843147352947;12094.879051209375;18836.434906952814;18886.3389781357;18933;19272.32546860853;15428.614284642974;15553.066815991077;15893.761593575875;15936.601584960468;15940.123293218985;15160.862935279678;15172.165530895465;15364;15729.921350393175;15997.520074397788;15473.381064757283;15505.767413489033;15881.285342159399;16041.919790400972;16085.195740213177;9990.350627209207;10441.843372349269;10572.735681608021;10637.893621063691;10666.83999739989;10894.583704118413;10971.561137554409;13683.384247708678;15816.288267027781;16139 10:53:10 INFO - 2016-02-23 10:53:10,572 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1465.4284828916732;1764.9558761030974;1810.9728354074687;1823.68997270464;1846.4737549798301;6959.304069593039;7144.321289477504;7506.4370172237095;8090.43366964312;8093.595320233987;7434.702611895531;7540.62296885155;7642.923570764291;7656.042994625672;7657.27255910689;2330.4290448840015;2364.4561750797325;2370.9525809483885;2371.1937941100023;2371.27676058802;2028.2191356327774;2073.0774805211686;2314.1784666443423;2347.6243800991847;2374.323317854412;2292.9541409171807;2326.4067662745974;2337.1586228957563;2339.94150146246;2340.918067867625;2355.1286024170995;2384.1059602649007;2385.86877721727;2387.116766796279;2387.9761202387926;2364.4679947011955;2406.6630671705975;2406.9037238510437;2408.0728919365997;2408.241403957765;7828.804279892991;7883.251091146332;7886.72396466121;7889.8816517752275;7890.684372625088;4747.8575642730775;4855.417349918032;4857.052874689437;4858.174110401205;4861.854144375674;3110.529745000229;3204.6795320468;3207.134073800084;3212.8714851405916;3229.0958531611186;1090.0462095666292;1114.3035602748282;1121.3552207480732;1150.1316506037972;1991.5021244688828;10767.83848242277;10918.890811091867;10925.672229833117;10930.781384372347;10956.726081848017;4094.324436468009;4113.03343714227;4136.98643832263;4173.83304667816;4178.958210417918;8844.115588441104;8861.778455538662;8878.644854205888;8886.644534218689;8896.59965301563;4755.191617424977;4757.26262429324;4763;4768.713877167381;4769.66612337133;4325.97837010813;4407.206702793528;4410.625096866738;4415.359772832985;4468.910621787546;3249.3014001989686;3614.927701446009;3621.6197299283194;3649.5073165122367;3654.3239500691925;988.5996171550534;1097.9121670266359;1097.9725506862392;1106.8948449897248;1112.488255402524;1162.4245998230929;1168.0363699947577;1216.6167657187957;1438.4246301479284;1457.176695167227;7317.963410182915;7410.11078670563;7576.28025337592;7597.582132982739;7603;625.8341539492038;629.2889035390072;631.8388810853295;668.0813880913744;807.1645846548911;6090.3605121461605;6148.200733904564;6150.7232174552255;6151.876962460726;6159.784407545714;9241.815163696689;9306.53467326631;9485.762855928657;9521.285903557122;9557.378770379903;9422.575984080733;9518.095780900803;9519.524023798782;9520.714378568655;9548.761280967892;7352.750032494489;7422.035135432359;7458.067741532309;7472.9252707473315;7482.5884576347735;7298.197198308288;7316.707331706885;7334.706611735471;7343.192248852515;7370.078740157481;8330.583470826314;8392.622331995197;8458;8563.100874408097;8570.657173712982;8662.04717481064;8665.220130188314;8773.473591584525;8773.517456539952;8781;6589.374009469093;6973.546719463218;6988.440924726111;6990.685419156252;6992.580445173306;6778.288279730755;7635.961820190863;7690.192529784292;7699.961500192463;7701.037370328709;6462.4183823456115;6852.657367131723;7035.826724584537;7326.9267307326245;7439.218882017527;7135.643217838983;7174.246704095995;7254.854902902018;7268.563886166636;7296.379807716404;7251.456140789356;7269.709211631475;7322.816929576803;7324.523905946096;7331.41348692114;6735.595864248161;6773.62745049027;6783.559068660521;6788.389044985975;6788.66056697173;6804.081449004321;6852.211995620568;6882.655867206719;6894.827629309308;6917.481188910952 10:53:10 INFO - 2016-02-23 10:53:10,575 DEBUG : BROWSER_OUTPUT: |4;prototype.html;359.8920323902829;367.67277123360196;374.457037295921;374.52814381880734;383.9827207775648;6092.908606370903;6151.384861513852;6156.876862462759;6175.969120154393;6177.660228687426;6076.787312444066;6083.878322433548;6100.847478813032;6106.450419462248;6109.633421994672;4770.522947705236;4771.451283102453;4774.7135171889795;4775.570198682118;4776.713397196162;4585.426821647294;4682.08699303636;4684.203685373494;4774.1645212087915;4778.569928706415;4654.045920586289;4660.46404663463;4661.347411362412;4675.555822196886;4676.906461870761;4497;4668.393108895856;4675.2052151134385;4681.46163191234;4691.7654117294;4029.073313137965;4047.332190188615;4124.484439445069;4130.855420060314;4155.688323375729;5768.855778605526;5775.971120144372;5787.797427090074;5795.884082318372;5801.50687191585;5164.8967020659375;5261;5286.233496143076;5470.316210473691;5486.725663716798;6410.487161027107;6413.679316034226;6445.83885402864;6447.677616119222;6452.90320645186;4437.378766972626;4443.355713421536;4448.822047118111;4449.688521803475;4451.198784218839;6588.604683718992;6635.43598794103;6712.194536655584;6714.764983225564;6716.865662686768;4663.463701674283;4682.367880336179;4683.8360657376825;4686.6485013624115;4691.741954192552;4735.763211839394;4739.075880203327;4747.501512341223;4748.145333839873;4750.192467280571;5442.619016668875;5446.455354464601;5452.04589196889;5456.536194423439;5458.317710286215;6339.714712837934;6477.4170324671;6478.643674597848;6494;6512.967435162794;1655.1558705060265;1656.080875114299;1657.4696097248764;1658.1377683604458;1658.96682066358;1095.611058074372;1105.9225854190129;1114.0419239454063;1114.587602587048;1117.7652692934412;6340.968295158495;6374.553781235361;6384.07430922523;6393.424591786761;6399.168108145911;6732.4950628703045;6733.6296503692765;6741.797746067626;6742.123523941857;6744.730210791513;6296.433321001132;6350.777722779773;6378.712957916905;6381.936180638227;6389;3629.528161339009;3672.779633222015;3673.2653469306247;3674.430463278196;3677.485152078711;6985.965070174617;7005.264447233069;7006.544574602634;7010.404115650125;7020.719171233196;6888.311168883171;6944.02783610295;6960.443164546824;6964.199117101598;6985.057017302701;6982.720691172398;7014.754483593049;7015.298470152944;7017.859642807115;7019.543729657556;6793.966030169818;6794.76218332356;6820.93179068203;6822.11312529368;6827.965860170866;6553.901691474536;6678.298778628174;6705.497087718538;6707.295733947865;6713.899291510534;7000.054992575937;7006.194287656985;7009.7897063088185;7012.789616311519;7015.6842942066705;4708.693934894221;4755.5006724294835;4756.976215119041;4759.262314341283;4759.286107083826;6669.233038200669;6793.320667933365;6813.080234168324;6821.1814582250445;6825.931740682529;6597.472202223715;6607.240167380813;6620.8344791380605;6623;6624.569402988983;6601.240857301472;6605.174353205849;6613.933860661332;6616.603003819786;6618.933810662024;6360.39576240275;6476.805695829133;6506.479481641552;6507.349265073455;6509.73961041572;6624.138861947916;6639.734410623714;6640.667966601747;6646.567973081735;6655.267920528835;6463.353664633499;6473;6492.415682588589;6495.953774560998;6496.740130394731 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: |5;yui.html;904.2404380320531;911.134422298816;922.5617831530024;923.381334505881;937.5171786529943;14116.223607701573;14407.495737649184;14409.855901440995;14426.062305950132;14431.49489767857;13237.933810330935;13266.800997985038;13267.93366033171;13282.800757988638;13291.335433228323;713.8072720365499;733.8679037773198;859.6475445067524;871.4422769427572;873.1050673060132;913.6893456224882;915.2952226785371;920.6501529418812;922.1331947968921;922.9400088994194;757.727218201447;951.5860600638745;963.7831487915198;965.1844191658038;965.2760429677742;764.2853931573968;768.6733138416179;929.9535023248844;944.8960614332418;945.8912225094133;707.0878566649017;707.3739740329794;707.4199156691515;707.6815433055094;707.8407358344383;14426.92786536071;14444.494442694455;14498.05762625426;14562.854371456257;14581.635459113608;12475.563355282522;12849.100562960597;13323.400446979813;13560.602295880317;13603.047786654937;11285.322880627107;11296;11341.546338146465;11375.089992800557;11382.089432845352;3774.660280574734;3775.339315619756;3799.1072098056934;3818.5990471000423;3890;47194;47213.763931180125;49447.25829112566;49842.7507862462;49902.750486247336;10801.405922674172;10888.455577221266;10939.507722152523;11117.777644447227;11300.660980170607;19029.524261893286;19082.236710531703;19084.427467176007;19119.52201194981;19121.235150594097;8036.357091432788;8048.154943730941;8077.919220807717;8126.801152737728;8131.349492040623;4136.689748268892;4423.270160423558;4454.977725111354;4493.73037617744;4498.730076195438;3079.59965204522;3104.3946430445844;3110.4712198926236;3114.6573876873526;3125.4217969675683;546.519063224368;560.4003716023815;564.3199944067471;565.2284631478085;569.1207085053602;591.8549955260988;594.432317137133;623.9188905442354;648.1929997153574;667.6361383046251;8774.868376974351;8904.243139333099;8977;9084.31867609932;9112.271018318652;501.3657722980433;571.6541492397123;594.8126340202822;645.8837409266283;659.4658326755343;3864.439656249828;3924.3524818404644;3984.7808370539897;4044.7775372354804;4051.6556092731857;64802;64824.67587662031;65287.67356163284;65824.67087664531;65839;53704.46295537073;54774.72612636911;55468.72265638727;55478.16782748262;56091;50031.499685003415;50045.24932126021;50063.24905126426;50533.24200137001;51351.48648513467;8320.583970801557;8387.664493420194;8399.160083991552;8625.439346442461;8698.260647845003;36158.276834463686;36622;36715;36860;37376.813115934245;44651.77674111609;44930;45433.09133817239;46449.07101857876;46737.2989405166;10894.836577451186;11367.886321136682;11618.825717614412;11636.88363116358;11943.283402995849;12644.114911956074;12734.299613520974;12756.61730148097;12981.740365192833;13389.397477113358;11448.828267575826;11466.426678666201;11529.884701153216;11683.474243658886;12106.939465302617;10718.517666704862;11458.770824583295;11626.82559761621;11654.53381864716;11901.583444579399;8475.068264003394;11622.012128968794;11711.238769479956;11945.223560468541;12029.4586743595;9281.62873485053;9504.382215155993;9551.570179342085;9684.370515916444;9771.413715176828;9517.904820951702;9672.274579406432;9852.556634951301;9957.452340121363;10097 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,985 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: __startTimestamp1456253590564__endTimestamp 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,576 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5587.21 Median:5800.59 stddev:631.10 (10.9%) stddev-sans-first:606.72 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: Values: 3288.5 3353.5 3358.5 3372.3 3380.7 5530.0 5563.5 5572.4 5580.9 5616.9 5566.5 5593.0 5606.7 5607.2 5625.5 4953.9 5042.2 5098.4 5112.2 5172.5 5022.2 5069.2 5074.3 5081.9 5084.3 5025.6 5047.6 5077.4 5080.7 5171.3 5081.9 5182.4 5185.5 5189.5 5191.7 5123.3 5153.8 5269.9 5276.9 5304.8 5253.6 5282.6 5355.2 5419.5 5520.2 5964.5 6023.7 6023.7 6030.9 6032.1 5966.8 6041.0 6045.2 6048.0 6048.3 5396.8 5490.5 5627.3 5720.9 5770.6 6185.8 6187.6 6191.6 6196.5 6197.1 5693.3 6006.5 6011.3 6038.3 6038.5 5890.8 5980.0 6062.3 6063.6 6090.7 5997.3 6022.3 6036.2 6037.1 6047.2 5652.7 5694.1 6016.2 6019.1 6023.9 4453.9 4529.6 4535.8 4545.5 4573.7 4114.6 4132.5 4139.4 4153.5 4172.1 5343.2 5754.6 5768.5 5778.4 5779.5 5905.8 5911.6 5983.8 5993.6 6020.2 5759.0 5791.7 5795.7 5798.2 5801.1 4977.2 5070.1 5241.0 5285.5 5291.2 6060.9 6122.4 6131.1 6133.4 6141.4 6089.8 6118.4 6119.5 6120.1 6121.8 5915.5 6030.0 6174.7 6280.7 6282.7 5969.5 6084.3 6089.6 6109.8 6117.7 6083.7 6116.2 6138.4 6145.6 6148.5 5691.3 5998.3 6000.7 6001.0 6007.1 5693.3 5874.7 5983.9 5988.8 6011.5 5800.6 5805.7 5811.8 5813.1 5815.9 5661.3 5768.7 5915.1 5915.1 5916.4 5736.1 5812.0 5814.7 5823.6 5852.1 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56027.88 Median:36202.14 stddev:98588.46 (272.3%) stddev-sans-first:98777.11 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: Values: 574.8 618.8 622.1 627.2 637.5 32245.5 42863.0 42907.1 43012.1 43102.8 46949.0 47331.5 47365.0 47427.3 47595.0 7781.5 7891.6 7895.2 9111.0 9287.8 10882.2 11014.4 11021.7 11060.3 11067.7 10997.3 11170.6 11198.0 11199.0 11203.0 11047.5 11148.3 11162.3 11164.8 11190.4 10743.8 10909.9 10912.4 10914.9 10916.2 42610.4 42957.8 43019.4 43019.8 43078.0 3427.8 3480.9 3571.4 3664.2 3961.6 31044.8 31677.0 32111.5 34551.0 36055.8 4294.0 4359.8 4363.7 4368.1 4372.0 316906.0 319272.0 319365.0 320055.0 320259.0 60058.7 67629.3 67920.0 67993.3 68032.0 53531.5 53740.7 79242.0 79562.0 79674.0 18542.6 18638.7 18672.3 18821.5 18836.5 14394.8 14472.3 14551.6 14581.9 14595.2 21930.6 22283.2 22305.7 22312.2 22369.2 7973.7 8029.7 8095.6 8151.1 8322.3 968.6 1096.5 1215.8 1227.3 1236.8 29808.7 29971.7 30611.0 30791.7 30798.4 1114.9 1137.4 1172.6 1236.7 1240.7 14322.5 15731.8 16284.4 17029.6 18469.4 452827.0 458194.0 458847.0 458961.0 459345.0 61299.1 61353.4 61725.0 61913.0 62009.7 1442.9 1443.7 1451.5 1458.1 1483.9 31899.8 32039.8 32244.5 33890.7 35235.3 55596.2 56703.7 56717.4 56738.1 56766.4 330646.0 332666.0 333103.0 333933.0 334835.0 39569.6 40280.6 46369.3 49766.0 52324.0 47208.0 47279.0 48249.8 48725.3 48872.5 39864.4 40477.2 40760.8 41269.8 41274.6 38604.4 40312.0 40380.0 41010.8 44757.6 39475.8 39642.8 39712.8 40291.4 40344.2 34894.5 35373.6 35491.6 36348.5 36382.5 27917.4 28462.7 30866.8 31163.7 31177.4 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,577 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28654.79 Median:1951.53 stddev:80567.46 (4128.4%) stddev-sans-first:80765.51 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: Values: 387.5 387.7 391.3 392.2 394.0 18247.6 19678.6 20023.2 20051.4 20114.8 18384.1 18477.9 18656.3 18896.8 18903.7 5499.5 5616.5 5629.7 5635.9 5638.9 5497.3 5576.5 5582.8 5588.1 5610.5 4076.1 4173.3 5535.3 5540.9 5543.8 5843.0 5956.8 6008.3 6018.9 6020.5 5271.6 5330.3 5346.5 5364.2 5366.5 12760.7 16847.2 18861.8 20753.6 21267.5 2213.2 2275.9 2322.4 2347.0 2631.0 9816.4 10066.5 10263.4 12309.6 13182.9 1919.2 1936.1 1958.5 1995.1 2023.5 63682.7 66725.3 67358.0 67524.5 67554.0 11218.3 11237.6 11261.9 11291.2 11298.8 14969.1 14984.6 15064.6 15076.5 15136.0 8627.2 8652.8 8730.2 8784.5 8944.2 8362.7 8371.5 8410.7 8464.4 8466.1 4467.6 4470.8 4622.4 4722.2 4903.2 1875.4 1919.7 1937.1 1944.6 1966.3 1055.7 1076.0 1086.8 1104.6 1126.0 15300.5 17494.8 17594.5 17938.9 18181.0 985.4 999.5 1007.9 1027.3 1043.0 10185.8 10265.3 10322.9 10362.3 10798.4 452670.0 457319.0 510846.0 514083.0 518028.0 82459.2 88593.0 88911.0 89864.0 89954.6 1159.6 1205.3 1217.9 1224.1 1226.9 15896.9 15923.4 15929.5 16025.0 16335.1 37566.6 37729.8 38309.0 38392.0 39418.8 50626.0 51289.5 52760.7 53705.5 54018.4 10188.2 10293.3 10420.2 10421.1 10456.8 12094.9 18836.4 18886.3 18933.0 19272.3 15428.6 15553.1 15893.8 15936.6 15940.1 15160.9 15172.2 15364.0 15729.9 15997.5 15473.4 15505.8 15881.3 16041.9 16085.2 9990.4 10441.8 10572.7 10637.9 10666.8 10894.6 10971.6 13683.4 15816.3 16139.0 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5544.38 Median:6754.61 stddev:2851.95 (42.2%) stddev-sans-first:2843.47 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: Values: 1465.4 1765.0 1811.0 1823.7 1846.5 6959.3 7144.3 7506.4 8090.4 8093.6 7434.7 7540.6 7642.9 7656.0 7657.3 2330.4 2364.5 2371.0 2371.2 2371.3 2028.2 2073.1 2314.2 2347.6 2374.3 2293.0 2326.4 2337.2 2339.9 2340.9 2355.1 2384.1 2385.9 2387.1 2388.0 2364.5 2406.7 2406.9 2408.1 2408.2 7828.8 7883.3 7886.7 7889.9 7890.7 4747.9 4855.4 4857.1 4858.2 4861.9 3110.5 3204.7 3207.1 3212.9 3229.1 1090.0 1114.3 1121.4 1150.1 1991.5 10767.8 10918.9 10925.7 10930.8 10956.7 4094.3 4113.0 4137.0 4173.8 4179.0 8844.1 8861.8 8878.6 8886.6 8896.6 4755.2 4757.3 4763.0 4768.7 4769.7 4326.0 4407.2 4410.6 4415.4 4468.9 3249.3 3614.9 3621.6 3649.5 3654.3 988.6 1097.9 1098.0 1106.9 1112.5 1162.4 1168.0 1216.6 1438.4 1457.2 7318.0 7410.1 7576.3 7597.6 7603.0 625.8 629.3 631.8 668.1 807.2 6090.4 6148.2 6150.7 6151.9 6159.8 9241.8 9306.5 9485.8 9521.3 9557.4 9422.6 9518.1 9519.5 9520.7 9548.8 7352.8 7422.0 7458.1 7472.9 7482.6 7298.2 7316.7 7334.7 7343.2 7370.1 8330.6 8392.6 8458.0 8563.1 8570.7 8662.0 8665.2 8773.5 8773.5 8781.0 6589.4 6973.5 6988.4 6990.7 6992.6 6778.3 7636.0 7690.2 7700.0 7701.0 6462.4 6852.7 7035.8 7326.9 7439.2 7135.6 7174.2 7254.9 7268.6 7296.4 7251.5 7269.7 7322.8 7324.5 7331.4 6735.6 6773.6 6783.6 6788.4 6788.7 6804.1 6852.2 6882.7 6894.8 6917.5 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,578 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5478.67 Median:6318.07 stddev:1651.89 (26.1%) stddev-sans-first:1611.22 10:53:10 INFO - 2016-02-23 10:53:10,580 DEBUG : BROWSER_OUTPUT: Values: 359.9 367.7 374.5 374.5 384.0 6092.9 6151.4 6156.9 6176.0 6177.7 6076.8 6083.9 6100.8 6106.5 6109.6 4770.5 4771.5 4774.7 4775.6 4776.7 4585.4 4682.1 4684.2 4774.2 4778.6 4654.0 4660.5 4661.3 4675.6 4676.9 4497.0 4668.4 4675.2 4681.5 4691.8 4029.1 4047.3 4124.5 4130.9 4155.7 5768.9 5776.0 5787.8 5795.9 5801.5 5164.9 5261.0 5286.2 5470.3 5486.7 6410.5 6413.7 6445.8 6447.7 6452.9 4437.4 4443.4 4448.8 4449.7 4451.2 6588.6 6635.4 6712.2 6714.8 6716.9 4663.5 4682.4 4683.8 4686.6 4691.7 4735.8 4739.1 4747.5 4748.1 4750.2 5442.6 5446.5 5452.0 5456.5 5458.3 6339.7 6477.4 6478.6 6494.0 6513.0 1655.2 1656.1 1657.5 1658.1 1659.0 1095.6 1105.9 1114.0 1114.6 1117.8 6341.0 6374.6 6384.1 6393.4 6399.2 6732.5 6733.6 6741.8 6742.1 6744.7 6296.4 6350.8 6378.7 6381.9 6389.0 3629.5 3672.8 3673.3 3674.4 3677.5 6986.0 7005.3 7006.5 7010.4 7020.7 6888.3 6944.0 6960.4 6964.2 6985.1 6982.7 7014.8 7015.3 7017.9 7019.5 6794.0 6794.8 6820.9 6822.1 6828.0 6553.9 6678.3 6705.5 6707.3 6713.9 7000.1 7006.2 7009.8 7012.8 7015.7 4708.7 4755.5 4757.0 4759.3 4759.3 6669.2 6793.3 6813.1 6821.2 6825.9 6597.5 6607.2 6620.8 6623.0 6624.6 6601.2 6605.2 6613.9 6616.6 6618.9 6360.4 6476.8 6506.5 6507.3 6509.7 6624.1 6639.7 6640.7 6646.6 6655.3 6463.4 6473.0 6492.4 6496.0 6496.7 10:53:10 INFO - 2016-02-23 10:53:10,580 DEBUG : BROWSER_OUTPUT: 10:53:10 INFO - 2016-02-23 10:53:10,580 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14568.55 Median:47203.88 stddev:17171.64 (36.4%) stddev-sans-first:17189.15 10:53:10 INFO - 2016-02-23 10:53:10,581 DEBUG : BROWSER_OUTPUT: Values: 904.2 911.1 922.6 923.4 937.5 14116.2 14407.5 14409.9 14426.1 14431.5 13237.9 13266.8 13267.9 13282.8 13291.3 713.8 733.9 859.6 871.4 873.1 913.7 915.3 920.7 922.1 922.9 757.7 951.6 963.8 965.2 965.3 764.3 768.7 930.0 944.9 945.9 707.1 707.4 707.4 707.7 707.8 14426.9 14444.5 14498.1 14562.9 14581.6 12475.6 12849.1 13323.4 13560.6 13603.0 11285.3 11296.0 11341.5 11375.1 11382.1 3774.7 3775.3 3799.1 3818.6 3890.0 47194.0 47213.8 49447.3 49842.8 49902.8 10801.4 10888.5 10939.5 11117.8 11300.7 19029.5 19082.2 19084.4 19119.5 19121.2 8036.4 8048.2 8077.9 8126.8 8131.3 4136.7 4423.3 4455.0 4493.7 4498.7 3079.6 3104.4 3110.5 3114.7 3125.4 546.5 560.4 564.3 565.2 569.1 591.9 594.4 623.9 648.2 667.6 8774.9 8904.2 8977.0 9084.3 9112.3 501.4 571.7 594.8 645.9 659.5 3864.4 3924.4 3984.8 4044.8 4051.7 64802.0 64824.7 65287.7 65824.7 65839.0 53704.5 54774.7 55468.7 55478.2 56091.0 50031.5 50045.2 50063.2 50533.2 51351.5 8320.6 8387.7 8399.2 8625.4 8698.3 36158.3 36622.0 36715.0 36860.0 37376.8 44651.8 44930.0 45433.1 46449.1 46737.3 10894.8 11367.9 11618.8 11636.9 11943.3 12644.1 12734.3 12756.6 12981.7 13389.4 11448.8 11466.4 11529.9 11683.5 12106.9 10718.5 11458.8 11626.8 11654.5 11901.6 8475.1 11622.0 11711.2 11945.2 12029.5 9281.6 9504.4 9551.6 9684.4 9771.4 9517.9 9672.3 9852.6 9957.5 10097.0 10:53:10 INFO - 2016-02-23 10:53:10,581 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:53:10 INFO - 2016-02-23 10:53:10,581 DEBUG : BROWSER_OUTPUT: 10:53:11 INFO - 2016-02-23 10:53:11,141 INFO : Browser exited with error code: 0 10:53:11 INFO - 2016-02-23 10:53:11,166 INFO : Completed test dromaeo_css (00:18:30) 10:53:11 INFO - 2016-02-23 10:53:11,166 INFO : Starting test kraken 10:53:11 INFO - 2016-02-23 10:53:11,166 DEBUG : operating with platform_type : linux_ 10:53:11 INFO - 2016-02-23 10:53:11,166 INFO : Initialising browser for kraken test... 10:53:11 INFO - 2016-02-23 10:53:11,172 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLeOQGe/profile http://localhost:37296/getInfo.html 10:53:14 INFO - 2016-02-23 10:53:14,889 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:53:14 INFO - 2016-02-23 10:53:14,889 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:53:14 INFO - 2016-02-23 10:53:14,889 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:53:14 INFO - 2016-02-23 10:53:14,889 DEBUG : BROWSER_OUTPUT: __metrics 10:53:15 INFO - 2016-02-23 10:53:15,047 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:53:16 INFO - 2016-02-23 10:53:16,111 INFO : Browser initialized. 10:53:16 INFO - 2016-02-23 10:53:16,112 INFO : Running cycle 1/1 for kraken test... 10:53:16 INFO - 2016-02-23 10:53:16,112 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLeOQGe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:53:27 INFO - 2016-02-23 10:53:27,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 133955584 10:53:27 INFO - 2016-02-23 10:53:27,794 DEBUG : BROWSER_OUTPUT: 10:53:30 INFO - 2016-02-23 10:53:30,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37296/tests/kraken/driver.html?testName=audio-beat-detection) 10:53:30 INFO - 2016-02-23 10:53:30,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 163340288 10:53:30 INFO - 2016-02-23 10:53:30,815 DEBUG : BROWSER_OUTPUT: 10:53:33 INFO - 2016-02-23 10:53:33,393 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37296/tests/kraken/driver.html?testName=audio-dft) 10:53:33 INFO - 2016-02-23 10:53:33,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 162119680 10:53:33 INFO - 2016-02-23 10:53:33,956 DEBUG : BROWSER_OUTPUT: 10:53:36 INFO - 2016-02-23 10:53:36,785 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37296/tests/kraken/driver.html?testName=audio-fft) 10:53:37 INFO - 2016-02-23 10:53:37,346 DEBUG : BROWSER_OUTPUT: RSS: Main: 158490624 10:53:37 INFO - 2016-02-23 10:53:37,347 DEBUG : BROWSER_OUTPUT: 10:53:39 INFO - 2016-02-23 10:53:39,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37296/tests/kraken/driver.html?testName=audio-oscillator) 10:53:39 INFO - 2016-02-23 10:53:39,881 DEBUG : BROWSER_OUTPUT: RSS: Main: 159481856 10:53:39 INFO - 2016-02-23 10:53:39,881 DEBUG : BROWSER_OUTPUT: 10:53:42 INFO - 2016-02-23 10:53:42,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37296/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:53:42 INFO - 2016-02-23 10:53:42,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 156635136 10:53:42 INFO - 2016-02-23 10:53:42,605 DEBUG : BROWSER_OUTPUT: 10:53:45 INFO - 2016-02-23 10:53:45,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37296/tests/kraken/driver.html?testName=imaging-darkroom) 10:53:45 INFO - 2016-02-23 10:53:45,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 158060544 10:53:45 INFO - 2016-02-23 10:53:45,989 DEBUG : BROWSER_OUTPUT: 10:53:49 INFO - 2016-02-23 10:53:49,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37296/tests/kraken/driver.html?testName=imaging-desaturate) 10:53:49 INFO - 2016-02-23 10:53:49,872 DEBUG : BROWSER_OUTPUT: RSS: Main: 158576640 10:53:49 INFO - 2016-02-23 10:53:49,873 DEBUG : BROWSER_OUTPUT: 10:53:52 INFO - 2016-02-23 10:53:52,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37296/tests/kraken/driver.html?testName=json-parse-financial) 10:53:53 INFO - 2016-02-23 10:53:53,136 DEBUG : BROWSER_OUTPUT: RSS: Main: 158482432 10:53:53 INFO - 2016-02-23 10:53:53,136 DEBUG : BROWSER_OUTPUT: 10:53:54 INFO - 2016-02-23 10:53:54,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37296/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:53:55 INFO - 2016-02-23 10:53:55,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 164802560 10:53:55 INFO - 2016-02-23 10:53:55,227 DEBUG : BROWSER_OUTPUT: 10:53:57 INFO - 2016-02-23 10:53:57,005 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:53:57 INFO - 2016-02-23 10:53:57,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 160661504 10:53:57 INFO - 2016-02-23 10:53:57,600 DEBUG : BROWSER_OUTPUT: 10:53:59 INFO - 2016-02-23 10:53:59,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:54:00 INFO - 2016-02-23 10:54:00,143 DEBUG : BROWSER_OUTPUT: RSS: Main: 168304640 10:54:00 INFO - 2016-02-23 10:54:00,143 DEBUG : BROWSER_OUTPUT: 10:54:02 INFO - 2016-02-23 10:54:02,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:54:03 INFO - 2016-02-23 10:54:03,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 173858816 10:54:03 INFO - 2016-02-23 10:54:03,112 DEBUG : BROWSER_OUTPUT: 10:54:05 INFO - 2016-02-23 10:54:05,616 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:54:06 INFO - 2016-02-23 10:54:06,189 DEBUG : BROWSER_OUTPUT: RSS: Main: 172990464 10:54:06 INFO - 2016-02-23 10:54:06,189 DEBUG : BROWSER_OUTPUT: 10:54:07 INFO - 2016-02-23 10:54:07,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37296/tests/kraken/driver.html?testName=ai-astar) 10:54:08 INFO - 2016-02-23 10:54:08,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 230711296 10:54:08 INFO - 2016-02-23 10:54:08,065 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;103;108;109;109;99;108;108;110 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;159;123;149;163;123;145;148;122;168;126 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;161;162;162;161;161;164;160;162 10:54:08 INFO - 2016-02-23 10:54:08,066 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;73;139;74;72;73;73;154;70 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;147;91;93;93;137;92;92;133 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;147;145;145;146;145;146;145;144 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;95;97;95;95 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;63;57;55;56;57;57;56;53;54 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;69;65;65;64;65;65;65;65 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;77;77;77;78;85;77;77;79;85 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;113;117;109;112;126;117;109;112;111 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;141;140;139;139;140;150;138;138;132 10:54:08 INFO - 2016-02-23 10:54:08,067 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;63;64;62;64;63;63;61;63;63 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,895 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: __startTimestamp1456253648065__endTimestamp 10:54:08 INFO - 2016-02-23 10:54:08,068 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,072 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:54:08 INFO - 2016-02-23 10:54:08,072 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:54:08 INFO - 2016-02-23 10:54:08,072 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,072 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.20 Median:109.00 stddev:3.46 (3.2%) stddev-sans-first:3.61 10:54:08 INFO - 2016-02-23 10:54:08,072 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 103.0 108.0 109.0 109.0 99.0 108.0 108.0 110.0 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.60 Median:148.50 stddev:17.87 (12.0%) stddev-sans-first:17.94 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: Values: 159.0 123.0 149.0 163.0 123.0 145.0 148.0 122.0 168.0 126.0 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.13 10:54:08 INFO - 2016-02-23 10:54:08,073 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 161.0 162.0 162.0 161.0 161.0 164.0 160.0 162.0 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.00 Median:72.50 stddev:31.58 (43.6%) stddev-sans-first:32.90 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 73.0 139.0 74.0 72.0 73.0 73.0 154.0 70.0 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:22.89 (24.9%) stddev-sans-first:23.70 10:54:08 INFO - 2016-02-23 10:54:08,074 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 147.0 91.0 93.0 93.0 137.0 92.0 92.0 133.0 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.97 (0.7%) stddev-sans-first:0.87 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 147.0 145.0 145.0 146.0 145.0 146.0 145.0 144.0 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:54:08 INFO - 2016-02-23 10:54:08,075 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:2.78 (4.9%) stddev-sans-first:2.83 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: Values: 54.0 63.0 57.0 55.0 56.0 57.0 57.0 56.0 53.0 54.0 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:1.45 (2.2%) stddev-sans-first:1.48 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 69.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:77.50 stddev:3.28 (4.2%) stddev-sans-first:3.41 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: Values: 77.0 77.0 77.0 77.0 78.0 85.0 77.0 77.0 79.0 85.0 10:54:08 INFO - 2016-02-23 10:54:08,076 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,077 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:115.00 stddev:5.40 (4.7%) stddev-sans-first:5.36 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: Values: 120.0 113.0 117.0 109.0 112.0 126.0 117.0 109.0 112.0 111.0 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.50 Median:139.50 stddev:4.43 (3.2%) stddev-sans-first:4.66 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: Values: 138.0 141.0 140.0 139.0 139.0 140.0 150.0 138.0 138.0 132.0 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.32 (2.1%) stddev-sans-first:0.93 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: Values: 66.0 63.0 64.0 62.0 64.0 63.0 63.0 61.0 63.0 63.0 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:54:08 INFO - 2016-02-23 10:54:08,078 DEBUG : BROWSER_OUTPUT: 10:54:08 INFO - 2016-02-23 10:54:08,740 INFO : Browser exited with error code: 0 10:54:08 INFO - 2016-02-23 10:54:08,789 INFO : Completed test kraken (00:00:57) 10:54:08 INFO - 2016-02-23 10:54:08,789 INFO : Starting test v8_7 10:54:08 INFO - 2016-02-23 10:54:08,789 DEBUG : operating with platform_type : linux_ 10:54:08 INFO - 2016-02-23 10:54:08,789 INFO : Initialising browser for v8_7 test... 10:54:08 INFO - 2016-02-23 10:54:08,796 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUienj/profile http://localhost:37296/getInfo.html 10:54:12 INFO - 2016-02-23 10:54:12,754 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:54:12 INFO - 2016-02-23 10:54:12,754 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:54:12 INFO - 2016-02-23 10:54:12,754 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:54:12 INFO - 2016-02-23 10:54:12,754 DEBUG : BROWSER_OUTPUT: __metrics 10:54:12 INFO - 2016-02-23 10:54:12,896 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:54:13 INFO - 2016-02-23 10:54:13,643 INFO : Browser initialized. 10:54:13 INFO - 2016-02-23 10:54:13,644 INFO : Running cycle 1/1 for v8_7 test... 10:54:13 INFO - 2016-02-23 10:54:13,644 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUienj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:54:25 INFO - 2016-02-23 10:54:25,292 DEBUG : BROWSER_OUTPUT: RSS: Main: 132714496 10:54:25 INFO - 2016-02-23 10:54:25,293 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37296/tests/v8_7/run.html (next: http://localhost:37296/tests/v8_7/run.html) 10:54:46 INFO - 2016-02-23 10:54:46,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 421183488 10:54:46 INFO - 2016-02-23 10:54:46,890 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,891 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:54:46 INFO - 2016-02-23 10:54:46,891 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:54:46 INFO - 2016-02-23 10:54:46,891 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:54:46 INFO - 2016-02-23 10:54:46,892 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:54:46 INFO - 2016-02-23 10:54:46,892 DEBUG : BROWSER_OUTPUT: |0;Richards;171.939477303989 10:54:46 INFO - 2016-02-23 10:54:46,892 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.85328058028355 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.77777777777777 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |4;RayTrace;925.925925925926 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |5;Earley;544.3658138268917 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |6;Boyer;10447.916666666666 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |7;RegExp;24142.85714285714 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |8;Splay;529.9417064122946 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: __startTimestamp1456253686890__endTimestamp 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,893 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.94 Median:171.94 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: Values: 171.9 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.85 Median:164.85 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: Values: 164.9 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.78 Median:277.78 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: Values: 277.8 10:54:46 INFO - 2016-02-23 10:54:46,894 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: Values: 5191.7 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:925.93 Median:925.93 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: Values: 925.9 10:54:46 INFO - 2016-02-23 10:54:46,895 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.37 Median:544.37 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: Values: 544.4 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10447.92 Median:10447.92 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: Values: 10447.9 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,896 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24142.86 Median:24142.86 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: Values: 24142.9 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:529.94 Median:529.94 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: Values: 529.9 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: Values: 4817.3 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:54:46 INFO - 2016-02-23 10:54:46,897 DEBUG : BROWSER_OUTPUT: 10:54:47 INFO - 2016-02-23 10:54:47,575 INFO : Browser exited with error code: 0 10:54:47 INFO - 2016-02-23 10:54:47,593 INFO : Completed test v8_7 (00:00:38) 10:54:47 INFO - 2016-02-23 10:54:47,918 INFO : Completed test suite (00:20:07) 10:54:47 INFO - 2016-02-23 10:54:47,918 DEBUG : Working with test: dromaeo_css 10:54:47 INFO - 2016-02-23 10:54:47,919 DEBUG : Generating results file: dromaeo_css 10:54:47 INFO - 2016-02-23 10:54:47,920 DEBUG : Working with test: kraken 10:54:47 INFO - 2016-02-23 10:54:47,920 DEBUG : Generating results file: kraken 10:54:47 INFO - 2016-02-23 10:54:47,920 INFO : javascript benchmark 10:54:47 INFO - 2016-02-23 10:54:47,920 DEBUG : Working with test: v8_7 10:54:47 INFO - 2016-02-23 10:54:47,920 DEBUG : Generating results file: v8_7 10:54:47 INFO - 2016-02-23 10:54:47,921 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:54:47 INFO - 2016-02-23 10:54:47,974 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:54:47 INFO - 2016-02-23 10:54:47,974 DEBUG : process_Request line: dromaeo_css 7412.71 graph.html#tests=[[72,53,35]] 10:54:47 INFO - 2016-02-23 10:54:47,974 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:54:48 INFO - 2016-02-23 10:54:48,000 DEBUG : process_Request line: kraken 1472.00 graph.html#tests=[[232,53,35]] 10:54:48 INFO - 2016-02-23 10:54:48,000 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:54:48 INFO - 2016-02-23 10:54:48,044 DEBUG : process_Request line: v8_7 23287.16 graph.html#tests=[[230,53,35]] 10:54:48 INFO - 2016-02-23 10:54:48,045 INFO : javascript benchmark 10:54:48 INFO - 2016-02-23 10:54:48,048 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3288.490284005979, 3353.463445848665, 3358.462645976644, 3372.2749608834097, 3380.66193380662, 5530.0, 5563.499285064348, 5572.359178694452, 5580.91628625571, 5616.943830561693, 5566.4990150886415, 5593.049181639121, 5606.719664016803, 5607.243022192003, 5625.52183064439, 4953.900921981576, 5042.168042273021, 5098.362704661908, 5112.182050871861, 5172.534471897528, 5022.171341728628, 5069.239614057902, 5074.2895994560795, 5081.949180508184, 5084.262781896623, 5025.597952163836, 5047.570956468705, 5077.441481437039, 5080.669756465836, 5171.301874246966, 5081.872953176182, 5182.352205974254, 5185.507376799217, 5189.532942035197, 5191.714455704935, 5123.333966584358, 5153.845384638467, 5269.86825329366, 5276.92084618731, 5304.787808487695, 5253.6322457428, 5282.550983166436, 5355.1699486579255, 5419.5393391561765, 5520.171974203861, 5964.493018093467, 6023.668698221596, 6023.668698221596, 6030.909536356959, 6032.125341825411, 5966.761329546813, 6041.033434650435, 6045.244344456943, 6048.0020796568515, 6048.274207095133, 5396.811111611075, 5490.478404551562, 5627.324721033461, 5720.885582288414, 5770.596058275881, 6185.783497577563, 6187.566870319034, 6191.597546159485, 6196.47329976948, 6197.101420294032, 5693.3168019837885, 6006.459418652343, 6011.308699499614, 6038.33578306386, 6038.547108966845, 5890.764369425175, 5980.0, 6062.333143354227, 6063.636181829016, 6090.725917333731, 5997.310309314485, 6022.307434645072, 6036.215292012098, 6037.1246169305205, 6047.15339852421, 5652.689102099424, 5694.088945768657, 6016.217891674054, 6019.067044608093, 6023.879522409527, 4453.888652783707, 4529.569690879362, 4535.841245556389, 4545.522720114406, 4573.6569757268335, 4114.567970363129, 4132.5454200037975, 4139.399787030924, 4153.480814898137, 4172.061286210639, 5343.198520221998, 5754.568407369464, 5768.451997060272, 5778.3643799182055, 5779.537636989031, 5905.822825315161, 5911.645301281937, 5983.760649573968, 5993.550483713739, 6020.187274717945, 5759.0209664356325, 5791.681457519792, 5795.652260864277, 5798.217240672539, 5801.100829371454, 4977.228529577921, 5070.112730272188, 5241.0, 5285.524302812765, 5291.232771248147, 6060.878782424327, 6122.39815511198, 6131.080337949344, 6133.355997620275, 6141.385861413913, 6089.847753806013, 6118.388161184024, 6119.4798442132915, 6120.1125836752535, 6121.755129794937, 5915.497182739516, 6029.969850150721, 6174.748976760268, 6280.7487700491465, 6282.654454005074, 5969.492593129459, 6084.33072362031, 6089.5737298388685, 6109.84725381869, 6117.663528505975, 6083.66539840313, 6116.204893363834, 6138.386161383826, 6145.600535965327, 6148.4773794227995, 5691.317041954826, 5998.25021872266, 6000.729967151401, 6001.0, 6007.098935159586, 5693.259876216065, 5874.676892770768, 5983.910241346471, 5988.820335389945, 6011.458968692838, 5800.59395842287, 5805.738741756547, 5811.767529298781, 5813.128030795415, 5815.912761308668, 5661.320641523043, 5768.653880767168, 5915.112733089899, 5915.142304365929, 5916.437938395846, 5736.110902810071, 5812.0, 5814.709264536841, 5823.621464604787, 5852.122181672613], "unit": "ms", "name": "dojo.html", "value": 5545.894887054614}, {"lowerIsBetter": false, "replicates": [574.7787101965741, 618.7617767159643, 622.1290193728779, 627.203451616447, 637.4517914593451, 32245.51631725523, 42863.0, 42907.14185716284, 43012.13975720484, 43102.7844860776, 46949.0, 47331.526684733144, 47365.0, 47427.28859067117, 47595.0, 7781.455298129134, 7891.565963871984, 7895.210478952116, 9110.954445227764, 9287.767805804875, 10882.183836212276, 11014.44927753613, 11021.724456888562, 11060.281081729701, 11067.72330691731, 10997.285176463503, 11170.609028683997, 11198.0, 11199.048080913131, 11203.0, 11047.502862371173, 11148.331100134017, 11162.330260184415, 11164.776704465947, 11190.38452885091, 10743.785124297548, 10909.890901090967, 10912.399818010006, 10914.890851091466, 10916.181286403473, 42610.360844587354, 42957.785211073744, 43019.354709679064, 43019.78490107561, 43078.0, 3427.8114703691285, 3480.868717666743, 3571.357155711971, 3664.1572438339063, 3961.58403367648, 31044.844775776204, 31677.0, 32111.51832722511, 34551.0, 36055.81972090149, 4293.957060429387, 4359.782010899474, 4363.738175709467, 4368.148211098837, 4371.956280437218, 316906.0, 319272.0, 319365.0, 320055.0, 320259.0, 60058.69970650206, 67629.3237067623, 67920.0, 67993.3200667987, 68032.0, 53531.46468535343, 53740.73129634327, 79242.0, 79562.0, 79674.0, 18542.629147417247, 18638.720419193724, 18672.253109875724, 18821.529461763566, 18836.529086772665, 14394.784078238834, 14472.348744306531, 14551.636209094857, 14581.85418145805, 14595.197264150574, 21930.574512656625, 22283.22008729719, 22305.665415018786, 22312.21907233239, 22369.217077402212, 7973.72091976778, 8029.7189598363775, 8095.635696393676, 8151.103378628346, 8322.250997410141, 968.6028728221395, 1096.4572536594437, 1215.769003889258, 1227.34337129637, 1236.7526494701096, 29808.701912981025, 29971.70028299689, 30611.0, 30791.692083078884, 30798.384032319675, 1114.9318015082067, 1137.4142316706902, 1172.6013155527162, 1236.6970092327258, 1240.6588188248304, 14322.498712545012, 15731.842681573267, 16284.43004494837, 17029.574260643334, 18469.445916622524, 452827.0, 458194.0, 458847.0, 458961.0, 459345.0, 61299.08051379233, 61353.38646613566, 61725.0, 61913.0, 62009.68995154995, 1442.898997070195, 1443.7150935667264, 1451.506487794134, 1458.0522660270908, 1483.9258037098102, 31899.84050079735, 32039.839800800848, 32244.516332255505, 33890.66109338875, 35235.29529409449, 55596.220188991196, 56703.71648141733, 56717.43282567122, 56738.14892776694, 56766.43233567777, 330646.0, 332666.0, 333103.0, 333933.0, 334835.0, 39569.60430395659, 40280.59719402886, 46369.304460432446, 49766.0, 52324.0, 47208.0, 47279.0, 48249.75875120602, 48725.26912096392, 48872.51127488822, 39864.402033970095, 40477.190456190125, 40760.79619601883, 41269.793651032756, 41274.58725412707, 38604.42093368658, 40312.0, 40380.0, 41010.79494602508, 44757.55242447534, 39475.80262098671, 39642.80178599089, 39712.80143599264, 40291.395629065, 40344.1931161381, 34894.476582851785, 35373.64626353703, 35491.64508354987, 36348.45477317895, 36382.454263186606, 27917.441651167268, 28462.715372846837, 30866.84566577153, 31163.688363116078, 31177.37645247128], "unit": "ms", "name": "ext.html", "value": 21284.67448026972}, {"lowerIsBetter": false, "replicates": [387.4846454215895, 387.72471545202893, 391.2644228849762, 392.21361170852435, 393.990150246244, 18247.635047299045, 19678.60642787147, 20023.1990720371, 20051.398458046246, 20114.798852011474, 18384.08079596018, 18477.907610461963, 18656.347027854026, 18896.811031889676, 18903.716444253314, 5499.450054994499, 5616.494515493605, 5629.662220266797, 5635.8591035224035, 5638.915416268739, 5497.340319161706, 5576.470235327658, 5582.7766889324375, 5588.105903055512, 5610.495055445009, 4076.062505623708, 4173.290540608104, 5535.280413546233, 5540.889182216353, 5543.750531226105, 5843.0, 5956.821295361146, 6008.279006519202, 6018.939810601903, 6020.548458865603, 5271.578273738091, 5330.253764472977, 5346.518813306782, 5364.249005139283, 5366.4633536646415, 12760.6809829755, 16847.241874115694, 18861.811381886142, 20753.5849283015, 21267.46831329214, 2213.1700612270397, 2275.9317220483244, 2322.372959300996, 2346.9530609387716, 2631.0, 9816.361936474177, 10066.496675166212, 10263.43551104689, 12309.630711078682, 13182.934085329607, 1919.2323070771663, 1936.0997136331532, 1958.4712127725434, 1995.102204008202, 2023.514356554431, 63682.68158659223, 66725.33274667288, 67358.0, 67524.49392510756, 67554.0, 11218.326900386002, 11237.550497980154, 11261.943690281496, 11291.153163512605, 11298.774024519627, 14969.101853888802, 14984.550463486114, 15064.623384415258, 15076.472323468626, 15136.0, 8627.180417860418, 8652.783680407914, 8730.21428071464, 8784.472931624123, 8944.150305720947, 8362.749117526484, 8371.539565323845, 8410.663573457115, 8464.365172612079, 8466.068732439428, 4467.5755803198645, 4470.843520476833, 4622.422197225347, 4722.244440889442, 4903.190973489334, 1875.367255271061, 1919.7408349872867, 1937.1282922684848, 1944.5819148883058, 1966.3019628031843, 1055.7096798380508, 1075.9515821788038, 1086.750047489082, 1104.6078642082107, 1125.994370028161, 15300.464483743015, 17494.825051749573, 17594.472165835046, 17938.91030544839, 18181.00004499739, 985.443000059918, 999.4652860719481, 1007.9193664506822, 1027.3476342522551, 1042.9843552346722, 10185.847212291821, 10265.281430299956, 10322.948385258176, 10362.326448780956, 10798.406087665255, 452670.0, 457319.0, 510846.0, 514083.0, 518028.0, 82459.17540824634, 88593.0, 88911.0, 89864.0, 89954.55022724844, 1159.5535718748342, 1205.2828567002618, 1217.8843009914044, 1224.0819385460904, 1226.8773122687837, 15896.920515397349, 15923.44267950616, 15929.522114336589, 16025.038497690175, 16335.101569413797, 37566.624333756314, 37729.81135094307, 38309.0, 38392.0, 39418.802905986435, 50626.0, 51289.48710512996, 52760.73619631877, 53705.46294537161, 54018.35081741733, 10188.235882308709, 10293.330933489298, 10420.166386688896, 10421.114205292635, 10456.843147352947, 12094.879051209375, 18836.434906952814, 18886.3389781357, 18933.0, 19272.32546860853, 15428.614284642974, 15553.066815991077, 15893.761593575875, 15936.601584960468, 15940.123293218985, 15160.862935279678, 15172.165530895465, 15364.0, 15729.921350393175, 15997.520074397788, 15473.381064757283, 15505.767413489033, 15881.285342159399, 16041.919790400972, 16085.195740213177, 9990.350627209207, 10441.843372349269, 10572.735681608021, 10637.893621063691, 10666.83999739989, 10894.583704118413, 10971.561137554409, 13683.384247708678, 15816.288267027781, 16139.0], "unit": "ms", "name": "jquery.html", "value": 9508.11345928664}, {"lowerIsBetter": false, "replicates": [1465.4284828916732, 1764.9558761030974, 1810.9728354074687, 1823.68997270464, 1846.4737549798301, 6959.304069593039, 7144.321289477504, 7506.4370172237095, 8090.43366964312, 8093.595320233987, 7434.702611895531, 7540.62296885155, 7642.923570764291, 7656.042994625672, 7657.27255910689, 2330.4290448840015, 2364.4561750797325, 2370.9525809483885, 2371.1937941100023, 2371.27676058802, 2028.2191356327774, 2073.0774805211686, 2314.1784666443423, 2347.6243800991847, 2374.323317854412, 2292.9541409171807, 2326.4067662745974, 2337.1586228957563, 2339.94150146246, 2340.918067867625, 2355.1286024170995, 2384.1059602649007, 2385.86877721727, 2387.116766796279, 2387.9761202387926, 2364.4679947011955, 2406.6630671705975, 2406.9037238510437, 2408.0728919365997, 2408.241403957765, 7828.804279892991, 7883.251091146332, 7886.72396466121, 7889.8816517752275, 7890.684372625088, 4747.8575642730775, 4855.417349918032, 4857.052874689437, 4858.174110401205, 4861.854144375674, 3110.529745000229, 3204.6795320468, 3207.134073800084, 3212.8714851405916, 3229.0958531611186, 1090.0462095666292, 1114.3035602748282, 1121.3552207480732, 1150.1316506037972, 1991.5021244688828, 10767.83848242277, 10918.890811091867, 10925.672229833117, 10930.781384372347, 10956.726081848017, 4094.324436468009, 4113.03343714227, 4136.98643832263, 4173.83304667816, 4178.958210417918, 8844.115588441104, 8861.778455538662, 8878.644854205888, 8886.644534218689, 8896.59965301563, 4755.191617424977, 4757.26262429324, 4763.0, 4768.713877167381, 4769.66612337133, 4325.97837010813, 4407.206702793528, 4410.625096866738, 4415.359772832985, 4468.910621787546, 3249.3014001989686, 3614.927701446009, 3621.6197299283194, 3649.5073165122367, 3654.3239500691925, 988.5996171550534, 1097.9121670266359, 1097.9725506862392, 1106.8948449897248, 1112.488255402524, 1162.4245998230929, 1168.0363699947577, 1216.6167657187957, 1438.4246301479284, 1457.176695167227, 7317.963410182915, 7410.11078670563, 7576.28025337592, 7597.582132982739, 7603.0, 625.8341539492038, 629.2889035390072, 631.8388810853295, 668.0813880913744, 807.1645846548911, 6090.3605121461605, 6148.200733904564, 6150.7232174552255, 6151.876962460726, 6159.784407545714, 9241.815163696689, 9306.53467326631, 9485.762855928657, 9521.285903557122, 9557.378770379903, 9422.575984080733, 9518.095780900803, 9519.524023798782, 9520.714378568655, 9548.761280967892, 7352.750032494489, 7422.035135432359, 7458.067741532309, 7472.9252707473315, 7482.5884576347735, 7298.197198308288, 7316.707331706885, 7334.706611735471, 7343.192248852515, 7370.078740157481, 8330.583470826314, 8392.622331995197, 8458.0, 8563.100874408097, 8570.657173712982, 8662.04717481064, 8665.220130188314, 8773.473591584525, 8773.517456539952, 8781.0, 6589.374009469093, 6973.546719463218, 6988.440924726111, 6990.685419156252, 6992.580445173306, 6778.288279730755, 7635.961820190863, 7690.192529784292, 7699.961500192463, 7701.037370328709, 6462.4183823456115, 6852.657367131723, 7035.826724584537, 7326.9267307326245, 7439.218882017527, 7135.643217838983, 7174.246704095995, 7254.854902902018, 7268.563886166636, 7296.379807716404, 7251.456140789356, 7269.709211631475, 7322.816929576803, 7324.523905946096, 7331.41348692114, 6735.595864248161, 6773.62745049027, 6783.559068660521, 6788.389044985975, 6788.66056697173, 6804.081449004321, 6852.211995620568, 6882.655867206719, 6894.827629309308, 6917.481188910952], "unit": "ms", "name": "mootools.html", "value": 4541.97761288027}, {"lowerIsBetter": false, "replicates": [359.8920323902829, 367.67277123360196, 374.457037295921, 374.52814381880734, 383.9827207775648, 6092.908606370903, 6151.384861513852, 6156.876862462759, 6175.969120154393, 6177.660228687426, 6076.787312444066, 6083.878322433548, 6100.847478813032, 6106.450419462248, 6109.633421994672, 4770.522947705236, 4771.451283102453, 4774.7135171889795, 4775.570198682118, 4776.713397196162, 4585.426821647294, 4682.08699303636, 4684.203685373494, 4774.1645212087915, 4778.569928706415, 4654.045920586289, 4660.46404663463, 4661.347411362412, 4675.555822196886, 4676.906461870761, 4497.0, 4668.393108895856, 4675.2052151134385, 4681.46163191234, 4691.7654117294, 4029.073313137965, 4047.332190188615, 4124.484439445069, 4130.855420060314, 4155.688323375729, 5768.855778605526, 5775.971120144372, 5787.797427090074, 5795.884082318372, 5801.50687191585, 5164.8967020659375, 5261.0, 5286.233496143076, 5470.316210473691, 5486.725663716798, 6410.487161027107, 6413.679316034226, 6445.83885402864, 6447.677616119222, 6452.90320645186, 4437.378766972626, 4443.355713421536, 4448.822047118111, 4449.688521803475, 4451.198784218839, 6588.604683718992, 6635.43598794103, 6712.194536655584, 6714.764983225564, 6716.865662686768, 4663.463701674283, 4682.367880336179, 4683.8360657376825, 4686.6485013624115, 4691.741954192552, 4735.763211839394, 4739.075880203327, 4747.501512341223, 4748.145333839873, 4750.192467280571, 5442.619016668875, 5446.455354464601, 5452.04589196889, 5456.536194423439, 5458.317710286215, 6339.714712837934, 6477.4170324671, 6478.643674597848, 6494.0, 6512.967435162794, 1655.1558705060265, 1656.080875114299, 1657.4696097248764, 1658.1377683604458, 1658.96682066358, 1095.611058074372, 1105.9225854190129, 1114.0419239454063, 1114.587602587048, 1117.7652692934412, 6340.968295158495, 6374.553781235361, 6384.07430922523, 6393.424591786761, 6399.168108145911, 6732.4950628703045, 6733.6296503692765, 6741.797746067626, 6742.123523941857, 6744.730210791513, 6296.433321001132, 6350.777722779773, 6378.712957916905, 6381.936180638227, 6389.0, 3629.528161339009, 3672.779633222015, 3673.2653469306247, 3674.430463278196, 3677.485152078711, 6985.965070174617, 7005.264447233069, 7006.544574602634, 7010.404115650125, 7020.719171233196, 6888.311168883171, 6944.02783610295, 6960.443164546824, 6964.199117101598, 6985.057017302701, 6982.720691172398, 7014.754483593049, 7015.298470152944, 7017.859642807115, 7019.543729657556, 6793.966030169818, 6794.76218332356, 6820.93179068203, 6822.11312529368, 6827.965860170866, 6553.901691474536, 6678.298778628174, 6705.497087718538, 6707.295733947865, 6713.899291510534, 7000.054992575937, 7006.194287656985, 7009.7897063088185, 7012.789616311519, 7015.6842942066705, 4708.693934894221, 4755.5006724294835, 4756.976215119041, 4759.262314341283, 4759.286107083826, 6669.233038200669, 6793.320667933365, 6813.080234168324, 6821.1814582250445, 6825.931740682529, 6597.472202223715, 6607.240167380813, 6620.8344791380605, 6623.0, 6624.569402988983, 6601.240857301472, 6605.174353205849, 6613.933860661332, 6616.603003819786, 6618.933810662024, 6360.39576240275, 6476.805695829133, 6506.479481641552, 6507.349265073455, 6509.73961041572, 6624.138861947916, 6639.734410623714, 6640.667966601747, 6646.567973081735, 6655.267920528835, 6463.353664633499, 6473.0, 6492.415682588589, 6495.953774560998, 6496.740130394731], "unit": "ms", "name": "prototype.html", "value": 4949.493322752298}, {"lowerIsBetter": false, "replicates": [904.2404380320531, 911.134422298816, 922.5617831530024, 923.381334505881, 937.5171786529943, 14116.223607701573, 14407.495737649184, 14409.855901440995, 14426.062305950132, 14431.49489767857, 13237.933810330935, 13266.800997985038, 13267.93366033171, 13282.800757988638, 13291.335433228323, 713.8072720365499, 733.8679037773198, 859.6475445067524, 871.4422769427572, 873.1050673060132, 913.6893456224882, 915.2952226785371, 920.6501529418812, 922.1331947968921, 922.9400088994194, 757.727218201447, 951.5860600638745, 963.7831487915198, 965.1844191658038, 965.2760429677742, 764.2853931573968, 768.6733138416179, 929.9535023248844, 944.8960614332418, 945.8912225094133, 707.0878566649017, 707.3739740329794, 707.4199156691515, 707.6815433055094, 707.8407358344383, 14426.92786536071, 14444.494442694455, 14498.05762625426, 14562.854371456257, 14581.635459113608, 12475.563355282522, 12849.100562960597, 13323.400446979813, 13560.602295880317, 13603.047786654937, 11285.322880627107, 11296.0, 11341.546338146465, 11375.089992800557, 11382.089432845352, 3774.660280574734, 3775.339315619756, 3799.1072098056934, 3818.5990471000423, 3890.0, 47194.0, 47213.763931180125, 49447.25829112566, 49842.7507862462, 49902.750486247336, 10801.405922674172, 10888.455577221266, 10939.507722152523, 11117.777644447227, 11300.660980170607, 19029.524261893286, 19082.236710531703, 19084.427467176007, 19119.52201194981, 19121.235150594097, 8036.357091432788, 8048.154943730941, 8077.919220807717, 8126.801152737728, 8131.349492040623, 4136.689748268892, 4423.270160423558, 4454.977725111354, 4493.73037617744, 4498.730076195438, 3079.59965204522, 3104.3946430445844, 3110.4712198926236, 3114.6573876873526, 3125.4217969675683, 546.519063224368, 560.4003716023815, 564.3199944067471, 565.2284631478085, 569.1207085053602, 591.8549955260988, 594.432317137133, 623.9188905442354, 648.1929997153574, 667.6361383046251, 8774.868376974351, 8904.243139333099, 8977.0, 9084.31867609932, 9112.271018318652, 501.3657722980433, 571.6541492397123, 594.8126340202822, 645.8837409266283, 659.4658326755343, 3864.439656249828, 3924.3524818404644, 3984.7808370539897, 4044.7775372354804, 4051.6556092731857, 64802.0, 64824.67587662031, 65287.67356163284, 65824.67087664531, 65839.0, 53704.46295537073, 54774.72612636911, 55468.72265638727, 55478.16782748262, 56091.0, 50031.499685003415, 50045.24932126021, 50063.24905126426, 50533.24200137001, 51351.48648513467, 8320.583970801557, 8387.664493420194, 8399.160083991552, 8625.439346442461, 8698.260647845003, 36158.276834463686, 36622.0, 36715.0, 36860.0, 37376.813115934245, 44651.77674111609, 44930.0, 45433.09133817239, 46449.07101857876, 46737.2989405166, 10894.836577451186, 11367.886321136682, 11618.825717614412, 11636.88363116358, 11943.283402995849, 12644.114911956074, 12734.299613520974, 12756.61730148097, 12981.740365192833, 13389.397477113358, 11448.828267575826, 11466.426678666201, 11529.884701153216, 11683.474243658886, 12106.939465302617, 10718.517666704862, 11458.770824583295, 11626.82559761621, 11654.53381864716, 11901.583444579399, 8475.068264003394, 11622.012128968794, 11711.238769479956, 11945.223560468541, 12029.4586743595, 9281.62873485053, 9504.382215155993, 9551.570179342085, 9684.370515916444, 9771.413715176828, 9517.904820951702, 9672.274579406432, 9852.556634951301, 9957.452340121363, 10097.0], "unit": "ms", "name": "yui.html", "value": 6574.790191973307}], "name": "dromaeo_css", "value": 7412.712396692126}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 103.0, 108.0, 109.0, 109.0, 99.0, 108.0, 108.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.2}, {"lowerIsBetter": true, "replicates": [159.0, 123.0, 149.0, 163.0, 123.0, 145.0, 148.0, 122.0, 168.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.6}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 161.0, 162.0, 162.0, 161.0, 161.0, 164.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.7}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 73.0, 139.0, 74.0, 72.0, 73.0, 73.0, 154.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.0}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 147.0, 91.0, 93.0, 93.0, 137.0, 92.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 147.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [54.0, 63.0, 57.0, 55.0, 56.0, 57.0, 57.0, 56.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 69.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [77.0, 77.0, 77.0, 77.0, 78.0, 85.0, 77.0, 77.0, 79.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.9}, {"lowerIsBetter": true, "replicates": [120.0, 113.0, 117.0, 109.0, 112.0, 126.0, 117.0, 109.0, 112.0, 111.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.6}, {"lowerIsBetter": true, "replicates": [138.0, 141.0, 140.0, 139.0, 139.0, 140.0, 150.0, 138.0, 138.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.5}, {"lowerIsBetter": true, "replicates": [66.0, 63.0, 64.0, 62.0, 64.0, 63.0, 63.0, 61.0, 63.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.2}], "name": "kraken", "value": 1472.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.939477303989], "unit": "score", "name": "Richards", "value": 205.31643199999996}, {"lowerIsBetter": false, "replicates": [164.85328058028355], "unit": "score", "name": "DeltaBlue", "value": 401.0717880000001}, {"lowerIsBetter": false, "replicates": [277.77777777777777], "unit": "score", "name": "Encrypt", "value": 958.2516000000002}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [925.925925925926], "unit": "score", "name": "RayTrace", "value": 799.1881199999998}, {"lowerIsBetter": false, "replicates": [544.3658138268917], "unit": "score", "name": "Earley", "value": 1224.2925309999998}, {"lowerIsBetter": false, "replicates": [10447.916666666666], "unit": "score", "name": "Boyer", "value": 63.78908075772688}, {"lowerIsBetter": false, "replicates": [24142.85714285714], "unit": "score", "name": "RegExp", "value": 37.73310650887576}, {"lowerIsBetter": false, "replicates": [529.9417064122946], "unit": "score", "name": "Splay", "value": 153.77351699999997}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23287.16296235668}]} 10:54:48 INFO - RETURN: dromaeo_css: 7412.71 10:54:48 INFO - RETURN: kraken: 1472.00 10:54:48 INFO - RETURN: v8_7: 23287.16 10:54:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23287.16"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1472.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7412.71"}}} 10:54:48 INFO - Return code: 0 10:54:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:54:48 INFO - # TBPL SUCCESS # 10:54:48 INFO - Running post-action listener: _resource_record_post_action 10:54:48 INFO - Running post-run listener: _resource_record_post_run 10:54:49 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 315392; Write bytes: 513245184; Read time: 60; Write time: 547048 10:54:49 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 247877632; Read time: 0; Write time: 366112 10:54:49 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 315392; Write bytes: 265367552; Read time: 60; Write time: 180936 10:54:49 INFO - Running post-run listener: _upload_blobber_files 10:54:49 INFO - Blob upload gear active. 10:54:49 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:54:49 INFO - Copying logs to upload dir... 10:54:49 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.855500 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-23 10:54:48.621761) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-23 10:54:48.625046) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009956 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456243852/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-23 10:54:48.679397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:54:48.679737) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456252316.806074-1426022817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 10:54:48.732311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-23 10:54:48.733035) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-23 10:54:48.733669) ========= ========= Total master_lag: 0.32 =========