builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1456468760.8 results: success (0) buildid: 20160225202124 builduid: badaf181d0b5424f9909767f4ec9b0e5 revision: 54424f42b757 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.797272) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.797797) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.798092) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009182 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.821408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.821715) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.835069) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.835541) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004697 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.886083) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.886616) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.887235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:20.887704) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 22:39:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.6M=0s 2016-02-25 22:39:21 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156272 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.056900) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.057233) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022595 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.107025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.107393) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 54424f42b757 --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 54424f42b757 --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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 22:39:21,618 Setting DEBUG logging. 2016-02-25 22:39:21,618 attempt 1/10 2016-02-25 22:39:21,618 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/54424f42b757?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 22:39:21,901 unpacking tar archive at: mozilla-beta-54424f42b757/testing/mozharness/ program finished with exit code 0 elapsedTime=0.604976 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.725046) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.725418) ========= echo 54424f42b757 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'54424f42b757'] 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 54424f42b757 program finished with exit code 0 elapsedTime=0.003989 script_repo_revision: '54424f42b757' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.774101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.774432) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 22:39:21.784579) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-25 22:39:21.784935) ========= /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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:39:22 INFO - MultiFileLogger online at 20160225 22:39:22 in /builds/slave/test-pgo 22:39:22 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 22:39:22 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:39:22 INFO - {'append_to_log': False, 22:39:22 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:39:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:39:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:39:22 INFO - 'branch': 'Mozilla-Beta', 22:39:22 INFO - 'buildbot_json_path': 'buildprops.json', 22:39:22 INFO - 'config_files': ('talos/linux_config.py',), 22:39:22 INFO - 'default_actions': ('clobber', 22:39:22 INFO - 'read-buildbot-config', 22:39:22 INFO - 'download-and-extract', 22:39:22 INFO - 'populate-webroot', 22:39:22 INFO - 'create-virtualenv', 22:39:22 INFO - 'install', 22:39:22 INFO - 'run-tests'), 22:39:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:39:22 INFO - 'download_minidump_stackwalk': True, 22:39:22 INFO - 'download_symbols': 'ondemand', 22:39:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:39:22 INFO - 'tooltool.py': '/tools/tooltool.py', 22:39:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:39:22 INFO - '/tools/misc-python/virtualenv.py')}, 22:39:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:39:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:39:22 INFO - 'installer_path': 'installer.exe', 22:39:22 INFO - 'log_level': 'info', 22:39:22 INFO - 'log_name': 'talos', 22:39:22 INFO - 'log_to_console': True, 22:39:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:39:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:39:22 INFO - 'opt_config_files': (), 22:39:22 INFO - 'pip_index': False, 22:39:22 INFO - 'sps_profile': False, 22:39:22 INFO - 'sps_profile_interval': 0, 22:39:22 INFO - 'suite': 'dromaeojs', 22:39:22 INFO - 'system_bits': '32', 22:39:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:39:22 INFO - 'title': 'talos-linux64-ix-019', 22:39:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:39:22 INFO - 'use_talos_json': True, 22:39:22 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:39:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:39:22 INFO - 'work_dir': 'build'} 22:39:22 INFO - ##### 22:39:22 INFO - ##### Running clobber step. 22:39:22 INFO - ##### 22:39:22 INFO - Running pre-action listener: _resource_record_pre_action 22:39:22 INFO - Running main action method: clobber 22:39:22 INFO - rmtree: /builds/slave/test-pgo/build 22:39:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:39:22 INFO - Running post-action listener: _resource_record_post_action 22:39:22 INFO - ##### 22:39:22 INFO - ##### Running read-buildbot-config step. 22:39:22 INFO - ##### 22:39:22 INFO - Running pre-action listener: _resource_record_pre_action 22:39:22 INFO - Running main action method: read_buildbot_config 22:39:22 INFO - Using buildbot properties: 22:39:22 INFO - { 22:39:22 INFO - "properties": { 22:39:22 INFO - "buildnumber": 103, 22:39:22 INFO - "product": "firefox", 22:39:22 INFO - "script_repo_revision": "production", 22:39:22 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 22:39:22 INFO - "repository": "", 22:39:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 22:39:22 INFO - "buildid": "20160225202124", 22:39:22 INFO - "slavename": "talos-linux64-ix-019", 22:39:22 INFO - "pgo_build": "True", 22:39:22 INFO - "basedir": "/builds/slave/test-pgo", 22:39:22 INFO - "project": "", 22:39:22 INFO - "platform": "ubuntu64_hw", 22:39:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:39:22 INFO - "slavebuilddir": "test", 22:39:22 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:39:22 INFO - "branch": "mozilla-beta", 22:39:22 INFO - "repo_path": "releases/mozilla-beta", 22:39:22 INFO - "stage_platform": "linux64-pgo", 22:39:22 INFO - "builduid": "badaf181d0b5424f9909767f4ec9b0e5", 22:39:22 INFO - "revision": "54424f42b757" 22:39:22 INFO - }, 22:39:22 INFO - "sourcestamp": { 22:39:22 INFO - "repository": "", 22:39:22 INFO - "hasPatch": false, 22:39:22 INFO - "project": "", 22:39:22 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 22:39:22 INFO - "changes": [ 22:39:22 INFO - { 22:39:22 INFO - "category": null, 22:39:22 INFO - "files": [ 22:39:22 INFO - { 22:39:22 INFO - "url": null, 22:39:22 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2" 22:39:22 INFO - } 22:39:22 INFO - ], 22:39:22 INFO - "repository": "", 22:39:22 INFO - "rev": "54424f42b757", 22:39:22 INFO - "who": "sendchange", 22:39:22 INFO - "when": 1456468718, 22:39:22 INFO - "number": 7657852, 22:39:22 INFO - "comments": "Bug 1247175 - Accept libavcodec 57.100+ - r=jya, a=lizzard, a=sylvestre", 22:39:22 INFO - "project": "", 22:39:22 INFO - "at": "Thu 25 Feb 2016 22:38:38", 22:39:22 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 22:39:22 INFO - "revlink": "", 22:39:22 INFO - "properties": [ 22:39:22 INFO - [ 22:39:22 INFO - "buildid", 22:39:22 INFO - "20160225202124", 22:39:22 INFO - "Change" 22:39:22 INFO - ], 22:39:22 INFO - [ 22:39:22 INFO - "builduid", 22:39:22 INFO - "badaf181d0b5424f9909767f4ec9b0e5", 22:39:22 INFO - "Change" 22:39:22 INFO - ], 22:39:22 INFO - [ 22:39:22 INFO - "pgo_build", 22:39:22 INFO - "True", 22:39:22 INFO - "Change" 22:39:22 INFO - ] 22:39:22 INFO - ], 22:39:22 INFO - "revision": "54424f42b757" 22:39:22 INFO - } 22:39:22 INFO - ], 22:39:22 INFO - "revision": "54424f42b757" 22:39:22 INFO - } 22:39:22 INFO - } 22:39:22 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2. 22:39:22 INFO - Running post-action listener: _resource_record_post_action 22:39:22 INFO - ##### 22:39:22 INFO - ##### Running download-and-extract step. 22:39:22 INFO - ##### 22:39:22 INFO - Running pre-action listener: _resource_record_pre_action 22:39:22 INFO - Running main action method: download_and_extract 22:39:22 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:39:22 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')]} 22:39:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/test_packages.json 22:39:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:39:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:39:22 INFO - Downloaded 1270 bytes. 22:39:22 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:39:22 INFO - Using the following test package requirements: 22:39:22 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 22:39:22 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:39:22 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'jsshell-linux-x86_64.zip'], 22:39:22 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:39:22 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 22:39:22 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 22:39:22 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 22:39:22 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:39:22 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 22:39:22 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 22:39:22 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:39:22 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:39:22 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')]} 22:39:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.common.tests.zip 22:39:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:23 INFO - Downloaded 21584291 bytes. 22:39:23 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 22:39:23 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 22:39:23 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 22:39:24 INFO - Return code: 0 22:39:24 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 22:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.common.tests.zip 22:39:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:24 INFO - Downloaded 21584291 bytes. 22:39:24 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 22:39:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 22:39:24 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 22:39:25 INFO - Return code: 0 22:39:25 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')]} 22:39:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 22:39:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:27 INFO - Downloaded 11132038 bytes. 22:39:27 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 22:39:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 22:39:27 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 22:39:27 INFO - Return code: 0 22:39:27 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')]} 22:39:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2 22:39:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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 22:39:28 INFO - Downloaded 57948147 bytes. 22:39:28 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2 22:39:28 INFO - mkdir: /builds/slave/test-pgo/properties 22:39:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:39:28 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:39:28 INFO - Contents: 22:39:28 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2 22:39:28 INFO - Running post-action listener: _resource_record_post_action 22:39:28 INFO - Running post-action listener: set_extra_try_arguments 22:39:28 INFO - ##### 22:39:28 INFO - ##### Running populate-webroot step. 22:39:28 INFO - ##### 22:39:28 INFO - Running pre-action listener: _resource_record_pre_action 22:39:28 INFO - Running main action method: populate_webroot 22:39:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:39:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:39:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:39:28 INFO - '--tppagecycles', 22:39:28 INFO - '7'], 22:39:28 INFO - 'tests': ['tsvgm']}}, 22:39:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:39:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:39:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:39:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:39:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'tests': ['damp', 'tps']}, 22:39:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['damp', 'tps']}, 22:39:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:39:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:39:28 INFO - 'other': {'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'other_l64': {'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'other_nol64': {'tests': ['a11yr', 22:39:28 INFO - 'ts_paint', 22:39:28 INFO - 'tpaint', 22:39:28 INFO - 'sessionrestore', 22:39:28 INFO - 'sessionrestore_no_auto_restore']}, 22:39:28 INFO - 'svgr': {'tests': ['tsvgx', 22:39:28 INFO - 'tsvgr_opacity', 22:39:28 INFO - 'tart', 22:39:28 INFO - 'tscrollx', 22:39:28 INFO - 'cart']}, 22:39:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['tsvgx', 22:39:28 INFO - 'tsvgr_opacity', 22:39:28 INFO - 'tart', 22:39:28 INFO - 'tscrollx', 22:39:28 INFO - 'cart']}, 22:39:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'tests': ['tp5o']}, 22:39:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'talos_options': ['--e10s'], 22:39:28 INFO - 'tests': ['tp5o']}, 22:39:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'talos_options': ['--xperf_path', 22:39:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:28 INFO - 'tests': ['tp5n']}, 22:39:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:28 INFO - 'talos_options': ['--e10s', 22:39:28 INFO - '--xperf_path', 22:39:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:28 INFO - 'tests': ['tp5n']}}, 22:39:28 INFO - 'talos.zip': {'path': '', 22:39:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:39:28 INFO - Running post-action listener: _resource_record_post_action 22:39:28 INFO - ##### 22:39:28 INFO - ##### Running create-virtualenv step. 22:39:28 INFO - ##### 22:39:28 INFO - Running pre-action listener: _resource_record_pre_action 22:39:28 INFO - Running main action method: create_virtualenv 22:39:28 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:39:28 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 22:39:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:39:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:39:28 INFO - Using real prefix '/usr' 22:39:28 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:39:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:39:30 INFO - Installing pip.................done. 22:39:30 INFO - Return code: 0 22:39:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:31 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 22:39:31 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 22:39:31 INFO - Using env: {'DISPLAY': ':0', 22:39:31 INFO - 'HOME': '/home/cltbld', 22:39:31 INFO - 'LANG': 'en_US.UTF-8', 22:39:31 INFO - 'LANGUAGE': 'en_US:en', 22:39:31 INFO - 'LOGNAME': 'cltbld', 22:39:31 INFO - 'MAIL': '/var/mail/cltbld', 22:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:31 INFO - 'MOZ_NO_REMOTE': '1', 22:39:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:31 INFO - 'NO_EM_RESTART': '1', 22:39:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:31 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:31 INFO - 'SHELL': '/bin/bash', 22:39:31 INFO - 'SHLVL': '1', 22:39:31 INFO - 'TERM': 'linux', 22:39:31 INFO - 'TMOUT': '86400', 22:39:31 INFO - 'USER': 'cltbld', 22:39:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:31 INFO - '_': '/tools/buildbot/bin/python'} 22:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:31 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 22:39:31 INFO - Cleaning up... 22:39:31 INFO - Return code: 0 22:39:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:31 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 22:39:31 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 22:39:31 INFO - Using env: {'DISPLAY': ':0', 22:39:31 INFO - 'HOME': '/home/cltbld', 22:39:31 INFO - 'LANG': 'en_US.UTF-8', 22:39:31 INFO - 'LANGUAGE': 'en_US:en', 22:39:31 INFO - 'LOGNAME': 'cltbld', 22:39:31 INFO - 'MAIL': '/var/mail/cltbld', 22:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:31 INFO - 'MOZ_NO_REMOTE': '1', 22:39:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:31 INFO - 'NO_EM_RESTART': '1', 22:39:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:31 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:31 INFO - 'SHELL': '/bin/bash', 22:39:31 INFO - 'SHLVL': '1', 22:39:31 INFO - 'TERM': 'linux', 22:39:31 INFO - 'TMOUT': '86400', 22:39:31 INFO - 'USER': 'cltbld', 22:39:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:31 INFO - '_': '/tools/buildbot/bin/python'} 22:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:31 INFO - Downloading/unpacking psutil>=0.7.1 22:39:31 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 22:39:31 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 22:39:31 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 22:39:31 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 22:39:31 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 22:39:31 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 22:39:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:39:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:33 INFO - Installing collected packages: psutil 22:39:33 INFO - Running setup.py install for psutil 22:39:33 INFO - building 'psutil._psutil_linux' extension 22:39:33 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 22:39:33 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 22:39:33 INFO - building 'psutil._psutil_posix' extension 22:39:33 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 22:39:33 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 22:39:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:33 INFO - Successfully installed psutil 22:39:33 INFO - Cleaning up... 22:39:33 INFO - Return code: 0 22:39:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:39:33 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')]} 22:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:33 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')]} 22:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:33 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:33 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 22:39:33 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 22:39:33 INFO - Using env: {'DISPLAY': ':0', 22:39:33 INFO - 'HOME': '/home/cltbld', 22:39:33 INFO - 'LANG': 'en_US.UTF-8', 22:39:33 INFO - 'LANGUAGE': 'en_US:en', 22:39:33 INFO - 'LOGNAME': 'cltbld', 22:39:33 INFO - 'MAIL': '/var/mail/cltbld', 22:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:33 INFO - 'MOZ_NO_REMOTE': '1', 22:39:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:33 INFO - 'NO_EM_RESTART': '1', 22:39:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:33 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:33 INFO - 'SHELL': '/bin/bash', 22:39:33 INFO - 'SHLVL': '1', 22:39:33 INFO - 'TERM': 'linux', 22:39:33 INFO - 'TMOUT': '86400', 22:39:33 INFO - 'USER': 'cltbld', 22:39:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:33 INFO - '_': '/tools/buildbot/bin/python'} 22:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:39:34 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 22:39:34 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 22:39:34 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 22:39:34 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 22:39:34 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 22:39:34 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 22:39:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:39:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:39:35 INFO - Installing collected packages: mozsystemmonitor 22:39:35 INFO - Running setup.py install for mozsystemmonitor 22:39:36 INFO - Successfully installed mozsystemmonitor 22:39:36 INFO - Cleaning up... 22:39:36 INFO - Return code: 0 22:39:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:39:36 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')]} 22:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:36 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')]} 22:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:36 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:36 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 22:39:36 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 22:39:36 INFO - Using env: {'DISPLAY': ':0', 22:39:36 INFO - 'HOME': '/home/cltbld', 22:39:36 INFO - 'LANG': 'en_US.UTF-8', 22:39:36 INFO - 'LANGUAGE': 'en_US:en', 22:39:36 INFO - 'LOGNAME': 'cltbld', 22:39:36 INFO - 'MAIL': '/var/mail/cltbld', 22:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:36 INFO - 'MOZ_NO_REMOTE': '1', 22:39:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:36 INFO - 'NO_EM_RESTART': '1', 22:39:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:36 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:36 INFO - 'SHELL': '/bin/bash', 22:39:36 INFO - 'SHLVL': '1', 22:39:36 INFO - 'TERM': 'linux', 22:39:36 INFO - 'TMOUT': '86400', 22:39:36 INFO - 'USER': 'cltbld', 22:39:36 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:36 INFO - '_': '/tools/buildbot/bin/python'} 22:39:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:36 INFO - Downloading/unpacking blobuploader==1.2.4 22:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:38 INFO - Downloading blobuploader-1.2.4.tar.gz 22:39:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:39:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:39:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 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 22:39:38 INFO - Downloading docopt-0.6.1.tar.gz 22:39:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:39:38 INFO - Installing collected packages: blobuploader, requests, docopt 22:39:38 INFO - Running setup.py install for blobuploader 22:39:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:39:39 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:39:39 INFO - Running setup.py install for requests 22:39:39 INFO - Running setup.py install for docopt 22:39:39 INFO - Successfully installed blobuploader requests docopt 22:39:39 INFO - Cleaning up... 22:39:39 INFO - Return code: 0 22:39:39 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:39: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')]} 22:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:39 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:39 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 22:39:39 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 22:39:39 INFO - Using env: {'DISPLAY': ':0', 22:39:39 INFO - 'HOME': '/home/cltbld', 22:39:39 INFO - 'LANG': 'en_US.UTF-8', 22:39:39 INFO - 'LANGUAGE': 'en_US:en', 22:39:39 INFO - 'LOGNAME': 'cltbld', 22:39:39 INFO - 'MAIL': '/var/mail/cltbld', 22:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:39 INFO - 'MOZ_NO_REMOTE': '1', 22:39:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:39 INFO - 'NO_EM_RESTART': '1', 22:39:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:39 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:39 INFO - 'SHELL': '/bin/bash', 22:39:39 INFO - 'SHLVL': '1', 22:39:39 INFO - 'TERM': 'linux', 22:39:39 INFO - 'TMOUT': '86400', 22:39:39 INFO - 'USER': 'cltbld', 22:39:39 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:39 INFO - '_': '/tools/buildbot/bin/python'} 22:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:39:39 INFO - Running setup.py (path:/tmp/pip-GPHkvK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:39:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:39:39 INFO - Running setup.py (path:/tmp/pip-esEYPC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:39:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:39:39 INFO - Running setup.py (path:/tmp/pip-xqWZN1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:39:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:39:39 INFO - Running setup.py (path:/tmp/pip-shkTW5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:39:40 INFO - Running setup.py (path:/tmp/pip-InyBRT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:39:40 INFO - Running setup.py (path:/tmp/pip-nfzjws-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:39:40 INFO - Running setup.py (path:/tmp/pip-FDh8bu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:39:40 INFO - Running setup.py (path:/tmp/pip-zkEo7K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:39:40 INFO - Running setup.py (path:/tmp/pip-z2PW9z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:39:40 INFO - Running setup.py (path:/tmp/pip-UwUsQ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:39:40 INFO - Running setup.py (path:/tmp/pip-UpAyXy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:39:40 INFO - Running setup.py (path:/tmp/pip-2H2MOs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:39:40 INFO - Running setup.py (path:/tmp/pip-GGJzxR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:39:40 INFO - Running setup.py (path:/tmp/pip-74ICyR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:39:40 INFO - Running setup.py (path:/tmp/pip-5Qm8gG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:39:40 INFO - Running setup.py (path:/tmp/pip-DKnFBL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:39:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:39:40 INFO - Running setup.py (path:/tmp/pip-u4Pmzq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:39:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:39:40 INFO - Running setup.py install for manifestparser 22:39:41 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Running setup.py install for mozcrash 22:39:41 INFO - Running setup.py install for mozdebug 22:39:41 INFO - Running setup.py install for mozdevice 22:39:41 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Running setup.py install for mozfile 22:39:41 INFO - Running setup.py install for mozhttpd 22:39:41 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Running setup.py install for mozinfo 22:39:41 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Running setup.py install for mozInstall 22:39:41 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:39:41 INFO - Running setup.py install for mozleak 22:39:41 INFO - Running setup.py install for mozlog 22:39:41 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Running setup.py install for moznetwork 22:39:42 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Running setup.py install for mozprocess 22:39:42 INFO - Running setup.py install for mozprofile 22:39:42 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Running setup.py install for mozrunner 22:39:42 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Running setup.py install for mozscreenshot 22:39:42 INFO - Running setup.py install for moztest 22:39:42 INFO - Running setup.py install for mozversion 22:39:42 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:39:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:39:42 INFO - Cleaning up... 22:39:42 INFO - Return code: 0 22:39:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:42 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:42 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 22:39:42 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 22:39:42 INFO - Using env: {'DISPLAY': ':0', 22:39:42 INFO - 'HOME': '/home/cltbld', 22:39:42 INFO - 'LANG': 'en_US.UTF-8', 22:39:42 INFO - 'LANGUAGE': 'en_US:en', 22:39:42 INFO - 'LOGNAME': 'cltbld', 22:39:42 INFO - 'MAIL': '/var/mail/cltbld', 22:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:42 INFO - 'MOZ_NO_REMOTE': '1', 22:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:42 INFO - 'NO_EM_RESTART': '1', 22:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:42 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:42 INFO - 'SHELL': '/bin/bash', 22:39:42 INFO - 'SHLVL': '1', 22:39:42 INFO - 'TERM': 'linux', 22:39:42 INFO - 'TMOUT': '86400', 22:39:42 INFO - 'USER': 'cltbld', 22:39:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:42 INFO - '_': '/tools/buildbot/bin/python'} 22:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:39:42 INFO - Running setup.py (path:/tmp/pip-l5eq6S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:39:43 INFO - Running setup.py (path:/tmp/pip-Ng8btP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:39:43 INFO - Running setup.py (path:/tmp/pip-1DvSMK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:39:43 INFO - Running setup.py (path:/tmp/pip-sEPoE6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:39:43 INFO - Running setup.py (path:/tmp/pip-w8aM5O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:39:43 INFO - Running setup.py (path:/tmp/pip-ISS2F6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:39:43 INFO - Running setup.py (path:/tmp/pip-66DiFG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:39:43 INFO - Running setup.py (path:/tmp/pip-m5tPN9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:39:43 INFO - Running setup.py (path:/tmp/pip-cQDrKQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:39:43 INFO - Running setup.py (path:/tmp/pip-pO2VEJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:39:43 INFO - Running setup.py (path:/tmp/pip-_BA1b5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:39:43 INFO - Running setup.py (path:/tmp/pip-FrO5lL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:39:43 INFO - Running setup.py (path:/tmp/pip-yjT5Vc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:39:43 INFO - Running setup.py (path:/tmp/pip-1Z1m_0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:39:43 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)) 22:39:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:39:44 INFO - Running setup.py (path:/tmp/pip-jedKzI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:39:44 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)) 22:39:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:39:44 INFO - Running setup.py (path:/tmp/pip-QcZ3Ij-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:39:44 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)) 22:39:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:39:44 INFO - Running setup.py (path:/tmp/pip-NqUWHX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:39:44 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)) 22:39:44 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)) 22:39:44 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)) 22:39:44 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)) 22:39:44 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)) 22:39:44 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)) 22:39:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:39:44 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 22:39:44 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 22:39:44 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 22:39:44 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 22:39:44 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 22:39:44 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 22:39:46 INFO - Downloading blessings-1.6.tar.gz 22:39:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:39:46 INFO - Installing collected packages: blessings 22:39:46 INFO - Running setup.py install for blessings 22:39:46 INFO - Successfully installed blessings 22:39:46 INFO - Cleaning up... 22:39:46 INFO - Return code: 0 22:39:46 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:39:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:39:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:39:46 INFO - Reading from file tmpfile_stdout 22:39:46 INFO - Current package versions: 22:39:46 INFO - argparse == 1.2.1 22:39:46 INFO - blessings == 1.6 22:39:46 INFO - blobuploader == 1.2.4 22:39:46 INFO - docopt == 0.6.1 22:39:46 INFO - manifestparser == 1.1 22:39:46 INFO - mozInstall == 1.12 22:39:46 INFO - mozcrash == 0.16 22:39:46 INFO - mozdebug == 0.1 22:39:46 INFO - mozdevice == 0.47 22:39:46 INFO - mozfile == 1.2 22:39:46 INFO - mozhttpd == 0.7 22:39:46 INFO - mozinfo == 0.9 22:39:46 INFO - mozleak == 0.1 22:39:46 INFO - mozlog == 3.1 22:39:46 INFO - moznetwork == 0.27 22:39:46 INFO - mozprocess == 0.22 22:39:46 INFO - mozprofile == 0.27 22:39:46 INFO - mozrunner == 6.11 22:39:46 INFO - mozscreenshot == 0.1 22:39:46 INFO - mozsystemmonitor == 0.0 22:39:46 INFO - moztest == 0.7 22:39:46 INFO - mozversion == 1.4 22:39:46 INFO - psutil == 3.1.1 22:39:46 INFO - requests == 1.2.3 22:39:46 INFO - wsgiref == 0.1.2 22:39:46 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:46 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:46 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 22:39:46 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 22:39:46 INFO - Using env: {'DISPLAY': ':0', 22:39:46 INFO - 'HOME': '/home/cltbld', 22:39:46 INFO - 'LANG': 'en_US.UTF-8', 22:39:46 INFO - 'LANGUAGE': 'en_US:en', 22:39:46 INFO - 'LOGNAME': 'cltbld', 22:39:46 INFO - 'MAIL': '/var/mail/cltbld', 22:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:46 INFO - 'MOZ_NO_REMOTE': '1', 22:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:46 INFO - 'NO_EM_RESTART': '1', 22:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:46 INFO - 'SHELL': '/bin/bash', 22:39:46 INFO - 'SHLVL': '1', 22:39:46 INFO - 'TERM': 'linux', 22:39:46 INFO - 'TMOUT': '86400', 22:39:46 INFO - 'USER': 'cltbld', 22:39:46 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:46 INFO - '_': '/tools/buildbot/bin/python'} 22:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 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)) 22:39:46 INFO - Cleaning up... 22:39:46 INFO - Return code: 0 22:39:46 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:46 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:46 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 22:39:46 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 22:39:46 INFO - Using env: {'DISPLAY': ':0', 22:39:46 INFO - 'HOME': '/home/cltbld', 22:39:46 INFO - 'LANG': 'en_US.UTF-8', 22:39:46 INFO - 'LANGUAGE': 'en_US:en', 22:39:46 INFO - 'LOGNAME': 'cltbld', 22:39:46 INFO - 'MAIL': '/var/mail/cltbld', 22:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:46 INFO - 'MOZ_NO_REMOTE': '1', 22:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:46 INFO - 'NO_EM_RESTART': '1', 22:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:46 INFO - 'SHELL': '/bin/bash', 22:39:46 INFO - 'SHLVL': '1', 22:39:46 INFO - 'TERM': 'linux', 22:39:46 INFO - 'TMOUT': '86400', 22:39:46 INFO - 'USER': 'cltbld', 22:39:46 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:46 INFO - '_': '/tools/buildbot/bin/python'} 22:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:46 INFO - Downloading/unpacking jsonschema 22:39:46 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 22:39:46 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 22:39:46 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 22:39:46 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 22:39:46 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 22:39:46 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 22:39:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:39:48 INFO - Downloading/unpacking functools32 (from jsonschema) 22:39:48 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 22:39:48 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 22:39:48 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 22:39:48 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 22:39:48 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 22:39:48 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 22:39:48 INFO - Downloading functools32-3.2.3-2.tar.gz 22:39:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:39:49 INFO - warning: no files found matching '*.txt' 22:39:49 INFO - no previously-included directories found matching 'build' 22:39:49 INFO - no previously-included directories found matching 'dist' 22:39:49 INFO - no previously-included directories found matching '.git*' 22:39:49 INFO - Installing collected packages: jsonschema, functools32 22:39:49 INFO - Running setup.py install for functools32 22:39:49 INFO - warning: no files found matching '*.txt' 22:39:49 INFO - no previously-included directories found matching 'build' 22:39:49 INFO - no previously-included directories found matching 'dist' 22:39:49 INFO - no previously-included directories found matching '.git*' 22:39:49 INFO - Successfully installed jsonschema functools32 22:39:49 INFO - Cleaning up... 22:39:49 INFO - Return code: 0 22:39:49 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:39:49 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')]} 22:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:49 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')]} 22:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:49 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 0x7fcadf01bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf41600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', '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 22:39:49 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 22:39:49 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 22:39:49 INFO - Using env: {'DISPLAY': ':0', 22:39:49 INFO - 'HOME': '/home/cltbld', 22:39:49 INFO - 'LANG': 'en_US.UTF-8', 22:39:49 INFO - 'LANGUAGE': 'en_US:en', 22:39:49 INFO - 'LOGNAME': 'cltbld', 22:39:49 INFO - 'MAIL': '/var/mail/cltbld', 22:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:49 INFO - 'MOZ_NO_REMOTE': '1', 22:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:49 INFO - 'NO_EM_RESTART': '1', 22:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:39:49 INFO - 'PWD': '/builds/slave/test-pgo', 22:39:49 INFO - 'SHELL': '/bin/bash', 22:39:49 INFO - 'SHLVL': '1', 22:39:49 INFO - 'TERM': 'linux', 22:39:49 INFO - 'TMOUT': '86400', 22:39:49 INFO - 'USER': 'cltbld', 22:39:49 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:49 INFO - '_': '/tools/buildbot/bin/python'} 22:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:49 INFO - Downloading/unpacking flake8 22:39:49 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 22:39:49 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 22:39:49 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 22:39:49 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 22:39:49 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 22:39:49 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 22:39:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:39:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:39:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:39:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:39:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:39:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:39:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:39:51 INFO - no previously-included directories found matching 'docs/_build' 22:39:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:39:52 INFO - Running setup.py install for pep8 22:39:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:39:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:39:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:39:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:39:52 INFO - no previously-included directories found matching 'docs/_build' 22:39:52 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:39:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:39:52 INFO - Cleaning up... 22:39:52 INFO - Return code: 0 22:39:52 INFO - Running post-action listener: _resource_record_post_action 22:39:52 INFO - Running post-action listener: _start_resource_monitoring 22:39:52 INFO - Starting resource monitoring. 22:39:52 INFO - ##### 22:39:52 INFO - ##### Running install step. 22:39:52 INFO - ##### 22:39:52 INFO - Running pre-action listener: _resource_record_pre_action 22:39:52 INFO - Running main action method: install 22:39:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:39:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:39:52 INFO - Reading from file tmpfile_stdout 22:39:52 INFO - Detecting whether we're running mozinstall >=1.0... 22:39:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:39:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:39:52 INFO - Reading from file tmpfile_stdout 22:39:52 INFO - Output received: 22:39:52 INFO - Usage: mozinstall [options] installer 22:39:52 INFO - Options: 22:39:52 INFO - -h, --help show this help message and exit 22:39:52 INFO - -d DEST, --destination=DEST 22:39:52 INFO - Directory to install application into. [default: 22:39:52 INFO - "/builds/slave/test-pgo"] 22:39:52 INFO - --app=APP Application being installed. [default: firefox] 22:39:52 INFO - mkdir: /builds/slave/test-pgo/build/application 22:39:52 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'] 22:39:52 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 22:40:07 INFO - Reading from file tmpfile_stdout 22:40:07 INFO - Output received: 22:40:07 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:40:07 INFO - Running post-action listener: _resource_record_post_action 22:40:07 INFO - ##### 22:40:07 INFO - ##### Running run-tests step. 22:40:07 INFO - ##### 22:40:07 INFO - Running pre-action listener: _resource_record_pre_action 22:40:07 INFO - Running main action method: run_tests 22:40:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:40:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:40:07 INFO - Python 2.7.3 22:40:07 INFO - Return code: 0 22:40:07 INFO - grabbing minidump binary from tooltool 22:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:07 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 0xfd0350>, '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 0xfd1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd16e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:40:07 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 22:40:07 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 22:40:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:40:07 INFO - Return code: 0 22:40:07 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:40:07 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:40:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:40:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:40:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:40:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:40:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:40:08 INFO - Return code: 0 22:40:08 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/1456460484/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 22:40:08 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/1456460484/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost 22:40:08 INFO - Using env: {'DISPLAY': ':0', 22:40:08 INFO - 'HOME': '/home/cltbld', 22:40:08 INFO - 'LANG': 'en_US.UTF-8', 22:40:08 INFO - 'LANGUAGE': 'en_US:en', 22:40:08 INFO - 'LOGNAME': 'cltbld', 22:40:08 INFO - 'MAIL': '/var/mail/cltbld', 22:40:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:40:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:08 INFO - 'MOZ_NO_REMOTE': '1', 22:40:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:08 INFO - 'NO_EM_RESTART': '1', 22:40:08 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:40:08 INFO - 'PWD': '/builds/slave/test-pgo', 22:40:08 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:40:08 INFO - 'SHELL': '/bin/bash', 22:40:08 INFO - 'SHLVL': '1', 22:40:08 INFO - 'TERM': 'linux', 22:40:08 INFO - 'TMOUT': '86400', 22:40:08 INFO - 'USER': 'cltbld', 22:40:08 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409', 22:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:08 INFO - '_': '/tools/buildbot/bin/python'} 22:40:08 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/1456460484/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] with output_timeout 3600 22:40:08 INFO - mozversion INFO | application_buildid: 20160225202124 22:40:08 INFO - mozversion INFO | application_changeset: 54424f42b7579179c5da184db66279c5d15929ba 22:40:08 INFO - mozversion INFO | application_display_name: Nightly 22:40:08 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:40:08 INFO - mozversion INFO | application_name: Firefox 22:40:08 INFO - mozversion INFO | application_remotingname: firefox 22:40:08 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:40:08 INFO - mozversion INFO | application_vendor: Mozilla 22:40:08 INFO - mozversion INFO | application_version: 45.0 22:40:08 INFO - mozversion INFO | platform_buildid: 20160225202124 22:40:08 INFO - mozversion INFO | platform_changeset: 54424f42b7579179c5da184db66279c5d15929ba 22:40:08 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:40:08 INFO - mozversion INFO | platform_version: 45.0 22:40:08 INFO - 2016-02-25 22:40:08,478 DEBUG : using testdate: 1456468808 22:40:08 INFO - 2016-02-25 22:40:08,478 DEBUG : actual date: 1456468808 22:40:08 INFO - 2016-02-25 22:40:08,563 INFO : starting webserver on 'localhost:54716' 22:40:08 INFO - 2016-02-25 22:40:08,564 INFO : Starting test suite talos-linux64-ix-019 22:40:08 INFO - 2016-02-25 22:40:08,564 INFO : Starting test dromaeo_css 22:40:08 INFO - 2016-02-25 22:40:08,564 DEBUG : operating with platform_type : linux_ 22:40:08 INFO - 2016-02-25 22:40:08,565 INFO : Initialising browser for dromaeo_css test... 22:40:08 INFO - 2016-02-25 22:40:08,580 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpa8S0eU/profile http://localhost:54716/getInfo.html 22:40:12 INFO - 2016-02-25 22:40:12,536 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:40:12 INFO - 2016-02-25 22:40:12,536 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:40:12 INFO - 2016-02-25 22:40:12,536 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:40:12 INFO - 2016-02-25 22:40:12,536 DEBUG : BROWSER_OUTPUT: __metrics 22:40:12 INFO - 2016-02-25 22:40:12,714 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] 22:40:13 INFO - 2016-02-25 22:40:13,911 INFO : Browser initialized. 22:40:13 INFO - 2016-02-25 22:40:13,912 INFO : Running cycle 1/1 for dromaeo_css test... 22:40:13 INFO - 2016-02-25 22:40:13,912 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpa8S0eU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:40:25 INFO - 2016-02-25 22:40:25,596 DEBUG : BROWSER_OUTPUT: RSS: Main: 134340608 22:40:25 INFO - 2016-02-25 22:40:25,596 DEBUG : BROWSER_OUTPUT: 22:41:16 INFO - 2016-02-25 22:41:16,352 DEBUG : BROWSER_OUTPUT: 1456468876348 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] 22:43:24 INFO - 2016-02-25 22:43:24,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54716/tests/dromaeo/cssquery-ext.html) 22:43:25 INFO - 2016-02-25 22:43:25,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 238411776 22:43:25 INFO - 2016-02-25 22:43:25,634 DEBUG : BROWSER_OUTPUT: 22:46:27 INFO - 2016-02-25 22:46:27,633 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-ext.html (next: http://localhost:54716/tests/dromaeo/cssquery-jquery.html) 22:46:28 INFO - 2016-02-25 22:46:28,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 214491136 22:46:28 INFO - 2016-02-25 22:46:28,194 DEBUG : BROWSER_OUTPUT: 22:49:29 INFO - 2016-02-25 22:49:29,870 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54716/tests/dromaeo/cssquery-mootools.html) 22:49:30 INFO - 2016-02-25 22:49:30,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 210944000 22:49:30 INFO - 2016-02-25 22:49:30,432 DEBUG : BROWSER_OUTPUT: 22:52:32 INFO - 2016-02-25 22:52:32,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54716/tests/dromaeo/cssquery-prototype.html) 22:52:32 INFO - 2016-02-25 22:52:32,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 202072064 22:52:32 INFO - 2016-02-25 22:52:32,682 DEBUG : BROWSER_OUTPUT: 22:55:35 INFO - 2016-02-25 22:55:35,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54716/tests/dromaeo/cssquery-yui.html) 22:55:35 INFO - 2016-02-25 22:55:35,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 197926912 22:55:35 INFO - 2016-02-25 22:55:35,699 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/dromaeo/cssquery-yui.html (next: http://localhost:54716/tests/dromaeo/cssquery-dojo.html) 22:58:38 INFO - 2016-02-25 22:58:38,307 DEBUG : BROWSER_OUTPUT: RSS: Main: 236486656 22:58:38 INFO - 2016-02-25 22:58:38,307 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,307 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:58:38 INFO - 2016-02-25 22:58:38,307 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:58:38 INFO - 2016-02-25 22:58:38,307 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:58:38 INFO - 2016-02-25 22:58:38,308 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:58:38 INFO - 2016-02-25 22:58:38,308 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3280.1143691203374;3333.316670082633;3359.4960755886614;3361.3949489091983;3391.643877392873;5460.290162278903;5465.2895123633925;5487.944433940671;5501.037318469272;5508.421615730345;5391.406945236021;5456.672599644025;5461;5473.917891231625;5475.589330800186;4872.512748725135;4942.505749425065;4983.202687569989;4994.825181118662;5014.473480284572;4888.682235654671;4972.254161875711;4980.950190498085;5010.549050585447;5047.066292735837;4963.677360971544;5127.359080114986;5135.512126347991;5142.151544995071;5144.4598317176715;5034.84895453137;5095.312132862054;5107.233914912756;5108.6168537359845;5118.6416950813455;4800.135975524404;4911.950880491221;4918.01639672067;5003.574696150831;5041.798328066873;5346.171343441734;5355.518003379714;5356.758945847446;5358.517733404013;5359.624826262163;5881.588288819784;5882.941170588325;5884.617499862496;5887.705614719247;5889.941100589025;5665.943340566583;5843.065109582489;5844.649321040709;5852.239208902858;5881.911771323434;5450.809221677222;5479.2602998595075;5486.451354864522;5490.478404551562;5491.28613280279;5778.88442231153;5893.941060589339;5897.705114744245;5942.673152976541;5948.315943666447;5668.234788303526;5729;5762.9135562966585;5764.596478246566;5797.420257974169;5908.527317814564;5919.3192782829665;5925.05199168131;5926.911096333559;5927.436893495111;5707.828765137053;5821.010428227211;5865.677387743715;5870.442307980735;5870.73581688825;5699.943000570024;5785.82642520725;5801.187833703285;5801.709914504342;5806.187133801272;4525.389072475174;4544.272916333371;4571.3600095986585;4576.519465456079;4595.770211489412;3964.108075683006;4001.819918103634;4063.776492292893;4113.115680128787;4144.316187829035;5502.449755024546;5515;5520.613557051048;5584.134459158837;5616.326040875121;5857;5861.20873682056;5861.882762344814;5862.736176871966;5869.090291004901;5385.434529374359;5387.434319396485;5505.587080968943;5507.118860982304;5511.421300763443;5229.790808367623;5246.108161612535;5246.449122842123;5256.448072952361;5262.105442074857;6143.078538219213;6148.754049837957;6154.415330543591;6156.230471191101;6159;5731.684757338387;5813.941860581509;5828.329742079715;5923.052311630118;6018.759249630053;6005;6106.786262480792;6111.755529778759;6116.5718399711595;6123.6631985239455;5862.091375836752;5867.32525759526;5902.1146827976145;5922.615030023035;5937.317208521075;5768.307803063659;5880.882382352243;5882.5293976482635;5960.582759206987;6007.339192688713;5838.32859221195;5838.824835254949;5842.795502157404;5846.532277417711;5847.853803654943;5606.635568688022;5831.679257640701;5836.883262334644;5839.299284085937;5843.795467158629;5532.28080349552;5633.887322253614;5635.098384258499;5638.37977822431;5651.095824668033;5782.5952183348445;5807.8548036299435;5815.912761308499;5865.794697185578;5872.882542349043;5566.387697353369;5686.914696279642;5755.7122143893475;5769.9711501442225;5780.768769249183 22:58:38 INFO - 2016-02-25 22:58:38,309 DEBUG : BROWSER_OUTPUT: |1;ext.html;582.7465052702075;628.9622622642643;636.3509220594993;642.5277421095496;643.5462998585997;33148.66851331486;33608;42816.357754633704;43251;43332.13335733284;46280;46653.53346466534;46748.766256168754;46788.766056169756;46906;8201.507909525448;9170.495622740747;9294.86057709135;9305.20905723011;9308.627654893797;8329.416940814146;8447.619857106414;8448.23965843074;10836.133109351233;11152.386618735965;10930;10999.560017599286;11021.559137634486;11036.944815275952;11038.448077596127;10879.728006799814;10971.451427428598;10983.780324393547;10987.835182472269;10996.780064398707;10766.138708903269;10820;10902.945485272603;10913.126949843994;10925.344479331265;43294.56705432937;43410;43478;43717.12565748699;43952.78023609862;3379.797212167278;3462.151772815651;3749.362608356586;4058.208649313385;4300.569943005706;31954.360912781845;32158.356832863446;32551.51172732411;32622.347553049043;32855.6714432855;4194.2450358935375;4196.118815048813;4254.510731265913;4318.2227199104145;4334.154839806239;305133;306868;307418;307545;308612.4569377161;57862.7106864463;59233;63234;63672.408189794696;63763.6811815938;75332;76342;76456.61771691106;76499;76688;18235.270589176285;18536.165872535767;18540.07299635013;18564.81435185631;18570.535736606424;13753.656158595915;13766.105203161762;13778;13785.931070344785;13786.93106534481;20794.896025519774;20990.79009209919;21015.579688406146;21022.894885525475;21039.158433662484;7573.659185336673;7664.425168112414;7735.342495887912;7782.805429864298;7883.251091146332;1030.057497389895;1049.9370037797758;1089.3899416326883;1099.6810924831868;1104.0615476844778;29557.85221073881;29620;30022.099337019925;30726.38547229043;30804.537931931038;1112.7329440934118;1118.6755840806238;1131.2307630810963;1147.5639257082255;1158.6118650252092;13541.932290338682;13614.591562253148;14012.859871401155;14411.207383594003;14982.850171498363;342566;342787;343445;343912;345032;58066.709666451396;58308;58338.7083064582;58387.70806145942;58405.41594584085;1349.1770020287618;1400.7408629403592;1430.370636919752;1465.8387577366695;1605.9759103613455;27492.862535687193;28658.28354291076;29172.56241156342;29188.613834300613;29670.40659186761;44823.77588112038;45236.32145517754;45377.31934021058;45379.54620453753;45496.54503454923;229523;230822;230956;230966;231418.5861636264;34117.829410852784;34164.82917585396;34298;34442.13894652654;34640.960771176906;45915.77042114768;45946.77026614846;46345.304820427045;46381.53618463772;46594.301085484425;33089.172770680925;34508.82745586256;37654.058648534;37736.81131594325;38289.80855095707;37736.30102804693;37770.43344349892;37914.62085379111;38182.23635527218;38485.230295393376;36912.26175476529;37264.813675931444;37625.62374376221;37658.43512347372;37740.2451950965;32555.67444325621;33183.63721645065;33403.665963340056;33545.16137096592;33620.495692564145;22263.554728905656;22265.220717274817;22327.44181395478;22400.551988960455;22495.437614059778 22:58:38 INFO - 2016-02-25 22:58:38,312 DEBUG : BROWSER_OUTPUT: |2;jquery.html;383.8560539797576;384.17439730780103;384.3351002765216;385.42379143180943;392.99214015719684;15254.694906101871;16529.586760330996;17434.73847892281;19174.616507669856;19181.32865349713;16543.42098026569;16807.243674034667;17138.742918856227;17988.73016904744;18012.189451474682;5999.850003749909;6131.3562075982045;6148.415900489469;6153.230846144232;6157.7536898524;4570.085982803436;4733.5739783419485;6162.661053642048;6627.271000189975;6836.521443498957;5108.4125325587665;6811.25076241613;6835.213950395693;6840.521163518556;6873.381395674388;5226.320578324793;5233.136532472137;5237.240600112981;5258.999999999981;5259.105951988152;5052.873678158038;5071.56891664209;5305.0450918834595;5309;5309.52214300711;12767.106302558825;12811.807822882663;12951;13264.204147751167;13418.26199559024;2141.0793358855685;2144.1423430627874;2151.5589304192604;2174.195547647365;2282.691836602054;10013.59945602175;13902.930485347508;13951.302434878215;13954.162750234917;13971.58085257444;1869.457857221404;1911.1017821623814;1948.9512762180916;1978.0505357428515;1986.3445063129134;63621.681891590706;65589.67205163991;65995;66630.3336966629;68393.31606683969;10355.896441035644;10587;10732.463376831283;10744.892551074545;10801.83797243042;13990.930045349709;14084.788728169086;14124.435022599186;14124.717505649829;14126.43494260218;8353;8391.706290279812;8398.076211616717;8404.28563572091;8413.411061225777;8265.710700125586;8279.58602069894;8309.667613295522;8358.623861926228;8363.456375335583;4176.14389050244;4531.592156705912;4577.8397756078375;4578.313253012075;4585.2663573828695;1800.666876627802;1812.510622132017;1812.9365472208674;1826.1234607388528;1846.8060853610177;974.2595627323285;992.2954702161546;994.4033579852176;1011.9595216191417;1066.6106870992032;14323.641908952359;14596.927015365067;15903.204839757966;16471.176441177893;16689.24898379576;957.2724729205854;967.5210770668424;974.0794948773482;1036.5698235232444;1037.1650821088945;9302.906970930204;9497.952510237405;9567;9575.138237558653;9667.323287369958;611884;620842;650175;656089;658499;85304;87090;87296.563517182;87839.56080219558;89637;1210.8789121087998;1218.4943248551751;1230.2003697596633;1236.8453943257093;1251.5869762978195;14509.347079381665;14798.334074966868;14974.625634359229;14975.251237438302;15186.468473603813;35198.296034079685;35217;35574;35678.286434271686;36159.819200903825;50624.49375506198;50639;51999.74000129975;54063.45936540584;55190.44809551853;9944.1547468466;10061.044200800912;10120.848187277345;10160.644377446753;10209.234307427121;16568;18112.81887181164;18296.26814927388;18312;18784.718229226844;14261.42954281817;15399;15413.075215487106;15702;15878.60303492422;14906.105633662015;14952.626184345043;15100.622484437978;15257.618559535656;15265.084094954338;15053.322600482785;15463.458778942682;15476.767848482057;15737.213139343216;15839.604009899846;9311.534423278945;9391.966883642657;9411.152996230372;9551.14039736427;9581.712548623553;10308.12380947598;10463.319884207502;11848.76302473963;15657.921710391374;15916.363345466052 22:58:38 INFO - 2016-02-25 22:58:38,313 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1537.7462718651423;1543.6063803730046;1759.2786957347473;1827.9725804112936;1845.9723104153436;8252.79368015799;8518.616662250199;8527.701530446435;8529.999999999993;8543.188397102273;7294.927050729491;7462.96268518658;7920.564368959705;8212.384071194669;8310.83378332433;2303.781140791622;2304.3432621702777;2321.2804030750435;2335.8598484090926;2336.2523992322463;2083.7916208379115;2322.268485427085;2336.2640768157976;2397.9160729374476;2407.6388541718707;2084.0413409831494;2246.269962262263;2290.061074959267;2291.598970180212;2299.494111295512;2265.4676151104477;2297.529006553661;2302.539492101578;2339.02930283932;2340.8127349811975;2323.848949818256;2327.371609665398;2353.870537120458;2381.3332266965276;2382.809375249976;8367.163283671645;8390.496570205747;8460.492370457792;8463.703770368007;8464.407491475598;4744.264638980963;4816.566509014206;4855.150348688965;4855.878603034917;4860.878478038042;3109.8911538096286;3118.5322201669705;3148.795328303676;3212.2932954749917;3242.400155971153;1006.6879267427121;1040.1938497664296;1050.2385770316537;1068.086785798147;1099.0218705352243;11371.886281137165;11389.715257118556;11475.081993440588;11533.653990380302;11535.826962595567;4302.548232435612;4324.437823082979;4386.758728269976;4414.668899832525;4415.028693687384;9864.704058878246;9916.950415248022;9919.752006199758;9936.80126397468;9938.602455901828;4909.7790599423115;4914.582260507826;4917.459079501252;4919.1883339248625;4920.507949205051;4458.866234012985;4477.462704475483;4486.932696009562;4487.820487180542;4495.730256184574;3832.827522761482;3851.480050193244;3856.093817952779;3913.2760439318818;3916.4712763776524;1109.040679811973;1118.0384869012642;1225.6200577820905;1228.9324087175291;1242.236024844714;1145.6158922052637;1163.7963356412595;1224.3205021213312;1394.8814350780094;1416.482983710938;7795.805104872424;7918.485298455582;8067;8200.630971606291;8323.542205178774;1088.3633074651418;1138.7950168969664;1191.9046476281876;1191.9880801191878;1223.6573759347398;6340.936590634127;6392.7123279452535;6447.1296374989715;6454.677266136674;6459.450946669492;10110.191184705205;10226.6420675276;10231.48842557884;10243.7439064024;10381.9480902595;9961.900380996241;10082.143017843418;10207.030332118436;10242.385456872611;10251.436171010517;7869.960650196713;7884.093329267208;7896.68413263463;7970.521768693897;7976.760697179094;7629.351505122127;7712.190220027041;7741.7677469676;7752.7674169775;7791.454598178299;8753.868691969497;9150.130737579919;9180.403273787182;9190.632374705205;9203.447793132433;9180.17378435944;9184.173424391836;9408.52957352116;9450.291228157777;9570.33007689455;7133.322334378226;7203.135623725187;7211.062561866924;7212.2427145149;7216.061911951413;7539.9246007541415;7722.227777222233;7738;7751.806204844924;7754.612269386621;6675.33246675348;7469.066366704162;7493.175750667313;7507.662155203138;7575.015248017722;7074.327938845801;7741;7782.88325675104;7828.334591559781;7836.333911617577;7850.568218748023;7899.368050556057;7900.130985591695;7911.8813217800625;7913.44605877583;7157.534760240567;7169.103862017248;7206.783796486114;7234.5659260444545;7260.419166466564;7345.70617175307;7399.519031262951;7403.962980185064;7407.074115735533;7409.333160015624 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.206552536015;340.2684228907848;345.94118999770035;362.57759709937926;369.156477449029;5974.79088231912;6039.42625450582;6133.754649814002;6167.753289868406;6182.103594978731;5917.674527900975;6058.061000544912;6091.329953705089;6096.695165241742;6098.664573448458;4671.392718946532;4678.76606169691;4686.062787442508;4686.554777296168;4692.22578274586;4480.6639502037315;4567.885802854922;4572.9314060289125;4677.228257337535;4703.082898834728;4661.533846615345;4695.577398034176;4702.24764037754;4704.506026867181;4722.598579120779;4684.1334353144775;4686.062787442508;4686.507916668769;4701.811927522895;4703.412073490814;4143.08852052548;4144.979275103605;4148.149629325981;4153.1070819773595;4155.563665815076;5688.857778555528;5788.971055144697;5802.82591522255;5803.390643982363;5818.912716309259;5459.535939445152;5475.835724928259;5481.068218402881;5482.0406428874785;5484.533814625762;6209.285932117819;6433.2601750798785;6446.516511261674;6449.484041276686;6456.2575303840185;4473.642108631302;4503.392042074311;4586.082783443291;4589.862304130881;4592.770361481913;6621;6648.16897887764;6651.201855777289;6694.397504224643;6701.29636388182;4684.320773487825;4692;4692.0381321828945;4694.920168361227;4699.271612900006;4649.3026046093355;4686.484486706459;4687.67186296956;4697.4832768395445;4753.287006948916;5386.542143917811;5389.75746091419;5400;5402.297701298885;5405.486478784509;6487.059376390397;6492.740290388331;6497.64262965532;6501.219853617502;6506.219253689587;1671.1978250439856;1685.1489997551403;1685.6628674265196;1686.7722857414337;1696.3638635511684;1076.7684947736275;1085.3596378136938;1086.750047489082;1089.215764649451;1152.590830255264;6221.595596286227;6287.811365659038;6293.3391993840905;6298.527610429236;6299.275583307886;6673.899891501631;6674.199096108401;6681.699323530453;6687.130673012574;6687.966560167265;6281.560290779602;6284.748610055638;6288.52836037299;6294.8111556653375;6296.653684047421;3565;3660.359437098497;3662.230931504361;3662.377395842713;3662.6886714629554;6915.51591388608;6917.169939607279;6921.411680007155;6923.653817309215;6924.203716572659;6840.828979275558;6915.377616014583;6930.410915072174;6933.445324374038;6937.306269373023;6779.694913728894;6919.8962015569805;6929.44564434844;6976.616286104211;7007.544509606859;6553.246376666744;6662.467002639874;6712.2616512184595;6807.115075040411;6815.216250131299;6654.301298363602;6691.197056353269;6698.39714425704;6701.262861085179;6702.430293425114;6924.58452492852;6940.7917762467205;6948.33990770876;6949;6961.6519174042105;4621.884452888705;4709.434867815884;4770.045990801784;4772.570468657837;4784.880377990578;6714.563553369016;6715.630640314635;6727;6728.495362847708;6730.764423245163;6572.849751293409;6578.8355291118105;6581.506387021088;6583.374579414948;6584.572002819801;6471.061696053952;6534.313897040743;6567.9014814776865;6571.638559879252;6572.934270657423;6422.710978006095;6433;6433.967830160819;6435.1312572803945;6439.871202575829;6561.179852518435;6561.442277406283;6569.638669873012;6570.178727659043;6573.276939536743;6268.498520118288;6383.36166383358;6413.134226879311;6436.935630643634;6504.959206526933 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: |5;yui.html;725.7713820146654;765.578931587627;919.4345477531317;920.479928840205;935.4948327902932;13805.240711760862;16261;16273.593160171002;16289.83710162898;16357.672846543062;15446.922765386158;15580.532584022469;15589.922050389761;15592.142432166227;15609.375624974988;792.053496072194;798.4889670610813;915.2997956563217;936.489613160829;936.7985883035146;907.4509921497498;907.8320510705507;918.6463211663491;921.6866265469739;925.2459245714755;983.1053741095604;988.8071825993934;989.8812142542869;990.5889056041734;991.1872264742877;818.3862103422433;831.7255305749088;937.5874615169304;967.2503809547586;976.1263669016226;721.4336745654689;721.5346101764385;721.7546034348346;721.7726416178939;722.003634983719;13444.86555134446;13504.257265850374;13553.796693049611;13618.591442256748;13626.659333516642;12248.326342051183;12550.309732964683;12655.557055503104;12682.936585317108;13024.674383140497;10768.138548916068;10836.729081772939;11668.708282292926;11762.588309409215;11950.581729639422;3905.8437662493466;3976.204759048202;3990.6408423242046;3990.8004599769897;4119.176164767028;48101;48230.27654585184;48640.75679621615;48681;48761;10975.231733778719;11059.889401105886;11114.388708620942;11218.363684534128;11455.312681239153;19667.311644092388;19753.40739777809;19787.505312367306;19789.802101978796;19792;7744.186860379579;7786.299233069051;7788.337991270692;7828.334591559667;8074.354051675852;4184.895377615584;4256.787160642018;4258.6805989550285;4313.439252897103;4314.072474417952;3431.1422144463886;3449.3273811606496;3455.809930453799;3459.584849818038;3462.9307413852084;599.340725202274;609.6494515653517;620.1503939602773;621.285521650106;622.4771192198575;648.1379764912655;650.7331993882485;685.2154283345542;697.9651017449107;705.7670968580356;8108.75673729789;8363.95818020906;8389.874151887727;8476.237382405778;8741.125887411335;588.717415640495;591.630231105559;639.2233436374827;675.8580698053464;716.8494616130566;3816.3702989007056;3820.7707537547835;3891.9805400972814;3957.2679054374457;4046.615571520701;55806.32581022576;56210.71894640501;56469;56883.71558142183;63323.36676633175;51779.741101294254;52457.2131418029;52551;52993.2051019235;53600.19599706007;47600.523994759606;49405.50594494009;49467.0106597866;49854;49894.25158622624;9169;9233.169014788702;9490.525473726424;9665.951670241604;9752.414855108716;35378.11554711153;36063.819680901426;36726.694597486356;37082.88751337464;37229;45138.322925156805;45877.54122458733;45888.31167532423;46412.30381544212;46748.29877551772;11642.126840487166;11976.221545599508;12037.152072037936;12110.939445302716;12166.330851803235;12780.297083660487;12908.225506469642;12927.612171634866;13112.475500979854;13127.540536081191;11954.222975506564;12362.814557781461;12654.87345126537;12774.61676149717;12972.35138243103;12054.698632533906;12125.333106679218;12727.681807954876;12785.296808675612;13346.3326833656;10748.516316765608;11656.708582285512;11945.283283003047;12185.086118540967;12300.507979680713;10380.480975951323;10866;10889.346639201673;10898.782024359627;11020.793101899839;9993.350432221881;10138;10169.627100341359;10725.463726813472;10959.232853700165 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,768 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: __startTimestamp1456469918304__endTimestamp 22:58:38 INFO - 2016-02-25 22:58:38,314 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,315 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:58:38 INFO - 2016-02-25 22:58:38,315 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5469.50 Median:5665.94 stddev:592.30 (10.5%) stddev-sans-first:568.66 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: Values: 3280.1 3333.3 3359.5 3361.4 3391.6 5460.3 5465.3 5487.9 5501.0 5508.4 5391.4 5456.7 5461.0 5473.9 5475.6 4872.5 4942.5 4983.2 4994.8 5014.5 4888.7 4972.3 4981.0 5010.5 5047.1 4963.7 5127.4 5135.5 5142.2 5144.5 5034.8 5095.3 5107.2 5108.6 5118.6 4800.1 4912.0 4918.0 5003.6 5041.8 5346.2 5355.5 5356.8 5358.5 5359.6 5881.6 5882.9 5884.6 5887.7 5889.9 5665.9 5843.1 5844.6 5852.2 5881.9 5450.8 5479.3 5486.5 5490.5 5491.3 5778.9 5893.9 5897.7 5942.7 5948.3 5668.2 5729.0 5762.9 5764.6 5797.4 5908.5 5919.3 5925.1 5926.9 5927.4 5707.8 5821.0 5865.7 5870.4 5870.7 5699.9 5785.8 5801.2 5801.7 5806.2 4525.4 4544.3 4571.4 4576.5 4595.8 3964.1 4001.8 4063.8 4113.1 4144.3 5502.4 5515.0 5520.6 5584.1 5616.3 5857.0 5861.2 5861.9 5862.7 5869.1 5385.4 5387.4 5505.6 5507.1 5511.4 5229.8 5246.1 5246.4 5256.4 5262.1 6143.1 6148.8 6154.4 6156.2 6159.0 5731.7 5813.9 5828.3 5923.1 6018.8 6005.0 6106.8 6111.8 6116.6 6123.7 5862.1 5867.3 5902.1 5922.6 5937.3 5768.3 5880.9 5882.5 5960.6 6007.3 5838.3 5838.8 5842.8 5846.5 5847.9 5606.6 5831.7 5836.9 5839.3 5843.8 5532.3 5633.9 5635.1 5638.4 5651.1 5782.6 5807.9 5815.9 5865.8 5872.9 5566.4 5686.9 5755.7 5770.0 5780.8 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48064.19 Median:18748.81 stddev:77819.80 (415.1%) stddev-sans-first:77956.44 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: Values: 582.7 629.0 636.4 642.5 643.5 33148.7 33608.0 42816.4 43251.0 43332.1 46280.0 46653.5 46748.8 46788.8 46906.0 8201.5 9170.5 9294.9 9305.2 9308.6 8329.4 8447.6 8448.2 10836.1 11152.4 10930.0 10999.6 11021.6 11036.9 11038.4 10879.7 10971.5 10983.8 10987.8 10996.8 10766.1 10820.0 10902.9 10913.1 10925.3 43294.6 43410.0 43478.0 43717.1 43952.8 3379.8 3462.2 3749.4 4058.2 4300.6 31954.4 32158.4 32551.5 32622.3 32855.7 4194.2 4196.1 4254.5 4318.2 4334.2 305133.0 306868.0 307418.0 307545.0 308612.5 57862.7 59233.0 63234.0 63672.4 63763.7 75332.0 76342.0 76456.6 76499.0 76688.0 18235.3 18536.2 18540.1 18564.8 18570.5 13753.7 13766.1 13778.0 13785.9 13786.9 20794.9 20990.8 21015.6 21022.9 21039.2 7573.7 7664.4 7735.3 7782.8 7883.3 1030.1 1049.9 1089.4 1099.7 1104.1 29557.9 29620.0 30022.1 30726.4 30804.5 1112.7 1118.7 1131.2 1147.6 1158.6 13541.9 13614.6 14012.9 14411.2 14982.9 342566.0 342787.0 343445.0 343912.0 345032.0 58066.7 58308.0 58338.7 58387.7 58405.4 1349.2 1400.7 1430.4 1465.8 1606.0 27492.9 28658.3 29172.6 29188.6 29670.4 44823.8 45236.3 45377.3 45379.5 45496.5 229523.0 230822.0 230956.0 230966.0 231418.6 34117.8 34164.8 34298.0 34442.1 34641.0 45915.8 45946.8 46345.3 46381.5 46594.3 33089.2 34508.8 37654.1 37736.8 38289.8 37736.3 37770.4 37914.6 38182.2 38485.2 36912.3 37264.8 37625.6 37658.4 37740.2 32555.7 33183.6 33403.7 33545.2 33620.5 22263.6 22265.2 22327.4 22400.6 22495.4 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,316 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32298.60 Median:2162.88 stddev:104556.68 (4834.1%) stddev-sans-first:104822.52 22:58:38 INFO - 2016-02-25 22:58:38,317 DEBUG : BROWSER_OUTPUT: Values: 383.9 384.2 384.3 385.4 393.0 15254.7 16529.6 17434.7 19174.6 19181.3 16543.4 16807.2 17138.7 17988.7 18012.2 5999.9 6131.4 6148.4 6153.2 6157.8 4570.1 4733.6 6162.7 6627.3 6836.5 5108.4 6811.3 6835.2 6840.5 6873.4 5226.3 5233.1 5237.2 5259.0 5259.1 5052.9 5071.6 5305.0 5309.0 5309.5 12767.1 12811.8 12951.0 13264.2 13418.3 2141.1 2144.1 2151.6 2174.2 2282.7 10013.6 13902.9 13951.3 13954.2 13971.6 1869.5 1911.1 1949.0 1978.1 1986.3 63621.7 65589.7 65995.0 66630.3 68393.3 10355.9 10587.0 10732.5 10744.9 10801.8 13990.9 14084.8 14124.4 14124.7 14126.4 8353.0 8391.7 8398.1 8404.3 8413.4 8265.7 8279.6 8309.7 8358.6 8363.5 4176.1 4531.6 4577.8 4578.3 4585.3 1800.7 1812.5 1812.9 1826.1 1846.8 974.3 992.3 994.4 1012.0 1066.6 14323.6 14596.9 15903.2 16471.2 16689.2 957.3 967.5 974.1 1036.6 1037.2 9302.9 9498.0 9567.0 9575.1 9667.3 611884.0 620842.0 650175.0 656089.0 658499.0 85304.0 87090.0 87296.6 87839.6 89637.0 1210.9 1218.5 1230.2 1236.8 1251.6 14509.3 14798.3 14974.6 14975.3 15186.5 35198.3 35217.0 35574.0 35678.3 36159.8 50624.5 50639.0 51999.7 54063.5 55190.4 9944.2 10061.0 10120.8 10160.6 10209.2 16568.0 18112.8 18296.3 18312.0 18784.7 14261.4 15399.0 15413.1 15702.0 15878.6 14906.1 14952.6 15100.6 15257.6 15265.1 15053.3 15463.5 15476.8 15737.2 15839.6 9311.5 9392.0 9411.2 9551.1 9581.7 10308.1 10463.3 11848.8 15657.9 15916.4 22:58:38 INFO - 2016-02-25 22:58:38,317 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,317 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5861.66 Median:4916.02 stddev:3097.84 (63.0%) stddev-sans-first:3089.49 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: Values: 1537.7 1543.6 1759.3 1828.0 1846.0 8252.8 8518.6 8527.7 8530.0 8543.2 7294.9 7463.0 7920.6 8212.4 8310.8 2303.8 2304.3 2321.3 2335.9 2336.3 2083.8 2322.3 2336.3 2397.9 2407.6 2084.0 2246.3 2290.1 2291.6 2299.5 2265.5 2297.5 2302.5 2339.0 2340.8 2323.8 2327.4 2353.9 2381.3 2382.8 8367.2 8390.5 8460.5 8463.7 8464.4 4744.3 4816.6 4855.2 4855.9 4860.9 3109.9 3118.5 3148.8 3212.3 3242.4 1006.7 1040.2 1050.2 1068.1 1099.0 11371.9 11389.7 11475.1 11533.7 11535.8 4302.5 4324.4 4386.8 4414.7 4415.0 9864.7 9917.0 9919.8 9936.8 9938.6 4909.8 4914.6 4917.5 4919.2 4920.5 4458.9 4477.5 4486.9 4487.8 4495.7 3832.8 3851.5 3856.1 3913.3 3916.5 1109.0 1118.0 1225.6 1228.9 1242.2 1145.6 1163.8 1224.3 1394.9 1416.5 7795.8 7918.5 8067.0 8200.6 8323.5 1088.4 1138.8 1191.9 1192.0 1223.7 6340.9 6392.7 6447.1 6454.7 6459.5 10110.2 10226.6 10231.5 10243.7 10381.9 9961.9 10082.1 10207.0 10242.4 10251.4 7870.0 7884.1 7896.7 7970.5 7976.8 7629.4 7712.2 7741.8 7752.8 7791.5 8753.9 9150.1 9180.4 9190.6 9203.4 9180.2 9184.2 9408.5 9450.3 9570.3 7133.3 7203.1 7211.1 7212.2 7216.1 7539.9 7722.2 7738.0 7751.8 7754.6 6675.3 7469.1 7493.2 7507.7 7575.0 7074.3 7741.0 7782.9 7828.3 7836.3 7850.6 7899.4 7900.1 7911.9 7913.4 7157.5 7169.1 7206.8 7234.6 7260.4 7345.7 7399.5 7404.0 7407.1 7409.3 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5450.83 Median:6215.44 stddev:1632.23 (26.3%) stddev-sans-first:1591.03 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: Values: 336.2 340.3 345.9 362.6 369.2 5974.8 6039.4 6133.8 6167.8 6182.1 5917.7 6058.1 6091.3 6096.7 6098.7 4671.4 4678.8 4686.1 4686.6 4692.2 4480.7 4567.9 4572.9 4677.2 4703.1 4661.5 4695.6 4702.2 4704.5 4722.6 4684.1 4686.1 4686.5 4701.8 4703.4 4143.1 4145.0 4148.1 4153.1 4155.6 5688.9 5789.0 5802.8 5803.4 5818.9 5459.5 5475.8 5481.1 5482.0 5484.5 6209.3 6433.3 6446.5 6449.5 6456.3 4473.6 4503.4 4586.1 4589.9 4592.8 6621.0 6648.2 6651.2 6694.4 6701.3 4684.3 4692.0 4692.0 4694.9 4699.3 4649.3 4686.5 4687.7 4697.5 4753.3 5386.5 5389.8 5400.0 5402.3 5405.5 6487.1 6492.7 6497.6 6501.2 6506.2 1671.2 1685.1 1685.7 1686.8 1696.4 1076.8 1085.4 1086.8 1089.2 1152.6 6221.6 6287.8 6293.3 6298.5 6299.3 6673.9 6674.2 6681.7 6687.1 6688.0 6281.6 6284.7 6288.5 6294.8 6296.7 3565.0 3660.4 3662.2 3662.4 3662.7 6915.5 6917.2 6921.4 6923.7 6924.2 6840.8 6915.4 6930.4 6933.4 6937.3 6779.7 6919.9 6929.4 6976.6 7007.5 6553.2 6662.5 6712.3 6807.1 6815.2 6654.3 6691.2 6698.4 6701.3 6702.4 6924.6 6940.8 6948.3 6949.0 6961.7 4621.9 4709.4 4770.0 4772.6 4784.9 6714.6 6715.6 6727.0 6728.5 6730.8 6572.8 6578.8 6581.5 6583.4 6584.6 6471.1 6534.3 6567.9 6571.6 6572.9 6422.7 6433.0 6434.0 6435.1 6439.9 6561.2 6561.4 6569.6 6570.2 6573.3 6268.5 6383.4 6413.1 6436.9 6505.0 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,318 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14503.85 Median:4257.73 stddev:16318.40 (383.3%) stddev-sans-first:16331.38 22:58:38 INFO - 2016-02-25 22:58:38,319 DEBUG : BROWSER_OUTPUT: Values: 725.8 765.6 919.4 920.5 935.5 13805.2 16261.0 16273.6 16289.8 16357.7 15446.9 15580.5 15589.9 15592.1 15609.4 792.1 798.5 915.3 936.5 936.8 907.5 907.8 918.6 921.7 925.2 983.1 988.8 989.9 990.6 991.2 818.4 831.7 937.6 967.3 976.1 721.4 721.5 721.8 721.8 722.0 13444.9 13504.3 13553.8 13618.6 13626.7 12248.3 12550.3 12655.6 12682.9 13024.7 10768.1 10836.7 11668.7 11762.6 11950.6 3905.8 3976.2 3990.6 3990.8 4119.2 48101.0 48230.3 48640.8 48681.0 48761.0 10975.2 11059.9 11114.4 11218.4 11455.3 19667.3 19753.4 19787.5 19789.8 19792.0 7744.2 7786.3 7788.3 7828.3 8074.4 4184.9 4256.8 4258.7 4313.4 4314.1 3431.1 3449.3 3455.8 3459.6 3462.9 599.3 609.6 620.2 621.3 622.5 648.1 650.7 685.2 698.0 705.8 8108.8 8364.0 8389.9 8476.2 8741.1 588.7 591.6 639.2 675.9 716.8 3816.4 3820.8 3892.0 3957.3 4046.6 55806.3 56210.7 56469.0 56883.7 63323.4 51779.7 52457.2 52551.0 52993.2 53600.2 47600.5 49405.5 49467.0 49854.0 49894.3 9169.0 9233.2 9490.5 9666.0 9752.4 35378.1 36063.8 36726.7 37082.9 37229.0 45138.3 45877.5 45888.3 46412.3 46748.3 11642.1 11976.2 12037.2 12110.9 12166.3 12780.3 12908.2 12927.6 13112.5 13127.5 11954.2 12362.8 12654.9 12774.6 12972.4 12054.7 12125.3 12727.7 12785.3 13346.3 10748.5 11656.7 11945.3 12185.1 12300.5 10380.5 10866.0 10889.3 10898.8 11020.8 9993.4 10138.0 10169.6 10725.5 10959.2 22:58:38 INFO - 2016-02-25 22:58:38,319 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:58:38 INFO - 2016-02-25 22:58:38,319 DEBUG : BROWSER_OUTPUT: 22:58:38 INFO - 2016-02-25 22:58:38,895 INFO : Browser exited with error code: 0 22:58:38 INFO - 2016-02-25 22:58:38,919 INFO : Completed test dromaeo_css (00:18:30) 22:58:38 INFO - 2016-02-25 22:58:38,919 INFO : Starting test kraken 22:58:38 INFO - 2016-02-25 22:58:38,919 DEBUG : operating with platform_type : linux_ 22:58:38 INFO - 2016-02-25 22:58:38,919 INFO : Initialising browser for kraken test... 22:58:38 INFO - 2016-02-25 22:58:38,926 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjNVYzd/profile http://localhost:54716/getInfo.html 22:58:43 INFO - 2016-02-25 22:58:43,184 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:58:43 INFO - 2016-02-25 22:58:43,185 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:58:43 INFO - 2016-02-25 22:58:43,185 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:58:43 INFO - 2016-02-25 22:58:43,185 DEBUG : BROWSER_OUTPUT: __metrics 22:58:43 INFO - 2016-02-25 22:58:43,314 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] 22:58:44 INFO - 2016-02-25 22:58:44,151 INFO : Browser initialized. 22:58:44 INFO - 2016-02-25 22:58:44,152 INFO : Running cycle 1/1 for kraken test... 22:58:44 INFO - 2016-02-25 22:58:44,152 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjNVYzd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:58:55 INFO - 2016-02-25 22:58:55,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 133894144 22:58:55 INFO - 2016-02-25 22:58:55,816 DEBUG : BROWSER_OUTPUT: 22:58:58 INFO - 2016-02-25 22:58:58,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54716/tests/kraken/driver.html?testName=audio-beat-detection) 22:58:58 INFO - 2016-02-25 22:58:58,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 166014976 22:58:58 INFO - 2016-02-25 22:58:58,853 DEBUG : BROWSER_OUTPUT: 22:59:01 INFO - 2016-02-25 22:59:01,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54716/tests/kraken/driver.html?testName=audio-dft) 22:59:02 INFO - 2016-02-25 22:59:02,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 163962880 22:59:02 INFO - 2016-02-25 22:59:02,023 DEBUG : BROWSER_OUTPUT: 22:59:04 INFO - 2016-02-25 22:59:04,847 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54716/tests/kraken/driver.html?testName=audio-fft) 22:59:05 INFO - 2016-02-25 22:59:05,411 DEBUG : BROWSER_OUTPUT: RSS: Main: 161312768 22:59:05 INFO - 2016-02-25 22:59:05,411 DEBUG : BROWSER_OUTPUT: 22:59:07 INFO - 2016-02-25 22:59:07,396 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54716/tests/kraken/driver.html?testName=audio-oscillator) 22:59:07 INFO - 2016-02-25 22:59:07,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 161169408 22:59:07 INFO - 2016-02-25 22:59:07,958 DEBUG : BROWSER_OUTPUT: 22:59:10 INFO - 2016-02-25 22:59:10,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54716/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:59:10 INFO - 2016-02-25 22:59:10,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 159264768 22:59:10 INFO - 2016-02-25 22:59:10,714 DEBUG : BROWSER_OUTPUT: 22:59:13 INFO - 2016-02-25 22:59:13,544 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54716/tests/kraken/driver.html?testName=imaging-darkroom) 22:59:14 INFO - 2016-02-25 22:59:14,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 160358400 22:59:14 INFO - 2016-02-25 22:59:14,118 DEBUG : BROWSER_OUTPUT: 22:59:17 INFO - 2016-02-25 22:59:17,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54716/tests/kraken/driver.html?testName=imaging-desaturate) 22:59:18 INFO - 2016-02-25 22:59:18,027 DEBUG : BROWSER_OUTPUT: RSS: Main: 161640448 22:59:18 INFO - 2016-02-25 22:59:18,027 DEBUG : BROWSER_OUTPUT: 22:59:20 INFO - 2016-02-25 22:59:20,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54716/tests/kraken/driver.html?testName=json-parse-financial) 22:59:21 INFO - 2016-02-25 22:59:21,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 160038912 22:59:21 INFO - 2016-02-25 22:59:21,272 DEBUG : BROWSER_OUTPUT: 22:59:22 INFO - 2016-02-25 22:59:22,805 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54716/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:59:23 INFO - 2016-02-25 22:59:23,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 169574400 22:59:23 INFO - 2016-02-25 22:59:23,358 DEBUG : BROWSER_OUTPUT: 22:59:25 INFO - 2016-02-25 22:59:25,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:59:25 INFO - 2016-02-25 22:59:25,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 166293504 22:59:25 INFO - 2016-02-25 22:59:25,743 DEBUG : BROWSER_OUTPUT: 22:59:27 INFO - 2016-02-25 22:59:27,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:59:28 INFO - 2016-02-25 22:59:28,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 171143168 22:59:28 INFO - 2016-02-25 22:59:28,291 DEBUG : BROWSER_OUTPUT: 22:59:30 INFO - 2016-02-25 22:59:30,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:59:31 INFO - 2016-02-25 22:59:31,219 DEBUG : BROWSER_OUTPUT: RSS: Main: 177172480 22:59:31 INFO - 2016-02-25 22:59:31,220 DEBUG : BROWSER_OUTPUT: 22:59:33 INFO - 2016-02-25 22:59:33,722 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:59:34 INFO - 2016-02-25 22:59:34,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 175624192 22:59:34 INFO - 2016-02-25 22:59:34,288 DEBUG : BROWSER_OUTPUT: 22:59:35 INFO - 2016-02-25 22:59:35,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54716/tests/kraken/driver.html?testName=ai-astar) 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 232407040 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:59:36 INFO - 2016-02-25 22:59:36,208 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;104;112;109;109;98;111;110;111 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;132;160;155;127;151;151;122;163;120 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;160;161;158;161;160;162;161;160 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;73;153;73;71;73;73;161;71 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;92;134;92;92;93;139;92;94;147 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;108;109;110;110;110;109;110;109;110;109 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;146;146;146;146;145;146 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;95;96;95;96;95;95 22:59:36 INFO - 2016-02-25 22:59:36,209 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;56;57;58;58;57;56;58;53 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;65;65;64;68;65;64;65;65 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;77;79;79;77;86;79;78;79 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;119;115;118;113;115;119;113;114;112 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;146;147;147;144;144;136;146;145;145;144 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;68;64;64;69;64;63;64;63;64 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:59:36 INFO - 2016-02-25 22:59:36,210 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:59:36 INFO - 2016-02-25 22:59:36,211 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,878 22:59:36 INFO - 2016-02-25 22:59:36,211 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:59:36 INFO - 2016-02-25 22:59:36,211 DEBUG : BROWSER_OUTPUT: __startTimestamp1456469976206__endTimestamp 22:59:36 INFO - 2016-02-25 22:59:36,211 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,212 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:59:36 INFO - 2016-02-25 22:59:36,212 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:59:36 INFO - 2016-02-25 22:59:36,212 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.40 Median:111.00 stddev:4.27 (3.9%) stddev-sans-first:4.53 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 104.0 112.0 109.0 109.0 98.0 111.0 110.0 111.0 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.70 Median:151.00 stddev:16.04 (10.6%) stddev-sans-first:16.97 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: Values: 146.0 132.0 160.0 155.0 127.0 151.0 151.0 122.0 163.0 120.0 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 160.0 161.0 158.0 161.0 160.0 162.0 161.0 160.0 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.10 Median:72.50 stddev:35.85 (49.4%) stddev-sans-first:37.42 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 73.0 153.0 73.0 71.0 73.0 73.0 161.0 71.0 22:59:36 INFO - 2016-02-25 22:59:36,213 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:92.00 stddev:23.13 (25.1%) stddev-sans-first:23.98 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 134.0 92.0 92.0 93.0 139.0 92.0 94.0 147.0 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:110.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 110.0 110.0 110.0 109.0 110.0 109.0 110.0 109.0 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 146.0 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,214 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.64 (2.9%) stddev-sans-first:1.67 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 56.0 57.0 58.0 58.0 57.0 56.0 58.0 53.0 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 65.0 64.0 68.0 65.0 64.0 65.0 65.0 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:2.64 (3.3%) stddev-sans-first:2.78 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 77.0 79.0 79.0 77.0 86.0 79.0 78.0 79.0 22:59:36 INFO - 2016-02-25 22:59:36,215 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:116.50 stddev:3.51 (3.0%) stddev-sans-first:2.69 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: Values: 123.0 119.0 115.0 118.0 113.0 115.0 119.0 113.0 114.0 112.0 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.40 Median:145.50 stddev:3.17 (2.2%) stddev-sans-first:3.31 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 147.0 144.0 144.0 136.0 146.0 145.0 145.0 144.0 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:64.00 stddev:2.08 (3.2%) stddev-sans-first:2.17 22:59:36 INFO - 2016-02-25 22:59:36,216 DEBUG : BROWSER_OUTPUT: Values: 66.0 68.0 64.0 64.0 69.0 64.0 63.0 64.0 63.0 64.0 22:59:36 INFO - 2016-02-25 22:59:36,217 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:59:36 INFO - 2016-02-25 22:59:36,217 DEBUG : BROWSER_OUTPUT: 22:59:36 INFO - 2016-02-25 22:59:36,895 INFO : Browser exited with error code: 0 22:59:36 INFO - 2016-02-25 22:59:36,947 INFO : Completed test kraken (00:00:58) 22:59:36 INFO - 2016-02-25 22:59:36,947 INFO : Starting test v8_7 22:59:36 INFO - 2016-02-25 22:59:36,947 DEBUG : operating with platform_type : linux_ 22:59:36 INFO - 2016-02-25 22:59:36,948 INFO : Initialising browser for v8_7 test... 22:59:36 INFO - 2016-02-25 22:59:36,954 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRiw4eB/profile http://localhost:54716/getInfo.html 22:59:40 INFO - 2016-02-25 22:59:40,823 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:59:40 INFO - 2016-02-25 22:59:40,823 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:59:40 INFO - 2016-02-25 22:59:40,823 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:59:40 INFO - 2016-02-25 22:59:40,823 DEBUG : BROWSER_OUTPUT: __metrics 22:59:40 INFO - 2016-02-25 22:59:40,992 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] 22:59:42 INFO - 2016-02-25 22:59:42,183 INFO : Browser initialized. 22:59:42 INFO - 2016-02-25 22:59:42,184 INFO : Running cycle 1/1 for v8_7 test... 22:59:42 INFO - 2016-02-25 22:59:42,184 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRiw4eB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:59:53 INFO - 2016-02-25 22:59:53,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 134459392 22:59:53 INFO - 2016-02-25 22:59:53,879 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54716/tests/v8_7/run.html (next: http://localhost:54716/tests/v8_7/run.html) 23:00:15 INFO - 2016-02-25 23:00:15,463 DEBUG : BROWSER_OUTPUT: RSS: Main: 411926528 23:00:15 INFO - 2016-02-25 23:00:15,463 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,465 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:00:15 INFO - 2016-02-25 23:00:15,465 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:00:15 INFO - 2016-02-25 23:00:15,465 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:00:15 INFO - 2016-02-25 23:00:15,465 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:00:15 INFO - 2016-02-25 23:00:15,465 DEBUG : BROWSER_OUTPUT: |0;Richards;172.68174753928508 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.05713308244952 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.54648903691367 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |4;RayTrace;985.2216748768473 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |5;Earley;557.1030640668523 23:00:15 INFO - 2016-02-25 23:00:15,466 DEBUG : BROWSER_OUTPUT: |6;Boyer;10600 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: |7;RegExp;24023.809523809523 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: |8;Splay;494.12915851272015 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: __startTimestamp1456470015463__endTimestamp 23:00:15 INFO - 2016-02-25 23:00:15,467 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: Number of tests: 10 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:172.68 Median:172.68 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: Values: 172.7 23:00:15 INFO - 2016-02-25 23:00:15,468 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,469 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.06 Median:171.06 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,469 DEBUG : BROWSER_OUTPUT: Values: 171.1 23:00:15 INFO - 2016-02-25 23:00:15,469 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,470 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.55 Median:277.55 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,470 DEBUG : BROWSER_OUTPUT: Values: 277.5 23:00:15 INFO - 2016-02-25 23:00:15,470 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,470 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,470 DEBUG : BROWSER_OUTPUT: Values: 5170.1 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:985.22 Median:985.22 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: Values: 985.2 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:557.10 Median:557.10 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: Values: 557.1 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10600.00 Median:10600.00 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,471 DEBUG : BROWSER_OUTPUT: Values: 10600.0 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24023.81 Median:24023.81 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: Values: 24023.8 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:494.13 Median:494.13 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: Values: 494.1 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: 23:00:15 INFO - 2016-02-25 23:00:15,472 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 23:00:15 INFO - 2016-02-25 23:00:15,473 DEBUG : BROWSER_OUTPUT: Values: 4845.4 23:00:15 INFO - 2016-02-25 23:00:15,473 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:00:15 INFO - 2016-02-25 23:00:15,473 DEBUG : BROWSER_OUTPUT: 23:00:16 INFO - 2016-02-25 23:00:16,151 INFO : Browser exited with error code: 0 23:00:16 INFO - 2016-02-25 23:00:16,166 INFO : Completed test v8_7 (00:00:39) 23:00:16 INFO - 2016-02-25 23:00:16,526 INFO : Completed test suite (00:20:07) 23:00:16 INFO - 2016-02-25 23:00:16,526 DEBUG : Working with test: dromaeo_css 23:00:16 INFO - 2016-02-25 23:00:16,526 DEBUG : Generating results file: dromaeo_css 23:00:16 INFO - 2016-02-25 23:00:16,527 DEBUG : Working with test: kraken 23:00:16 INFO - 2016-02-25 23:00:16,528 DEBUG : Generating results file: kraken 23:00:16 INFO - 2016-02-25 23:00:16,528 INFO : javascript benchmark 23:00:16 INFO - 2016-02-25 23:00:16,528 DEBUG : Working with test: v8_7 23:00:16 INFO - 2016-02-25 23:00:16,528 DEBUG : Generating results file: v8_7 23:00:16 INFO - 2016-02-25 23:00:16,529 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:00:16 INFO - 2016-02-25 23:00:16,593 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 23:00:16 INFO - 2016-02-25 23:00:16,594 DEBUG : process_Request line: dromaeo_css 7355.74 graph.html#tests=[[72,53,35]] 23:00:16 INFO - 2016-02-25 23:00:16,594 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:00:16 INFO - 2016-02-25 23:00:16,704 DEBUG : process_Request line: kraken 1483.60 graph.html#tests=[[232,53,35]] 23:00:16 INFO - 2016-02-25 23:00:16,704 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:00:16 INFO - 2016-02-25 23:00:16,733 DEBUG : process_Request line: v8_7 23131.02 graph.html#tests=[[230,53,35]] 23:00:16 INFO - 2016-02-25 23:00:16,734 INFO : javascript benchmark 23:00:16 INFO - 2016-02-25 23:00:16,738 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3280.1143691203374, 3333.316670082633, 3359.4960755886614, 3361.3949489091983, 3391.643877392873, 5460.290162278903, 5465.2895123633925, 5487.944433940671, 5501.037318469272, 5508.421615730345, 5391.406945236021, 5456.672599644025, 5461.0, 5473.917891231625, 5475.589330800186, 4872.512748725135, 4942.505749425065, 4983.202687569989, 4994.825181118662, 5014.473480284572, 4888.682235654671, 4972.254161875711, 4980.950190498085, 5010.549050585447, 5047.066292735837, 4963.677360971544, 5127.359080114986, 5135.512126347991, 5142.151544995071, 5144.4598317176715, 5034.84895453137, 5095.312132862054, 5107.233914912756, 5108.6168537359845, 5118.6416950813455, 4800.135975524404, 4911.950880491221, 4918.01639672067, 5003.574696150831, 5041.798328066873, 5346.171343441734, 5355.518003379714, 5356.758945847446, 5358.517733404013, 5359.624826262163, 5881.588288819784, 5882.941170588325, 5884.617499862496, 5887.705614719247, 5889.941100589025, 5665.943340566583, 5843.065109582489, 5844.649321040709, 5852.239208902858, 5881.911771323434, 5450.809221677222, 5479.2602998595075, 5486.451354864522, 5490.478404551562, 5491.28613280279, 5778.88442231153, 5893.941060589339, 5897.705114744245, 5942.673152976541, 5948.315943666447, 5668.234788303526, 5729.0, 5762.9135562966585, 5764.596478246566, 5797.420257974169, 5908.527317814564, 5919.3192782829665, 5925.05199168131, 5926.911096333559, 5927.436893495111, 5707.828765137053, 5821.010428227211, 5865.677387743715, 5870.442307980735, 5870.73581688825, 5699.943000570024, 5785.82642520725, 5801.187833703285, 5801.709914504342, 5806.187133801272, 4525.389072475174, 4544.272916333371, 4571.3600095986585, 4576.519465456079, 4595.770211489412, 3964.108075683006, 4001.819918103634, 4063.776492292893, 4113.115680128787, 4144.316187829035, 5502.449755024546, 5515.0, 5520.613557051048, 5584.134459158837, 5616.326040875121, 5857.0, 5861.20873682056, 5861.882762344814, 5862.736176871966, 5869.090291004901, 5385.434529374359, 5387.434319396485, 5505.587080968943, 5507.118860982304, 5511.421300763443, 5229.790808367623, 5246.108161612535, 5246.449122842123, 5256.448072952361, 5262.105442074857, 6143.078538219213, 6148.754049837957, 6154.415330543591, 6156.230471191101, 6159.0, 5731.684757338387, 5813.941860581509, 5828.329742079715, 5923.052311630118, 6018.759249630053, 6005.0, 6106.786262480792, 6111.755529778759, 6116.5718399711595, 6123.6631985239455, 5862.091375836752, 5867.32525759526, 5902.1146827976145, 5922.615030023035, 5937.317208521075, 5768.307803063659, 5880.882382352243, 5882.5293976482635, 5960.582759206987, 6007.339192688713, 5838.32859221195, 5838.824835254949, 5842.795502157404, 5846.532277417711, 5847.853803654943, 5606.635568688022, 5831.679257640701, 5836.883262334644, 5839.299284085937, 5843.795467158629, 5532.28080349552, 5633.887322253614, 5635.098384258499, 5638.37977822431, 5651.095824668033, 5782.5952183348445, 5807.8548036299435, 5815.912761308499, 5865.794697185578, 5872.882542349043, 5566.387697353369, 5686.914696279642, 5755.7122143893475, 5769.9711501442225, 5780.768769249183], "unit": "ms", "name": "dojo.html", "value": 5432.161500759014}, {"lowerIsBetter": false, "replicates": [582.7465052702075, 628.9622622642643, 636.3509220594993, 642.5277421095496, 643.5462998585997, 33148.66851331486, 33608.0, 42816.357754633704, 43251.0, 43332.13335733284, 46280.0, 46653.53346466534, 46748.766256168754, 46788.766056169756, 46906.0, 8201.507909525448, 9170.495622740747, 9294.86057709135, 9305.20905723011, 9308.627654893797, 8329.416940814146, 8447.619857106414, 8448.23965843074, 10836.133109351233, 11152.386618735965, 10930.0, 10999.560017599286, 11021.559137634486, 11036.944815275952, 11038.448077596127, 10879.728006799814, 10971.451427428598, 10983.780324393547, 10987.835182472269, 10996.780064398707, 10766.138708903269, 10820.0, 10902.945485272603, 10913.126949843994, 10925.344479331265, 43294.56705432937, 43410.0, 43478.0, 43717.12565748699, 43952.78023609862, 3379.797212167278, 3462.151772815651, 3749.362608356586, 4058.208649313385, 4300.569943005706, 31954.360912781845, 32158.356832863446, 32551.51172732411, 32622.347553049043, 32855.6714432855, 4194.2450358935375, 4196.118815048813, 4254.510731265913, 4318.2227199104145, 4334.154839806239, 305133.0, 306868.0, 307418.0, 307545.0, 308612.4569377161, 57862.7106864463, 59233.0, 63234.0, 63672.408189794696, 63763.6811815938, 75332.0, 76342.0, 76456.61771691106, 76499.0, 76688.0, 18235.270589176285, 18536.165872535767, 18540.07299635013, 18564.81435185631, 18570.535736606424, 13753.656158595915, 13766.105203161762, 13778.0, 13785.931070344785, 13786.93106534481, 20794.896025519774, 20990.79009209919, 21015.579688406146, 21022.894885525475, 21039.158433662484, 7573.659185336673, 7664.425168112414, 7735.342495887912, 7782.805429864298, 7883.251091146332, 1030.057497389895, 1049.9370037797758, 1089.3899416326883, 1099.6810924831868, 1104.0615476844778, 29557.85221073881, 29620.0, 30022.099337019925, 30726.38547229043, 30804.537931931038, 1112.7329440934118, 1118.6755840806238, 1131.2307630810963, 1147.5639257082255, 1158.6118650252092, 13541.932290338682, 13614.591562253148, 14012.859871401155, 14411.207383594003, 14982.850171498363, 342566.0, 342787.0, 343445.0, 343912.0, 345032.0, 58066.709666451396, 58308.0, 58338.7083064582, 58387.70806145942, 58405.41594584085, 1349.1770020287618, 1400.7408629403592, 1430.370636919752, 1465.8387577366695, 1605.9759103613455, 27492.862535687193, 28658.28354291076, 29172.56241156342, 29188.613834300613, 29670.40659186761, 44823.77588112038, 45236.32145517754, 45377.31934021058, 45379.54620453753, 45496.54503454923, 229523.0, 230822.0, 230956.0, 230966.0, 231418.5861636264, 34117.829410852784, 34164.82917585396, 34298.0, 34442.13894652654, 34640.960771176906, 45915.77042114768, 45946.77026614846, 46345.304820427045, 46381.53618463772, 46594.301085484425, 33089.172770680925, 34508.82745586256, 37654.058648534, 37736.81131594325, 38289.80855095707, 37736.30102804693, 37770.43344349892, 37914.62085379111, 38182.23635527218, 38485.230295393376, 36912.26175476529, 37264.813675931444, 37625.62374376221, 37658.43512347372, 37740.2451950965, 32555.67444325621, 33183.63721645065, 33403.665963340056, 33545.16137096592, 33620.495692564145, 22263.554728905656, 22265.220717274817, 22327.44181395478, 22400.551988960455, 22495.437614059778], "unit": "ms", "name": "ext.html", "value": 19806.654945432438}, {"lowerIsBetter": false, "replicates": [383.8560539797576, 384.17439730780103, 384.3351002765216, 385.42379143180943, 392.99214015719684, 15254.694906101871, 16529.586760330996, 17434.73847892281, 19174.616507669856, 19181.32865349713, 16543.42098026569, 16807.243674034667, 17138.742918856227, 17988.73016904744, 18012.189451474682, 5999.850003749909, 6131.3562075982045, 6148.415900489469, 6153.230846144232, 6157.7536898524, 4570.085982803436, 4733.5739783419485, 6162.661053642048, 6627.271000189975, 6836.521443498957, 5108.4125325587665, 6811.25076241613, 6835.213950395693, 6840.521163518556, 6873.381395674388, 5226.320578324793, 5233.136532472137, 5237.240600112981, 5258.999999999981, 5259.105951988152, 5052.873678158038, 5071.56891664209, 5305.0450918834595, 5309.0, 5309.52214300711, 12767.106302558825, 12811.807822882663, 12951.0, 13264.204147751167, 13418.26199559024, 2141.0793358855685, 2144.1423430627874, 2151.5589304192604, 2174.195547647365, 2282.691836602054, 10013.59945602175, 13902.930485347508, 13951.302434878215, 13954.162750234917, 13971.58085257444, 1869.457857221404, 1911.1017821623814, 1948.9512762180916, 1978.0505357428515, 1986.3445063129134, 63621.681891590706, 65589.67205163991, 65995.0, 66630.3336966629, 68393.31606683969, 10355.896441035644, 10587.0, 10732.463376831283, 10744.892551074545, 10801.83797243042, 13990.930045349709, 14084.788728169086, 14124.435022599186, 14124.717505649829, 14126.43494260218, 8353.0, 8391.706290279812, 8398.076211616717, 8404.28563572091, 8413.411061225777, 8265.710700125586, 8279.58602069894, 8309.667613295522, 8358.623861926228, 8363.456375335583, 4176.14389050244, 4531.592156705912, 4577.8397756078375, 4578.313253012075, 4585.2663573828695, 1800.666876627802, 1812.510622132017, 1812.9365472208674, 1826.1234607388528, 1846.8060853610177, 974.2595627323285, 992.2954702161546, 994.4033579852176, 1011.9595216191417, 1066.6106870992032, 14323.641908952359, 14596.927015365067, 15903.204839757966, 16471.176441177893, 16689.24898379576, 957.2724729205854, 967.5210770668424, 974.0794948773482, 1036.5698235232444, 1037.1650821088945, 9302.906970930204, 9497.952510237405, 9567.0, 9575.138237558653, 9667.323287369958, 611884.0, 620842.0, 650175.0, 656089.0, 658499.0, 85304.0, 87090.0, 87296.563517182, 87839.56080219558, 89637.0, 1210.8789121087998, 1218.4943248551751, 1230.2003697596633, 1236.8453943257093, 1251.5869762978195, 14509.347079381665, 14798.334074966868, 14974.625634359229, 14975.251237438302, 15186.468473603813, 35198.296034079685, 35217.0, 35574.0, 35678.286434271686, 36159.819200903825, 50624.49375506198, 50639.0, 51999.74000129975, 54063.45936540584, 55190.44809551853, 9944.1547468466, 10061.044200800912, 10120.848187277345, 10160.644377446753, 10209.234307427121, 16568.0, 18112.81887181164, 18296.26814927388, 18312.0, 18784.718229226844, 14261.42954281817, 15399.0, 15413.075215487106, 15702.0, 15878.60303492422, 14906.105633662015, 14952.626184345043, 15100.622484437978, 15257.618559535656, 15265.084094954338, 15053.322600482785, 15463.458778942682, 15476.767848482057, 15737.213139343216, 15839.604009899846, 9311.534423278945, 9391.966883642657, 9411.152996230372, 9551.14039736427, 9581.712548623553, 10308.12380947598, 10463.319884207502, 11848.76302473963, 15657.921710391374, 15916.363345466052], "unit": "ms", "name": "jquery.html", "value": 9281.278357394183}, {"lowerIsBetter": false, "replicates": [1537.7462718651423, 1543.6063803730046, 1759.2786957347473, 1827.9725804112936, 1845.9723104153436, 8252.79368015799, 8518.616662250199, 8527.701530446435, 8529.999999999993, 8543.188397102273, 7294.927050729491, 7462.96268518658, 7920.564368959705, 8212.384071194669, 8310.83378332433, 2303.781140791622, 2304.3432621702777, 2321.2804030750435, 2335.8598484090926, 2336.2523992322463, 2083.7916208379115, 2322.268485427085, 2336.2640768157976, 2397.9160729374476, 2407.6388541718707, 2084.0413409831494, 2246.269962262263, 2290.061074959267, 2291.598970180212, 2299.494111295512, 2265.4676151104477, 2297.529006553661, 2302.539492101578, 2339.02930283932, 2340.8127349811975, 2323.848949818256, 2327.371609665398, 2353.870537120458, 2381.3332266965276, 2382.809375249976, 8367.163283671645, 8390.496570205747, 8460.492370457792, 8463.703770368007, 8464.407491475598, 4744.264638980963, 4816.566509014206, 4855.150348688965, 4855.878603034917, 4860.878478038042, 3109.8911538096286, 3118.5322201669705, 3148.795328303676, 3212.2932954749917, 3242.400155971153, 1006.6879267427121, 1040.1938497664296, 1050.2385770316537, 1068.086785798147, 1099.0218705352243, 11371.886281137165, 11389.715257118556, 11475.081993440588, 11533.653990380302, 11535.826962595567, 4302.548232435612, 4324.437823082979, 4386.758728269976, 4414.668899832525, 4415.028693687384, 9864.704058878246, 9916.950415248022, 9919.752006199758, 9936.80126397468, 9938.602455901828, 4909.7790599423115, 4914.582260507826, 4917.459079501252, 4919.1883339248625, 4920.507949205051, 4458.866234012985, 4477.462704475483, 4486.932696009562, 4487.820487180542, 4495.730256184574, 3832.827522761482, 3851.480050193244, 3856.093817952779, 3913.2760439318818, 3916.4712763776524, 1109.040679811973, 1118.0384869012642, 1225.6200577820905, 1228.9324087175291, 1242.236024844714, 1145.6158922052637, 1163.7963356412595, 1224.3205021213312, 1394.8814350780094, 1416.482983710938, 7795.805104872424, 7918.485298455582, 8067.0, 8200.630971606291, 8323.542205178774, 1088.3633074651418, 1138.7950168969664, 1191.9046476281876, 1191.9880801191878, 1223.6573759347398, 6340.936590634127, 6392.7123279452535, 6447.1296374989715, 6454.677266136674, 6459.450946669492, 10110.191184705205, 10226.6420675276, 10231.48842557884, 10243.7439064024, 10381.9480902595, 9961.900380996241, 10082.143017843418, 10207.030332118436, 10242.385456872611, 10251.436171010517, 7869.960650196713, 7884.093329267208, 7896.68413263463, 7970.521768693897, 7976.760697179094, 7629.351505122127, 7712.190220027041, 7741.7677469676, 7752.7674169775, 7791.454598178299, 8753.868691969497, 9150.130737579919, 9180.403273787182, 9190.632374705205, 9203.447793132433, 9180.17378435944, 9184.173424391836, 9408.52957352116, 9450.291228157777, 9570.33007689455, 7133.322334378226, 7203.135623725187, 7211.062561866924, 7212.2427145149, 7216.061911951413, 7539.9246007541415, 7722.227777222233, 7738.0, 7751.806204844924, 7754.612269386621, 6675.33246675348, 7469.066366704162, 7493.175750667313, 7507.662155203138, 7575.015248017722, 7074.327938845801, 7741.0, 7782.88325675104, 7828.334591559781, 7836.333911617577, 7850.568218748023, 7899.368050556057, 7900.130985591695, 7911.8813217800625, 7913.44605877583, 7157.534760240567, 7169.103862017248, 7206.783796486114, 7234.5659260444545, 7260.419166466564, 7345.70617175307, 7399.519031262951, 7403.962980185064, 7407.074115735533, 7409.333160015624], "unit": "ms", "name": "mootools.html", "value": 4773.384931042913}, {"lowerIsBetter": false, "replicates": [336.206552536015, 340.2684228907848, 345.94118999770035, 362.57759709937926, 369.156477449029, 5974.79088231912, 6039.42625450582, 6133.754649814002, 6167.753289868406, 6182.103594978731, 5917.674527900975, 6058.061000544912, 6091.329953705089, 6096.695165241742, 6098.664573448458, 4671.392718946532, 4678.76606169691, 4686.062787442508, 4686.554777296168, 4692.22578274586, 4480.6639502037315, 4567.885802854922, 4572.9314060289125, 4677.228257337535, 4703.082898834728, 4661.533846615345, 4695.577398034176, 4702.24764037754, 4704.506026867181, 4722.598579120779, 4684.1334353144775, 4686.062787442508, 4686.507916668769, 4701.811927522895, 4703.412073490814, 4143.08852052548, 4144.979275103605, 4148.149629325981, 4153.1070819773595, 4155.563665815076, 5688.857778555528, 5788.971055144697, 5802.82591522255, 5803.390643982363, 5818.912716309259, 5459.535939445152, 5475.835724928259, 5481.068218402881, 5482.0406428874785, 5484.533814625762, 6209.285932117819, 6433.2601750798785, 6446.516511261674, 6449.484041276686, 6456.2575303840185, 4473.642108631302, 4503.392042074311, 4586.082783443291, 4589.862304130881, 4592.770361481913, 6621.0, 6648.16897887764, 6651.201855777289, 6694.397504224643, 6701.29636388182, 4684.320773487825, 4692.0, 4692.0381321828945, 4694.920168361227, 4699.271612900006, 4649.3026046093355, 4686.484486706459, 4687.67186296956, 4697.4832768395445, 4753.287006948916, 5386.542143917811, 5389.75746091419, 5400.0, 5402.297701298885, 5405.486478784509, 6487.059376390397, 6492.740290388331, 6497.64262965532, 6501.219853617502, 6506.219253689587, 1671.1978250439856, 1685.1489997551403, 1685.6628674265196, 1686.7722857414337, 1696.3638635511684, 1076.7684947736275, 1085.3596378136938, 1086.750047489082, 1089.215764649451, 1152.590830255264, 6221.595596286227, 6287.811365659038, 6293.3391993840905, 6298.527610429236, 6299.275583307886, 6673.899891501631, 6674.199096108401, 6681.699323530453, 6687.130673012574, 6687.966560167265, 6281.560290779602, 6284.748610055638, 6288.52836037299, 6294.8111556653375, 6296.653684047421, 3565.0, 3660.359437098497, 3662.230931504361, 3662.377395842713, 3662.6886714629554, 6915.51591388608, 6917.169939607279, 6921.411680007155, 6923.653817309215, 6924.203716572659, 6840.828979275558, 6915.377616014583, 6930.410915072174, 6933.445324374038, 6937.306269373023, 6779.694913728894, 6919.8962015569805, 6929.44564434844, 6976.616286104211, 7007.544509606859, 6553.246376666744, 6662.467002639874, 6712.2616512184595, 6807.115075040411, 6815.216250131299, 6654.301298363602, 6691.197056353269, 6698.39714425704, 6701.262861085179, 6702.430293425114, 6924.58452492852, 6940.7917762467205, 6948.33990770876, 6949.0, 6961.6519174042105, 4621.884452888705, 4709.434867815884, 4770.045990801784, 4772.570468657837, 4784.880377990578, 6714.563553369016, 6715.630640314635, 6727.0, 6728.495362847708, 6730.764423245163, 6572.849751293409, 6578.8355291118105, 6581.506387021088, 6583.374579414948, 6584.572002819801, 6471.061696053952, 6534.313897040743, 6567.9014814776865, 6571.638559879252, 6572.934270657423, 6422.710978006095, 6433.0, 6433.967830160819, 6435.1312572803945, 6439.871202575829, 6561.179852518435, 6561.442277406283, 6569.638669873012, 6570.178727659043, 6573.276939536743, 6268.498520118288, 6383.36166383358, 6413.134226879311, 6436.935630643634, 6504.959206526933], "unit": "ms", "name": "prototype.html", "value": 4921.875879771019}, {"lowerIsBetter": false, "replicates": [725.7713820146654, 765.578931587627, 919.4345477531317, 920.479928840205, 935.4948327902932, 13805.240711760862, 16261.0, 16273.593160171002, 16289.83710162898, 16357.672846543062, 15446.922765386158, 15580.532584022469, 15589.922050389761, 15592.142432166227, 15609.375624974988, 792.053496072194, 798.4889670610813, 915.2997956563217, 936.489613160829, 936.7985883035146, 907.4509921497498, 907.8320510705507, 918.6463211663491, 921.6866265469739, 925.2459245714755, 983.1053741095604, 988.8071825993934, 989.8812142542869, 990.5889056041734, 991.1872264742877, 818.3862103422433, 831.7255305749088, 937.5874615169304, 967.2503809547586, 976.1263669016226, 721.4336745654689, 721.5346101764385, 721.7546034348346, 721.7726416178939, 722.003634983719, 13444.86555134446, 13504.257265850374, 13553.796693049611, 13618.591442256748, 13626.659333516642, 12248.326342051183, 12550.309732964683, 12655.557055503104, 12682.936585317108, 13024.674383140497, 10768.138548916068, 10836.729081772939, 11668.708282292926, 11762.588309409215, 11950.581729639422, 3905.8437662493466, 3976.204759048202, 3990.6408423242046, 3990.8004599769897, 4119.176164767028, 48101.0, 48230.27654585184, 48640.75679621615, 48681.0, 48761.0, 10975.231733778719, 11059.889401105886, 11114.388708620942, 11218.363684534128, 11455.312681239153, 19667.311644092388, 19753.40739777809, 19787.505312367306, 19789.802101978796, 19792.0, 7744.186860379579, 7786.299233069051, 7788.337991270692, 7828.334591559667, 8074.354051675852, 4184.895377615584, 4256.787160642018, 4258.6805989550285, 4313.439252897103, 4314.072474417952, 3431.1422144463886, 3449.3273811606496, 3455.809930453799, 3459.584849818038, 3462.9307413852084, 599.340725202274, 609.6494515653517, 620.1503939602773, 621.285521650106, 622.4771192198575, 648.1379764912655, 650.7331993882485, 685.2154283345542, 697.9651017449107, 705.7670968580356, 8108.75673729789, 8363.95818020906, 8389.874151887727, 8476.237382405778, 8741.125887411335, 588.717415640495, 591.630231105559, 639.2233436374827, 675.8580698053464, 716.8494616130566, 3816.3702989007056, 3820.7707537547835, 3891.9805400972814, 3957.2679054374457, 4046.615571520701, 55806.32581022576, 56210.71894640501, 56469.0, 56883.71558142183, 63323.36676633175, 51779.741101294254, 52457.2131418029, 52551.0, 52993.2051019235, 53600.19599706007, 47600.523994759606, 49405.50594494009, 49467.0106597866, 49854.0, 49894.25158622624, 9169.0, 9233.169014788702, 9490.525473726424, 9665.951670241604, 9752.414855108716, 35378.11554711153, 36063.819680901426, 36726.694597486356, 37082.88751337464, 37229.0, 45138.322925156805, 45877.54122458733, 45888.31167532423, 46412.30381544212, 46748.29877551772, 11642.126840487166, 11976.221545599508, 12037.152072037936, 12110.939445302716, 12166.330851803235, 12780.297083660487, 12908.225506469642, 12927.612171634866, 13112.475500979854, 13127.540536081191, 11954.222975506564, 12362.814557781461, 12654.87345126537, 12774.61676149717, 12972.35138243103, 12054.698632533906, 12125.333106679218, 12727.681807954876, 12785.296808675612, 13346.3326833656, 10748.516316765608, 11656.708582285512, 11945.283283003047, 12185.086118540967, 12300.507979680713, 10380.480975951323, 10866.0, 10889.346639201673, 10898.782024359627, 11020.793101899839, 9993.350432221881, 10138.0, 10169.627100341359, 10725.463726813472, 10959.232853700165], "unit": "ms", "name": "yui.html", "value": 6751.065661600689}], "name": "dromaeo_css", "value": 7355.74223799021}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 112.0, 109.0, 109.0, 98.0, 111.0, 110.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.4}, {"lowerIsBetter": true, "replicates": [146.0, 132.0, 160.0, 155.0, 127.0, 151.0, 151.0, 122.0, 163.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 161.0, 158.0, 161.0, 160.0, 162.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 73.0, 153.0, 73.0, 71.0, 73.0, 73.0, 161.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 89.1}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 134.0, 92.0, 92.0, 93.0, 139.0, 92.0, 94.0, 147.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 56.0, 57.0, 58.0, 58.0, 57.0, 56.0, 58.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 65.0, 64.0, 68.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 77.0, 79.0, 79.0, 77.0, 86.0, 79.0, 78.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.9}, {"lowerIsBetter": true, "replicates": [123.0, 119.0, 115.0, 118.0, 113.0, 115.0, 119.0, 113.0, 114.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.1}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 147.0, 144.0, 144.0, 136.0, 146.0, 145.0, 145.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.4}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 64.0, 64.0, 69.0, 64.0, 63.0, 64.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1483.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.68174753928508], "unit": "score", "name": "Richards", "value": 204.43388200000004}, {"lowerIsBetter": false, "replicates": [171.05713308244952], "unit": "score", "name": "DeltaBlue", "value": 386.5258280000001}, {"lowerIsBetter": false, "replicates": [277.54648903691367], "unit": "score", "name": "Encrypt", "value": 959.0501430000003}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [985.2216748768473], "unit": "score", "name": "RayTrace", "value": 751.0888350000004}, {"lowerIsBetter": false, "replicates": [557.1030640668523], "unit": "score", "name": "Earley", "value": 1196.3010849999996}, {"lowerIsBetter": false, "replicates": [10600.0], "unit": "score", "name": "Boyer", "value": 62.873867924528355}, {"lowerIsBetter": false, "replicates": [24023.809523809523], "unit": "score", "name": "RegExp", "value": 37.920089197225}, {"lowerIsBetter": false, "replicates": [494.12915851272015], "unit": "score", "name": "Splay", "value": 164.9184198019802}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 23131.01692937717}]} 23:00:16 INFO - RETURN: dromaeo_css: 7355.74 23:00:16 INFO - RETURN: kraken: 1483.60 23:00:16 INFO - RETURN: v8_7: 23131.02 23:00:16 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23131.02"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1483.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7355.74"}}} 23:00:16 INFO - Return code: 0 23:00:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:00:16 INFO - # TBPL SUCCESS # 23:00:16 INFO - Running post-action listener: _resource_record_post_action 23:00:16 INFO - Running post-run listener: _resource_record_post_run 23:00:17 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 509964288; Read time: 0; Write time: 589680 23:00:17 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 246157312; Read time: 0; Write time: 321880 23:00:17 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 263483392; Read time: 0; Write time: 267772 23:00:17 INFO - Running post-run listener: _upload_blobber_files 23:00:17 INFO - Blob upload gear active. 23:00:17 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 23:00:17 INFO - Copying logs to upload dir... 23:00:17 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.715269 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-25 23:00:17.521969) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 23:00:17.527691) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010007 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456460484/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-25 23:00:17.579796) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 23:00:17.580164) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1456466617.783250-1683860409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 23:00:17.630027) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 23:00:17.630365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 23:00:17.630704) ========= ========= Total master_lag: 0.27 =========