builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1458709890.59 results: success (0) buildid: 20160322193105 builduid: 29aa2a79615c4e5ea1ffaa9f975f0a40 revision: 3381aa98edf72e02b9d6b4db6efa0865063a2329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.593914) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.594336) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.594621) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006416 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.639102) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.639401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.857523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.860889) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.956768) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.957078) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.957420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:30.960732) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-22 22:11:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 81.9M=0s 2016-03-22 22:11:31 (81.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125710 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.109126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.109415) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020389 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.183017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.183334) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3381aa98edf72e02b9d6b4db6efa0865063a2329 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3381aa98edf72e02b9d6b4db6efa0865063a2329 --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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-22 22:11:31,332 truncating revision to first 12 chars 2016-03-22 22:11:31,332 Setting DEBUG logging. 2016-03-22 22:11:31,332 attempt 1/10 2016-03-22 22:11:31,332 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3381aa98edf7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-22 22:11:31,681 unpacking tar archive at: mozilla-central-3381aa98edf7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.717607 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.924887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.925185) ========= echo 3381aa98edf72e02b9d6b4db6efa0865063a2329 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3381aa98edf72e02b9d6b4db6efa0865063a2329'] 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3381aa98edf72e02b9d6b4db6efa0865063a2329 program finished with exit code 0 elapsedTime=0.004628 script_repo_revision: '3381aa98edf72e02b9d6b4db6efa0865063a2329' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.984555) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:31.984871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-22 22:11:32.020323) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-03-22 22:11:32.020612) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:11:32 INFO - MultiFileLogger online at 20160322 22:11:32 in /builds/slave/test-pgo 22:11:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:11:32 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:11:32 INFO - {'append_to_log': False, 22:11:32 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:11:32 INFO - 'blob_upload_branch': 'Firefox', 22:11:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:11:32 INFO - 'branch': 'Firefox', 22:11:32 INFO - 'buildbot_json_path': 'buildprops.json', 22:11:32 INFO - 'config_files': ('talos/linux_config.py',), 22:11:32 INFO - 'default_actions': ('clobber', 22:11:32 INFO - 'read-buildbot-config', 22:11:32 INFO - 'download-and-extract', 22:11:32 INFO - 'populate-webroot', 22:11:32 INFO - 'create-virtualenv', 22:11:32 INFO - 'install', 22:11:32 INFO - 'run-tests'), 22:11:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:11:32 INFO - 'download_minidump_stackwalk': True, 22:11:32 INFO - 'download_symbols': 'ondemand', 22:11:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:11:32 INFO - 'tooltool.py': '/tools/tooltool.py', 22:11:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:11:32 INFO - '/tools/misc-python/virtualenv.py')}, 22:11:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:11:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:11:32 INFO - 'installer_path': 'installer.exe', 22:11:32 INFO - 'log_level': 'info', 22:11:32 INFO - 'log_name': 'talos', 22:11:32 INFO - 'log_to_console': True, 22:11:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:11:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:11:32 INFO - 'opt_config_files': (), 22:11:32 INFO - 'pip_index': False, 22:11:32 INFO - 'sps_profile': False, 22:11:32 INFO - 'sps_profile_interval': 0, 22:11:32 INFO - 'suite': 'dromaeojs', 22:11:32 INFO - 'system_bits': '32', 22:11:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:11:32 INFO - 'title': 'talos-linux64-ix-016', 22:11:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:11:32 INFO - 'use_talos_json': True, 22:11:32 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:11:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:11:32 INFO - 'work_dir': 'build'} 22:11:32 INFO - ##### 22:11:32 INFO - ##### Running clobber step. 22:11:32 INFO - ##### 22:11:32 INFO - Running pre-action listener: _resource_record_pre_action 22:11:32 INFO - Running main action method: clobber 22:11:32 INFO - rmtree: /builds/slave/test-pgo/build 22:11:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:11:33 INFO - Running post-action listener: _resource_record_post_action 22:11:33 INFO - ##### 22:11:33 INFO - ##### Running read-buildbot-config step. 22:11:33 INFO - ##### 22:11:33 INFO - Running pre-action listener: _resource_record_pre_action 22:11:33 INFO - Running main action method: read_buildbot_config 22:11:33 INFO - Using buildbot properties: 22:11:33 INFO - { 22:11:33 INFO - "project": "", 22:11:33 INFO - "product": "firefox", 22:11:33 INFO - "script_repo_revision": "production", 22:11:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:11:33 INFO - "repository": "", 22:11:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:11:33 INFO - "buildid": "20160322193105", 22:11:33 INFO - "pgo_build": "True", 22:11:33 INFO - "basedir": "/builds/slave/test-pgo", 22:11:33 INFO - "buildnumber": 717, 22:11:33 INFO - "slavename": "talos-linux64-ix-016", 22:11:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:11:33 INFO - "platform": "ubuntu64_hw", 22:11:33 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:11:33 INFO - "branch": "mozilla-central", 22:11:33 INFO - "repo_path": "mozilla-central", 22:11:33 INFO - "revision": "3381aa98edf72e02b9d6b4db6efa0865063a2329", 22:11:33 INFO - "stage_platform": "linux64-pgo", 22:11:33 INFO - "builduid": "29aa2a79615c4e5ea1ffaa9f975f0a40", 22:11:33 INFO - "slavebuilddir": "test" 22:11:33 INFO - } 22:11:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 22:11:33 INFO - Running post-action listener: _resource_record_post_action 22:11:33 INFO - ##### 22:11:33 INFO - ##### Running download-and-extract step. 22:11:33 INFO - ##### 22:11:33 INFO - Running pre-action listener: _resource_record_pre_action 22:11:33 INFO - Running main action method: download_and_extract 22:11:33 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:11: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:11:33 INFO - https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:11:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:11:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:11:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:11:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:11:38 INFO - Downloaded 1361 bytes. 22:11:38 INFO - Reading from file /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:11:38 INFO - Using the following test package requirements: 22:11:38 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:11:38 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:11:38 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:11:38 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'jsshell-linux-x86_64.zip'], 22:11:38 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:11:38 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:11:38 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:11:38 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:11:38 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:11:38 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:11:38 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:11:38 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:11:38 INFO - https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:11:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:11:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:11:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:12:11 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip: The read operation timed out 22:12:11 INFO - retry: attempt #1 caught exception: The read operation timed out 22:12:11 INFO - retry: Failed, sleeping 30 seconds before retrying 22:12:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:12:42 INFO - Downloaded 23573904 bytes. 22:12:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:12:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:12:43 INFO - Return code: 0 22:12:43 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:43 INFO - https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:12:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:12:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:12:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:12:45 INFO - Downloaded 23573904 bytes. 22:12:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:12:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:12:46 INFO - Return code: 0 22:12: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:12:46 INFO - https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:12:47 INFO - Downloaded 10848120 bytes. 22:12:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:12:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 22:12:47 INFO - Return code: 0 22:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:47 INFO - https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:12:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:12:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:12:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:12:49 INFO - Downloaded 57729661 bytes. 22:12:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:12:49 INFO - mkdir: /builds/slave/test-pgo/properties 22:12:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:12:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:12:49 INFO - Contents: 22:12:49 INFO - build_url:https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:12:51 INFO - Running post-action listener: _resource_record_post_action 22:12:51 INFO - Running post-action listener: set_extra_try_arguments 22:12:51 INFO - ##### 22:12:51 INFO - ##### Running populate-webroot step. 22:12:51 INFO - ##### 22:12:51 INFO - Running pre-action listener: _resource_record_pre_action 22:12:51 INFO - Running main action method: populate_webroot 22:12:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:12:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:12:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:12:51 INFO - '--tppagecycles', 22:12:51 INFO - '7'], 22:12:51 INFO - 'tests': ['tsvgm']}}, 22:12:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:12:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:12:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:12:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:12:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'tests': ['damp', 'tps']}, 22:12:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['damp', 'tps']}, 22:12:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:12:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:12:51 INFO - 'other': {'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'other_l64': {'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'other_nol64': {'tests': ['a11yr', 22:12:51 INFO - 'ts_paint', 22:12:51 INFO - 'tpaint', 22:12:51 INFO - 'sessionrestore', 22:12:51 INFO - 'sessionrestore_no_auto_restore', 22:12:51 INFO - 'tabpaint']}, 22:12:51 INFO - 'svgr': {'tests': ['tsvgx', 22:12:51 INFO - 'tsvgr_opacity', 22:12:51 INFO - 'tart', 22:12:51 INFO - 'tscrollx', 22:12:51 INFO - 'cart']}, 22:12:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['tsvgx', 22:12:51 INFO - 'tsvgr_opacity', 22:12:51 INFO - 'tart', 22:12:51 INFO - 'tscrollx', 22:12:51 INFO - 'cart']}, 22:12:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'tests': ['tp5o']}, 22:12:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'talos_options': ['--e10s'], 22:12:51 INFO - 'tests': ['tp5o']}, 22:12:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'talos_options': ['--xperf_path', 22:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:51 INFO - 'tests': ['tp5n']}, 22:12:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:51 INFO - 'talos_options': ['--e10s', 22:12:51 INFO - '--xperf_path', 22:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:51 INFO - 'tests': ['tp5n']}}, 22:12:51 INFO - 'talos.zip': {'path': '', 22:12:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:12:51 INFO - Running post-action listener: _resource_record_post_action 22:12:51 INFO - ##### 22:12:51 INFO - ##### Running create-virtualenv step. 22:12:51 INFO - ##### 22:12:51 INFO - Running pre-action listener: _resource_record_pre_action 22:12:51 INFO - Running main action method: create_virtualenv 22:12:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:12:51 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:12:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:12:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:12:51 INFO - Using real prefix '/usr' 22:12:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:12:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:12:53 INFO - Installing pip.................done. 22:12:53 INFO - Return code: 0 22:12:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:53 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:12:53 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:12:53 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:12:53 INFO - Using env: {'DISPLAY': ':0', 22:12:53 INFO - 'HOME': '/home/cltbld', 22:12:53 INFO - 'LANG': 'en_US.UTF-8', 22:12:53 INFO - 'LANGUAGE': 'en_US:en', 22:12:53 INFO - 'LOGNAME': 'cltbld', 22:12:53 INFO - 'MAIL': '/var/mail/cltbld', 22:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:53 INFO - 'MOZ_NO_REMOTE': '1', 22:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:53 INFO - 'NO_EM_RESTART': '1', 22:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:12:53 INFO - 'PWD': '/builds/slave/test-pgo', 22:12:53 INFO - 'SHELL': '/bin/bash', 22:12:53 INFO - 'SHLVL': '1', 22:12:53 INFO - 'TERM': 'linux', 22:12:53 INFO - 'TMOUT': '86400', 22:12:53 INFO - 'USER': 'cltbld', 22:12:53 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:53 INFO - '_': '/tools/buildbot/bin/python'} 22:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:54 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:12:54 INFO - Cleaning up... 22:12:54 INFO - Return code: 0 22:12:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:12:54 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:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:54 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:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:54 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:12:54 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:12:54 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:12:54 INFO - Using env: {'DISPLAY': ':0', 22:12:54 INFO - 'HOME': '/home/cltbld', 22:12:54 INFO - 'LANG': 'en_US.UTF-8', 22:12:54 INFO - 'LANGUAGE': 'en_US:en', 22:12:54 INFO - 'LOGNAME': 'cltbld', 22:12:54 INFO - 'MAIL': '/var/mail/cltbld', 22:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:54 INFO - 'MOZ_NO_REMOTE': '1', 22:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:54 INFO - 'NO_EM_RESTART': '1', 22:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:12:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:12:54 INFO - 'SHELL': '/bin/bash', 22:12:54 INFO - 'SHLVL': '1', 22:12:54 INFO - 'TERM': 'linux', 22:12:54 INFO - 'TMOUT': '86400', 22:12:54 INFO - 'USER': 'cltbld', 22:12:54 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:54 INFO - '_': '/tools/buildbot/bin/python'} 22:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:54 INFO - Downloading/unpacking psutil>=0.7.1 22:12:54 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:12:54 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:12:54 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:12:54 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:12:54 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:12:54 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:12:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:12:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:56 INFO - Installing collected packages: psutil 22:12:56 INFO - Running setup.py install for psutil 22:12:56 INFO - building 'psutil._psutil_linux' extension 22:12:56 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:12:56 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:12:56 INFO - building 'psutil._psutil_posix' extension 22:12:56 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:12:56 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:12:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:56 INFO - Successfully installed psutil 22:12:56 INFO - Cleaning up... 22:12:56 INFO - Return code: 0 22:12:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:56 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:12:56 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:12:56 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:12:56 INFO - Using env: {'DISPLAY': ':0', 22:12:56 INFO - 'HOME': '/home/cltbld', 22:12:56 INFO - 'LANG': 'en_US.UTF-8', 22:12:56 INFO - 'LANGUAGE': 'en_US:en', 22:12:56 INFO - 'LOGNAME': 'cltbld', 22:12:56 INFO - 'MAIL': '/var/mail/cltbld', 22:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:56 INFO - 'MOZ_NO_REMOTE': '1', 22:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:56 INFO - 'NO_EM_RESTART': '1', 22:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:12:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:12:56 INFO - 'SHELL': '/bin/bash', 22:12:56 INFO - 'SHLVL': '1', 22:12:56 INFO - 'TERM': 'linux', 22:12:56 INFO - 'TMOUT': '86400', 22:12:56 INFO - 'USER': 'cltbld', 22:12:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:56 INFO - '_': '/tools/buildbot/bin/python'} 22:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:12:57 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:12:57 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:12:57 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:12:57 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:12:57 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:12:57 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:12:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:12:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:12:59 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:12:59 INFO - Installing collected packages: mozsystemmonitor 22:12:59 INFO - Running setup.py install for mozsystemmonitor 22:12:59 INFO - Successfully installed mozsystemmonitor 22:12:59 INFO - Cleaning up... 22:12:59 INFO - Return code: 0 22:12:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:59 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:12:59 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:12:59 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:12:59 INFO - Using env: {'DISPLAY': ':0', 22:12:59 INFO - 'HOME': '/home/cltbld', 22:12:59 INFO - 'LANG': 'en_US.UTF-8', 22:12:59 INFO - 'LANGUAGE': 'en_US:en', 22:12:59 INFO - 'LOGNAME': 'cltbld', 22:12:59 INFO - 'MAIL': '/var/mail/cltbld', 22:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:59 INFO - 'MOZ_NO_REMOTE': '1', 22:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:59 INFO - 'NO_EM_RESTART': '1', 22:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:12:59 INFO - 'PWD': '/builds/slave/test-pgo', 22:12:59 INFO - 'SHELL': '/bin/bash', 22:12:59 INFO - 'SHLVL': '1', 22:12:59 INFO - 'TERM': 'linux', 22:12:59 INFO - 'TMOUT': '86400', 22:12:59 INFO - 'USER': 'cltbld', 22:12:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:59 INFO - '_': '/tools/buildbot/bin/python'} 22:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:59 INFO - Downloading/unpacking blobuploader==1.2.4 22:12:59 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:12:59 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:12:59 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:12:59 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:12:59 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:12:59 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:13:01 INFO - Downloading blobuploader-1.2.4.tar.gz 22:13:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:13:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:13:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:01 INFO - Downloading docopt-0.6.1.tar.gz 22:13:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:13:01 INFO - Installing collected packages: blobuploader, requests, docopt 22:13:01 INFO - Running setup.py install for blobuploader 22:13:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:13:02 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:13:02 INFO - Running setup.py install for requests 22:13:02 INFO - Running setup.py install for docopt 22:13:02 INFO - Successfully installed blobuploader requests docopt 22:13:02 INFO - Cleaning up... 22:13:02 INFO - Return code: 0 22:13:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:02 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:13:02 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:13:02 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:13:02 INFO - Using env: {'DISPLAY': ':0', 22:13:02 INFO - 'HOME': '/home/cltbld', 22:13:02 INFO - 'LANG': 'en_US.UTF-8', 22:13:02 INFO - 'LANGUAGE': 'en_US:en', 22:13:02 INFO - 'LOGNAME': 'cltbld', 22:13:02 INFO - 'MAIL': '/var/mail/cltbld', 22:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:02 INFO - 'MOZ_NO_REMOTE': '1', 22:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:02 INFO - 'NO_EM_RESTART': '1', 22:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:02 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:02 INFO - 'SHELL': '/bin/bash', 22:13:02 INFO - 'SHLVL': '1', 22:13:02 INFO - 'TERM': 'linux', 22:13:02 INFO - 'TMOUT': '86400', 22:13:02 INFO - 'USER': 'cltbld', 22:13:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:02 INFO - '_': '/tools/buildbot/bin/python'} 22:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:13:02 INFO - Running setup.py (path:/tmp/pip-DxklSj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:13:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:13:02 INFO - Running setup.py (path:/tmp/pip-Ej29wE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:13:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:13:02 INFO - Running setup.py (path:/tmp/pip-aSTeJL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:13:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:13:03 INFO - Running setup.py (path:/tmp/pip-lDW9aE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:13:03 INFO - Running setup.py (path:/tmp/pip-9OYFxo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:13:03 INFO - Running setup.py (path:/tmp/pip-ANmCtP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:13:03 INFO - Running setup.py (path:/tmp/pip-L9XVow-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:13:03 INFO - Running setup.py (path:/tmp/pip-gz9R4o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:13:03 INFO - Running setup.py (path:/tmp/pip-BVmLMZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:13:03 INFO - Running setup.py (path:/tmp/pip-8UjcnT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:13:03 INFO - Running setup.py (path:/tmp/pip-HeEgIG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:13:03 INFO - Running setup.py (path:/tmp/pip-MwwVZk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:13:03 INFO - Running setup.py (path:/tmp/pip-oiHWQE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:13:03 INFO - Running setup.py (path:/tmp/pip-VOZVOn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:13:03 INFO - Running setup.py (path:/tmp/pip-5e0ujM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:13:03 INFO - Running setup.py (path:/tmp/pip-NdhEeH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:13:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:13:03 INFO - Running setup.py (path:/tmp/pip-4LmKWg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:13:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:13:04 INFO - Running setup.py install for manifestparser 22:13:04 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Running setup.py install for mozcrash 22:13:04 INFO - Running setup.py install for mozdebug 22:13:04 INFO - Running setup.py install for mozdevice 22:13:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Running setup.py install for mozfile 22:13:04 INFO - Running setup.py install for mozhttpd 22:13:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Running setup.py install for mozinfo 22:13:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Running setup.py install for mozInstall 22:13:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:13:04 INFO - Running setup.py install for mozleak 22:13:04 INFO - Running setup.py install for mozlog 22:13:05 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Running setup.py install for moznetwork 22:13:05 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Running setup.py install for mozprocess 22:13:05 INFO - Running setup.py install for mozprofile 22:13:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Running setup.py install for mozrunner 22:13:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Running setup.py install for mozscreenshot 22:13:05 INFO - Running setup.py install for moztest 22:13:05 INFO - Running setup.py install for mozversion 22:13:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:13:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:13:05 INFO - Cleaning up... 22:13:05 INFO - Return code: 0 22:13:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:05 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:13:05 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:13:05 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:13:05 INFO - Using env: {'DISPLAY': ':0', 22:13:05 INFO - 'HOME': '/home/cltbld', 22:13:05 INFO - 'LANG': 'en_US.UTF-8', 22:13:05 INFO - 'LANGUAGE': 'en_US:en', 22:13:05 INFO - 'LOGNAME': 'cltbld', 22:13:05 INFO - 'MAIL': '/var/mail/cltbld', 22:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:05 INFO - 'MOZ_NO_REMOTE': '1', 22:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:05 INFO - 'NO_EM_RESTART': '1', 22:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:05 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:05 INFO - 'SHELL': '/bin/bash', 22:13:05 INFO - 'SHLVL': '1', 22:13:05 INFO - 'TERM': 'linux', 22:13:05 INFO - 'TMOUT': '86400', 22:13:05 INFO - 'USER': 'cltbld', 22:13:05 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:05 INFO - '_': '/tools/buildbot/bin/python'} 22:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:13:06 INFO - Running setup.py (path:/tmp/pip-S7SSFN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:13:06 INFO - Running setup.py (path:/tmp/pip-lu55Lv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:13:06 INFO - Running setup.py (path:/tmp/pip-lWiFzG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:13:06 INFO - Running setup.py (path:/tmp/pip-nFSto8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:13:06 INFO - Running setup.py (path:/tmp/pip-UvvXby-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:13:06 INFO - Running setup.py (path:/tmp/pip-NcNdJ3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:13:06 INFO - Running setup.py (path:/tmp/pip-KhzGpR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:13:06 INFO - Running setup.py (path:/tmp/pip-Q3NtlS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:13:06 INFO - Running setup.py (path:/tmp/pip-P5NHo3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:13:06 INFO - Running setup.py (path:/tmp/pip-Ot4ecE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:13:06 INFO - Running setup.py (path:/tmp/pip-Y4wLiw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:13:06 INFO - Running setup.py (path:/tmp/pip-Ah4rIM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:13:06 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:13:06 INFO - Running setup.py (path:/tmp/pip-WOoSta-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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:13:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:13:06 INFO - Running setup.py (path:/tmp/pip-kYdPQa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:13:07 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:13:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:13:07 INFO - Running setup.py (path:/tmp/pip-os0Dfh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:13:07 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:13:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:13:07 INFO - Running setup.py (path:/tmp/pip-v2Z1a0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:13:07 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:13:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:13:07 INFO - Running setup.py (path:/tmp/pip-iVGU_j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:13:07 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:13:07 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.17->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:13:07 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.17->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:13:07 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:13:07 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:13:07 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:13:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:09 INFO - Downloading blessings-1.6.tar.gz 22:13:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:13:09 INFO - Installing collected packages: blessings 22:13:09 INFO - Running setup.py install for blessings 22:13:09 INFO - Successfully installed blessings 22:13:09 INFO - Cleaning up... 22:13:09 INFO - Return code: 0 22:13:09 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:13:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:13:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:13:09 INFO - Reading from file tmpfile_stdout 22:13:09 INFO - Current package versions: 22:13:09 INFO - argparse == 1.2.1 22:13:09 INFO - blessings == 1.6 22:13:09 INFO - blobuploader == 1.2.4 22:13:09 INFO - docopt == 0.6.1 22:13:09 INFO - manifestparser == 1.1 22:13:09 INFO - mozInstall == 1.12 22:13:09 INFO - mozcrash == 0.17 22:13:09 INFO - mozdebug == 0.1 22:13:09 INFO - mozdevice == 0.48 22:13:09 INFO - mozfile == 1.2 22:13:09 INFO - mozhttpd == 0.7 22:13:09 INFO - mozinfo == 0.9 22:13:09 INFO - mozleak == 0.1 22:13:09 INFO - mozlog == 3.1 22:13:09 INFO - moznetwork == 0.27 22:13:09 INFO - mozprocess == 0.22 22:13:09 INFO - mozprofile == 0.28 22:13:09 INFO - mozrunner == 6.11 22:13:09 INFO - mozscreenshot == 0.1 22:13:09 INFO - mozsystemmonitor == 0.0 22:13:09 INFO - moztest == 0.7 22:13:09 INFO - mozversion == 1.4 22:13:09 INFO - psutil == 3.1.1 22:13:09 INFO - requests == 1.2.3 22:13:09 INFO - wsgiref == 0.1.2 22:13:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:13:09 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:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:09 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:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:09 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:13:09 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:13:09 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:13:09 INFO - Using env: {'DISPLAY': ':0', 22:13:09 INFO - 'HOME': '/home/cltbld', 22:13:09 INFO - 'LANG': 'en_US.UTF-8', 22:13:09 INFO - 'LANGUAGE': 'en_US:en', 22:13:09 INFO - 'LOGNAME': 'cltbld', 22:13:09 INFO - 'MAIL': '/var/mail/cltbld', 22:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:09 INFO - 'MOZ_NO_REMOTE': '1', 22:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:09 INFO - 'NO_EM_RESTART': '1', 22:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:09 INFO - 'SHELL': '/bin/bash', 22:13:09 INFO - 'SHLVL': '1', 22:13:09 INFO - 'TERM': 'linux', 22:13:09 INFO - 'TMOUT': '86400', 22:13:09 INFO - 'USER': 'cltbld', 22:13:09 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:09 INFO - '_': '/tools/buildbot/bin/python'} 22:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 22:13:09 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 2)) 22:13:09 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 3)) 22:13:09 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 4)) 22:13:09 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 5)) 22:13:09 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 6)) 22:13:09 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 7)) 22:13:09 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 8)) 22:13:09 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 9)) 22:13:09 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:13:09 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 4)) 22:13:09 INFO - Cleaning up... 22:13:09 INFO - Return code: 0 22:13:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:13:09 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:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:09 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:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:09 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:13:09 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:13:09 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:13:09 INFO - Using env: {'DISPLAY': ':0', 22:13:09 INFO - 'HOME': '/home/cltbld', 22:13:09 INFO - 'LANG': 'en_US.UTF-8', 22:13:09 INFO - 'LANGUAGE': 'en_US:en', 22:13:09 INFO - 'LOGNAME': 'cltbld', 22:13:09 INFO - 'MAIL': '/var/mail/cltbld', 22:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:09 INFO - 'MOZ_NO_REMOTE': '1', 22:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:09 INFO - 'NO_EM_RESTART': '1', 22:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:09 INFO - 'SHELL': '/bin/bash', 22:13:09 INFO - 'SHLVL': '1', 22:13:09 INFO - 'TERM': 'linux', 22:13:09 INFO - 'TMOUT': '86400', 22:13:09 INFO - 'USER': 'cltbld', 22:13:09 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:09 INFO - '_': '/tools/buildbot/bin/python'} 22:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:10 INFO - Downloading/unpacking jsonschema 22:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:13:11 INFO - Downloading/unpacking functools32 (from jsonschema) 22:13:11 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:13:11 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:13:11 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:13:11 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:13:11 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:13:11 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:13:12 INFO - Downloading functools32-3.2.3-2.tar.gz 22:13:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:13:12 INFO - warning: no files found matching '*.txt' 22:13:12 INFO - no previously-included directories found matching 'build' 22:13:12 INFO - no previously-included directories found matching 'dist' 22:13:12 INFO - no previously-included directories found matching '.git*' 22:13:12 INFO - Installing collected packages: jsonschema, functools32 22:13:12 INFO - Running setup.py install for functools32 22:13:12 INFO - warning: no files found matching '*.txt' 22:13:12 INFO - no previously-included directories found matching 'build' 22:13:12 INFO - no previously-included directories found matching 'dist' 22:13:12 INFO - no previously-included directories found matching '.git*' 22:13:12 INFO - Successfully installed jsonschema functools32 22:13:12 INFO - Cleaning up... 22:13:12 INFO - Return code: 0 22:13:12 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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 0x7fbde2293f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13576b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, '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': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', '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:13:12 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:13:12 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:13:12 INFO - Using env: {'DISPLAY': ':0', 22:13:12 INFO - 'HOME': '/home/cltbld', 22:13:12 INFO - 'LANG': 'en_US.UTF-8', 22:13:12 INFO - 'LANGUAGE': 'en_US:en', 22:13:12 INFO - 'LOGNAME': 'cltbld', 22:13:12 INFO - 'MAIL': '/var/mail/cltbld', 22:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:12 INFO - 'MOZ_NO_REMOTE': '1', 22:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:12 INFO - 'NO_EM_RESTART': '1', 22:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:12 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:12 INFO - 'SHELL': '/bin/bash', 22:13:12 INFO - 'SHLVL': '1', 22:13:12 INFO - 'TERM': 'linux', 22:13:12 INFO - 'TMOUT': '86400', 22:13:12 INFO - 'USER': 'cltbld', 22:13:12 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:12 INFO - '_': '/tools/buildbot/bin/python'} 22:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:12 INFO - Downloading/unpacking flake8 22:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:13:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:13:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:14 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:13:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:13:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:13:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:13:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:13:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:13:15 INFO - no previously-included directories found matching 'docs/_build' 22:13:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:13:15 INFO - Running setup.py install for pep8 22:13:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:13:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:13:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:13:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:13:15 INFO - no previously-included directories found matching 'docs/_build' 22:13:15 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:13:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:13:15 INFO - Cleaning up... 22:13:15 INFO - Return code: 0 22:13:15 INFO - Running post-action listener: _resource_record_post_action 22:13:15 INFO - Running post-action listener: _start_resource_monitoring 22:13:15 INFO - Starting resource monitoring. 22:13:15 INFO - ##### 22:13:15 INFO - ##### Running install step. 22:13:15 INFO - ##### 22:13:15 INFO - Running pre-action listener: _resource_record_pre_action 22:13:15 INFO - Running main action method: install 22:13:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:13:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:13:15 INFO - Reading from file tmpfile_stdout 22:13:15 INFO - Detecting whether we're running mozinstall >=1.0... 22:13:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:13:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:13:15 INFO - Reading from file tmpfile_stdout 22:13:15 INFO - Output received: 22:13:15 INFO - Usage: mozinstall [options] installer 22:13:15 INFO - Options: 22:13:15 INFO - -h, --help show this help message and exit 22:13:15 INFO - -d DEST, --destination=DEST 22:13:15 INFO - Directory to install application into. [default: 22:13:15 INFO - "/builds/slave/test-pgo"] 22:13:15 INFO - --app=APP Application being installed. [default: firefox] 22:13:15 INFO - mkdir: /builds/slave/test-pgo/build/application 22:13:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 22:13:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 22:13:32 INFO - Reading from file tmpfile_stdout 22:13:32 INFO - Output received: 22:13:32 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:13:32 INFO - Running post-action listener: _resource_record_post_action 22:13:32 INFO - ##### 22:13:32 INFO - ##### Running run-tests step. 22:13:32 INFO - ##### 22:13:32 INFO - Running pre-action listener: _resource_record_pre_action 22:13:32 INFO - Running main action method: run_tests 22:13:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:13:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:13:32 INFO - Python 2.7.3 22:13:32 INFO - Return code: 0 22:13:32 INFO - grabbing minidump binary from tooltool 22:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:32 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 0x13521d0>, '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 0x1355750>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1355be0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:13:32 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:13:32 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:13:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:13:32 INFO - Return code: 0 22:13:32 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:13:32 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:13:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:13:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:13:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:13:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:13:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:13:33 INFO - Return code: 0 22:13:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 22:13:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 22:13:33 INFO - Using env: {'DISPLAY': ':0', 22:13:33 INFO - 'HOME': '/home/cltbld', 22:13:33 INFO - 'LANG': 'en_US.UTF-8', 22:13:33 INFO - 'LANGUAGE': 'en_US:en', 22:13:33 INFO - 'LOGNAME': 'cltbld', 22:13:33 INFO - 'MAIL': '/var/mail/cltbld', 22:13:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:13:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:33 INFO - 'MOZ_NO_REMOTE': '1', 22:13:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:13:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:33 INFO - 'NO_EM_RESTART': '1', 22:13:33 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:13:33 INFO - 'PWD': '/builds/slave/test-pgo', 22:13:33 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:13:33 INFO - 'SHELL': '/bin/bash', 22:13:33 INFO - 'SHLVL': '1', 22:13:33 INFO - 'TERM': 'linux', 22:13:33 INFO - 'TMOUT': '86400', 22:13:33 INFO - 'USER': 'cltbld', 22:13:33 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1458705234.829846-332127681', 22:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:33 INFO - '_': '/tools/buildbot/bin/python'} 22:13:33 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:13:33 INFO - mozversion application_buildid: 20160322193105 22:13:33 INFO - mozversion application_changeset: 3381aa98edf72e02b9d6b4db6efa0865063a2329 22:13:33 INFO - mozversion application_display_name: Nightly 22:13:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:13:33 INFO - mozversion application_name: Firefox 22:13:33 INFO - mozversion application_remotingname: firefox 22:13:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:13:33 INFO - mozversion application_vendor: Mozilla 22:13:33 INFO - mozversion application_version: 48.0a1 22:13:33 INFO - mozversion platform_buildid: 20160322193105 22:13:33 INFO - mozversion platform_changeset: 3381aa98edf72e02b9d6b4db6efa0865063a2329 22:13:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:13:33 INFO - mozversion platform_version: 48.0a1 22:13:33 INFO - using testdate: 1458710013 22:13:33 INFO - actual date: 1458710013 22:13:33 INFO - starting webserver on 'localhost:38186' 22:13:33 INFO - SUITE-START | Running 2 tests 22:13:33 INFO - TEST-START | dromaeo_css 22:13:33 INFO - operating with platform_type : linux_ 22:13:33 INFO - Initialising browser for dromaeo_css test... 22:13:33 INFO - TEST-INFO | started process 26216 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm8scag/profile http://localhost:38186/getInfo.html) 22:13:37 INFO - PROCESS | 26216 | __metrics Screen width/height:1600/1200 22:13:37 INFO - PROCESS | 26216 | colorDepth:24 22:13:37 INFO - PROCESS | 26216 | Browser inner width/height: 1024/697 22:13:37 INFO - PROCESS | 26216 | __metrics 22:13:38 ERROR - PROCESS | 26216 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:13:39 INFO - TEST-INFO | 26216: exit 0 22:13:39 INFO - Browser initialized. 22:13:39 INFO - Running cycle 1/1 for dromaeo_css test... 22:13:39 INFO - TEST-INFO | started process 26308 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm8scag/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:13:51 INFO - PROCESS | 26308 | RSS: Main: 155967488 22:13:51 INFO - PROCESS | 26308 | 22:14:42 INFO - PROCESS | 26308 | 1458710082300 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:16:49 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38186/tests/dromaeo/cssquery-ext.html) 22:16:50 INFO - PROCESS | 26308 | RSS: Main: 243638272 22:16:50 INFO - PROCESS | 26308 | 22:19:53 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-ext.html (next: http://localhost:38186/tests/dromaeo/cssquery-jquery.html) 22:19:53 INFO - PROCESS | 26308 | RSS: Main: 233385984 22:19:53 INFO - PROCESS | 26308 | 22:22:55 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38186/tests/dromaeo/cssquery-mootools.html) 22:22:56 INFO - PROCESS | 26308 | RSS: Main: 236703744 22:22:56 INFO - PROCESS | 26308 | 22:25:57 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38186/tests/dromaeo/cssquery-prototype.html) 22:25:58 INFO - PROCESS | 26308 | RSS: Main: 220491776 22:25:58 INFO - PROCESS | 26308 | 22:29:01 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38186/tests/dromaeo/cssquery-yui.html) 22:29:01 INFO - PROCESS | 26308 | RSS: Main: 221822976 22:29:01 INFO - PROCESS | 26308 | 22:32:03 INFO - PROCESS | 26308 | Cycle 1(1): loaded http://localhost:38186/tests/dromaeo/cssquery-yui.html (next: http://localhost:38186/tests/dromaeo/cssquery-dojo.html) 22:32:04 INFO - PROCESS | 26308 | RSS: Main: 271536128 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | __start_tp_report 22:32:04 INFO - PROCESS | 26308 | _x_x_mozilla_page_load 22:32:04 INFO - PROCESS | 26308 | _x_x_mozilla_page_load_details 22:32:04 INFO - PROCESS | 26308 | |i|pagename|runs| 22:32:04 INFO - PROCESS | 26308 | |0;dojo.html;3047.5733397324375;3091.4126315999943;3126.2965832687646;3142.3401085772;3163.446396880546;5170.741462926858;5274.235235890794;5300.443453437391;5309.097453432916;5315.654482458637;5301.151815709478;5352.545033672132;5370.060239458089;5397.784088636459;5407.567394608432;4768.737719425439;4786.952130478686;4813.6389770767155;4816.181249187629;4962.776675049613;4877.292792545079;4893.853184404474;4924.039812236598;4942.827001054964;4959.876003099934;4730.172219861528;4769.356136921506;4863.148948933923;4889.8777530561665;4922.950770492303;4851.878703032417;4856.39295088114;4860.927086093711;4862.95137048632;4895.657303988723;4818.518148185189;4823.300621409911;4835.371401717754;4845.3216549683075;4845.951540484621;5179.689218646893;5253.028189784902;5300.496452836975;5310.920336194921;5338.706371149585;5718.170865224519;5732.5987180897355;5733.340665823442;5736.598438109334;5737.88524229513;5665.915011274834;5732.168835518868;5747.56893233005;5753.741081651336;5754.165645981309;5418.972905135489;5443.265159203536;5451.04606693827;5494.066008778517;5496.120620700669;5850.9414905851245;5855.560832937462;5859.677717725481;5874.5887787854435;5902.026165682614;5517.779288828482;5620.297462817148;5625.8593535161945;5633.521150702236;5639.915401268984;5752.827415177551;5758;5770.711464426846;5795.797147099831;5805.042168042226;5685.545156387479;5718.828435146952;5727.77088916447;5730.19777231188;5741.224934633771;5669.518090962232;5687.658740475585;5690.2033715279895;5728.742206600713;5747.942520574741;4374.934375984363;4376.3654270130655;4382.561743825656;4418.734875907456;4424.646028317791;3869.5163104611925;3875.2443273561385;3885.592012838668;3888.9027774305305;3908.9609103908597;5283.973580132151;5383.380911195262;5411.918821217685;5433.701146436984;5471.671699697951;5802.448767367094;5821.126830975388;5826.038703613941;5830.562707796932;5844.298684157929;5397.514223719884;5415.214793854869;5417.6478528895495;5423.945760542344;5424.267723857229;4885.389326334208;4969.82605608802;5023;5030.974845125751;5058.140116180185;5971.343152253248;5982.670953097621;5988.9101663475085;5992.131140984533;6009.06859436788;5775.104858746985;5802.796902108406;5806.883862322646;5807.477327040587;5811.767529298781;5868.618539794899;5948.5538584605465;6003.339632640494;6041.848953776191;6044.758209671564;5579.107342825128;5621.747021383966;5622.5501959842295;5631.774729010794;5639.2668953035845;5656.60403771732;5674.177244299636;5715.371309156073;5717.485426311652;5719.342275638355;5449.264349312951;5571.9721401392735;5574.303212098488;5612.102063669793;5655.349634792052;5527.2538207341495;5533.114701647717;5536.474034966672;5539.695316757617;5541.667499950016;5400.810971615974;5449.34607847045;5457.072297709319;5458.263134476795;5506.091494903456;5548.889022219614;5613.494785469327;5618.101103823368;5618.353889302783;5629.718514074362;5453.10023846061;5455.481729235875;5459.727013649381;5460.453954604508;5494.038543254835 22:32:04 INFO - PROCESS | 26308 | |1;ext.html;557.0145515378649;562.7692646015134;592.7717828635975;609.9847503812404;634.0394302631516;30174.698253017465;38943.41584876229;39026.41460378097;39058.60941390589;39239;40778.38832417516;41264;41411.17177656445;41432.17135657285;41490.79254603723;8040.959795201045;8557.871631925525;8851.026387097414;8852.778680533005;10239.283250172492;10511.1591072714;10693.572257109707;10701.732456688567;10729.892701073006;10736.570537178504;10655.04104630583;10751.462426878665;10757.462126893624;10765.084967777748;10767.946160269188;10597;10691.893081069207;10699.679009629723;10700.036996670337;10703.946480267627;10506.894931050669;10669.146468282519;10669.733256668645;10672.413017284047;10676.51955661997;41237.38143927843;41421;41777.95555111116;41865.58134418678;41920.37119443211;3409.62494125646;3491.336646037245;3634.9273014539563;3912.471816304819;4011.4785077939973;31973.680263197304;32071.51892721611;32442.18894527632;32509.187270318194;33047.33905321904;4230.76730779807;4331;4349.652027837766;4356.172327257811;4356.4772227332605;303277;303987;304245;305081;307004;47965.5203447968;48471.27293090607;48633.5136648636;62278.68860655758;62894.0565891512;47330.290045649344;47507.76246118816;47751.283730744064;47882.521174788504;47991.52008479871;15532.835037372242;15696.76454853178;15729.921350393175;15735.921320393554;15741.212939352987;12342.13605047638;12498.375081245902;12512.687182820502;12515.56195533152;12517.74964500723;16202.270897809627;16211.594710132105;16241.431549895697;16257.59356016109;16302.184890755414;6285.685715714196;6357.237131544244;6373.5143294114405;6425.453836423956;6484.383983521652;1026.38416949831;1031.4430207687915;1138.8633363996373;1142.2860712054965;1147.6729132197283;31715.682843171733;31896.362072758417;32095.197620059684;32247;32473.837630811693;1102.3385968418884;1148.5176225985106;1198.7782260281876;1207.589419597341;1219.9512019519298;14725.631859203606;15025.323860426308;15072.472463463726;15151.19983747812;15330.310136043905;393644;395476;395668;397018;397793;55271.44728552663;55578.44421555733;55774.721126394106;55787.72106139443;56078.43921560732;1223.1988047828686;1338.7389459055585;1345.7173993461483;1360.211077575004;1438.7122575484946;28049;29489.11532654024;30116.397672045998;30290.242743930696;30443.391132177676;40151;40255;40445.39331909965;40507.3923891136;40692.38961415522;199762;200681;200895;200932;201023;33640.663593364734;34993.47509787406;47724.522754772006;47740.7612961933;48245;46879;47314.29028564505;47576.286355704;47955.520444795104;47960;38186.80906595449;38353.04117397087;38571;38585.421218682306;38606.80696596499;37524.437133443564;39192.2161556773;40142;40989.79505102456;41274.79362603288;42735.572644274405;42773;43070.56929430791;45107;45245.54754452413;34189;35490;35888;35944.46083308805;36448.27103457969;27811;28855.85572072126;29337;29373.706262937096;29473.55789663114 22:32:04 INFO - PROCESS | 26308 | |2;jquery.html;377.886634009797;380.68593410436387;382.54859266066046;388.21774125137836;389.4761545721004;17399.652006959852;18306.26774929003;18899.905500472512;18924;18965.241390344403;13028.60914172574;17202.225899834506;17690.823091769078;17720.113994300296;17748.112594370294;4897.583705385046;5003.749812509378;5030.37120359955;5032.949670503284;5041.974790126044;3866.671332936704;6519.380658837426;6924.272951340113;6929.549579277356;6933.722651093975;6660.200775906874;6898.724051037952;6907.516473846833;6928.376446119848;6934.4105751010975;6709.463242940553;6907.998340240687;6914.101166848328;6960.0603918471115;6989.440844732435;4231.619154276099;4419.734815911023;6283.7800676976085;6489.02664600309;6496.285408605049;22439.55120897573;22864.77135228643;24488.387790305384;24606.13878514261;24629.753702462924;2411.131992482705;2552.9872350638316;2600.921972340833;2651.125128707522;2989.7309242168308;11917.284962902253;14909;14939.17834519101;15223.467178648694;15448.304826282843;2106.420734298065;2196.2093646287326;2204.8015678589004;2213.513027134028;2262.615355389588;76366;77106.61446692787;77745.99999999943;78116.21883781203;78413;17212;17382.304707811545;17663.29346826138;17703.557411064827;17849.821501784816;27386.863065684545;27422.862885685445;27773.72226277752;27775.16674499768;27920;9728;10051.296409251428;10104.545295461721;10122;10219.7445063874;8544.487330760174;8634.136586341441;8649.264812491008;8733.519656418957;8750.51872147038;7167.671799675979;8459.906576496727;8464.957675211585;8637.740867773977;8653.26447251978;3111.859966301522;3188.9043328700177;3206.0184028852923;3223.9677603224136;3343.9331213375594;906.8775715278485;1030.0729343590679;1058.5448257249457;1071.12703146935;1118.9440527973732;17019.914900425665;17415.73876391855;18466.17318810674;18472.445826625222;18621.162047707887;964.2478866484154;1009.3590569988111;1033.3593172233263;1040.427764729396;1112.8275117356661;10004.599816007423;10125.443100629536;10259.692209233735;10265.897341026493;10315.638952636771;428747;430368;451664;474496;474985;82135.5893220542;82397.17602824015;83192.58403707943;84725;86528.56735716281;1085.3487907255583;1102.1293178389144;1110.9222354435274;1120.0647459371353;1123.9494222759831;15903.840961590236;16176.514704558882;16209.918950405172;16261.67476650484;16483.258253378386;36216.456753148195;36834.63165368312;37630.62369376381;37955.62044379631;38218.617813821504;53157;53780.19329710136;53878.19182712341;54073;54209.72895135499;8029.755387914883;8085.83828323442;8187;8280.171982801672;8308.792280193044;11236.381998989807;12186.87813121893;12193.268403895794;12504;12564.308963006757;10780.29928054674;11106.555737770399;11175.608853690082;11397.601083962021;11426.542938282708;10780.353178809297;10906.672799816019;11057.723556911142;11091.667249982513;11169.72075698114;11075.169362297698;11157.44212789341;11420.543178272776;11496.367699776592;11515.8272625909;7946;8034.236747508978;8071.717489887826;8089.635966381645;8214.096449390681;8589.570521474026;10499.212559058255;11258.493367798632;11357.943210283896;11409.657710268706 22:32:04 INFO - PROCESS | 26308 | |3;mootools.html;1309.155594641457;1455.4542046732474;1474.7124310759407;1658.2952245295753;1832.3220408448885;8046.316063134634;8104.189581041893;8167.305779008791;8209.343252539797;8214.466059706116;8069.354451643854;8244.34045276378;8264.752057438287;8267.710630127931;8277.213664701858;2070.0477780221117;2339.94150146246;2372.632242002492;2398.1846172301416;2403.5793736096202;2403.627437747152;2413.6741539892064;2413.768977821306;2415.2150551070886;2415.2271273192587;2310.1798861404127;2345.44881952741;2360.976390236093;2367.92896213113;2368.3605426534828;2329.3244958961964;2351.8824058797077;2381.7499162587937;2381.940451488709;2386.1052105460453;2317.188983855637;2331.6619090231993;2335.6262997920253;2399.028393500635;2399.9520009599887;8469.068402475785;8471.407001509897;8539.786505337353;8543.27382172516;8552.358573107042;5144.948550514484;5295.232191332274;5296.232046353257;5298.470152984709;5304.36347638282;3498.650134986481;3569.39320315547;3585.31878943;3620;3653.4702468142236;1114.2033446086007;1121.8036843552345;1130.756887269233;1143.114086582905;1147.2026941275817;12073.637790866376;12215.816762748565;12216.328101954388;12238.755224895453;12241.632751017483;4663.090697313991;4708.293755936637;4759.643026773006;4776.498467660915;4785.688930219525;10166.4916754162;10184.694459166238;10231.693049208536;10243.385396876061;10256.07695307426;4619.2378257587125;5262.447443018505;5272.868178295496;5297.920531192035;5298.92051619226;4667.906641867144;4725.385699858996;4733.100710864925;4741.35991641124;4776.140294746979;3565.5899571549085;3650.361186792301;3687.852485900534;3708.054446116223;3747.0445036515516;1263.684078980255;1269.1877198592908;1274.7769140400578;1277.6933535951491;1287.3884904670206;1351.3445978700322;1362.5163067111232;1370.9862901371057;1395.9092658977133;1398.2728980930062;8019.39854510914;8107.391945604103;8133.1866813319375;8136.389770767216;8136.837263254702;486.1371066357244;486.8442098528508;489.3833769450518;490.5021403275678;497.09032470578757;5961.612495187799;5993.160957465958;6089;6117.082437634391;6146.293176284785;9789.46157961319;9918.702438926844;9952.402855828674;9957.352772069793;10076.848847267296;9742;9886.80226395468;9887.901120988698;9949.303548751517;10020.64927727526;7879.803004924923;7935.206479352134;7957.721479748181;7995;8016.839663206703;7821.843563128819;7836.529808211526;7928.84142317162;7929.246721561442;8077.353811695168;9249.676261330822;9291.93142788588;9356.81286374255;9464.526773661428;9503.23974082058;9447.480388578695;9561.378510396802;9605.231581473325;9743.123118919048;9803.068708472683;6738.191417029988;7166.355028047292;7304.452166087458;7467;7564.697412103455;7916.643751031333;7929.246721561442;7951.960240198762;7968.482048666818;7971.920280797118;6982.476314276551;7114.928850711427;7176.138863336433;7206.819829504095;7305.634718264172;7021.719131234693;7082.787516374517;7126.073610430611;7161.749338773118;7207.279272072751;7435.776926692208;7521;7534.773956781305;7559.055118110236;7568.243175682388;6959.478039146983;6963;6963.303669632996;6997.545159564612;7025.156981162088;6980.650967451708;7083.716651333889;7106.395956343768;7142.285771422816;7154.034205382415 22:32:04 INFO - PROCESS | 26308 | |4;prototype.html;345.15437178911674;346.5148791691631;349.88104044624805;350.4322996745272;351.9771214871033;5858.179854820318;5883.058710606304;5884.6763428011445;5928.110783382494;5935.406459354068;5812.447817457345;5824.330202026768;5831.358550559435;5832.62087964282;5840.299164100304;4248.277792775236;4381.737095774248;4443.933340999887;4446.533114023029;4496;4506.278995360726;4509.098180363924;4515.074409746011;4522.68341216115;4523.2988886722615;4476.529964353745;4502.684812063157;4502.864914052568;4512.30059340801;4540.818367265306;4311.719738217022;4380.167768124063;4443.577860103285;4445;4452.1986042512335;3949.5260568731937;3955.8022098895226;3957.1096503286817;3962.1481381503113;3964.781936993464;5695.77216911323;5703.116017017369;5708.657480551139;5717.799877004327;5719.399463056402;5267.604929630293;5274.525292723673;5274.709890955995;5275.894482110375;5277.656952298088;6214.720337584819;6215.595986260879;6217.160683307741;6219.502439804804;6219.720112594944;4342.652587792969;4417.602415782595;4431.556844315543;4444.466664000308;4492.326151077365;6460.160179176724;6504.121943537609;6512.413882750528;6537.411632953057;6547.803565893031;4595.172868883633;4597.678162528591;4599.563041511051;4601.792919318638;4611.16998940194;4651.534846515321;4656.045510670372;4659.44086709597;4665.020345727436;4665.650076244296;5307.787688492417;5314.521693047567;5322.0952438085615;5322.121849894801;5322.813701520428;6173.660448675276;6269.122322874801;6321.146645202839;6330.177076979963;6333.050042493663;1601.49552890839;1628.7638292447766;1639.7048531264486;1640.409452597064;1643;1039.4927355334794;1079.9676009719722;1091.939943303126;1110.5613282753268;1143.6969203161168;5808.186853840466;5818.44724751148;5944.405559444021;6109.083637454329;6120.44915957566;6526.575772574767;6539.313372095957;6545.607263564201;6546.869062618816;6548.148740663683;6049.3950604939155;6059.757609695563;6076.848078798065;6081.2094427725;6110.052941794029;3481.112316359312;3518.683318501347;3522.647735226457;3525;3528.835484290178;6677.833054173588;6780.813357662488;6809.829754256084;6837.3162683731225;6865.347791959756;6651.23510796255;6652;6691.063251144747;6697.464202863759;6698.397144256943;6808.6255255960405;6817.11377520929;6824.351686589766;6830.897536536857;6833.897491537631;6513.902291465726;6524.543281970216;6547.672616369067;6598.274189839018;6625.238097618835;6250.249970003629;6485.967570162119;6494.740210391531;6504.837379065372;6526.347365263436;6828.692708828214;6849;6851.177858656793;6856.394570063543;6856.9314306856295;4640.350350950937;4646.93029604563;4647.093816705845;4647.209974304309;4650.976785107271;6519.8044058678315;6539.149910511603;6540.051692504465;6541.215054193527;6550.377714117151;6100.572959892942;6293.905591416224;6304.495640348853;6329.683515824098;6333.90499142504;6270.2475702915945;6288.842778930563;6318.557700960889;6323.747050117944;6329.082283069021;6196.380361963767;6199.628022318675;6211.223597050369;6212.565120441525;6213.068039794067;6358.523110766803;6368.267649220214;6368.853606350901;6370.203724534434;6370.74517019314;5866.911996319973;5926.288845338415;6203.410675985774;6204.410580994799;6215.285242197205 22:32:04 INFO - PROCESS | 26308 | |5;yui.html;743.4015617427968;747.8018325143836;748.6743266678992;752.4883079505937;784.1256998446731;13429.597112086642;13610.115342502755;13669.521566745167;13719.725605487909;13742.862571374284;12788.72112788723;12874.678133046678;12898.290594017326;12924.870751292485;12955.48178072876;794.6543253684652;811.9188081191893;811.9431639785217;812.0823469479479;813.3980854167903;800.371708209056;803.5459965119688;804.4569915307173;807.8545861744883;808.3613944983456;829.2081062585216;835.7283882738104;837.1628371628371;837.3134030095324;837.5812093953024;831.4720152703047;836.7196989008659;837.3552364679163;838.3963546246692;838.8993320801482;607.2470137030051;609.7103875659027;610.6488768957867;612.1216054961145;612.3233826621608;13563.254021028839;13619.591412257649;13623.795643065361;13639.659008524866;13670;11614.18700690952;11673.1828771986;11790.705232369173;11895.286282823059;12340.5063797448;10498.212634052401;10561.471926403648;10708.089812365959;10714.785704285949;10744.14046876248;3669.21111960927;3726.888193354204;3817.9427308590393;3821.847126114952;3843.903902402434;43439.78280108579;50038.49961500375;50313;50435;51211.231831522186;11232.887671123184;11410.315381077162;11462;11498.827517587242;11654.475548600334;20066.19735210575;20093.799062009486;20216.494587635425;20352.084156213004;20360.89819550893;8373.539455330016;8412.579371031425;8436.535990520457;8506.149385061568;8526.232639062515;4718.764061796896;4722.881927951828;4723.83466578675;4812.1578723723205;4829.806807727722;3333.4166520858753;3353.144948038235;3354.580677415323;3356.8657253710066;3359.412102881986;606.8088552106072;618.783425800975;630.404267966767;632.2349956552531;636.9363063693594;588.867504811414;619.5724949784724;624.865653884417;627.4102343796816;629.2920464477463;8368.707095251637;8509.149085091442;8566.27186689126;8587.484750914966;8761.036286008533;604.2235727090667;623.7203022951121;635.5106567942751;639.2041907824788;641.0640464921267;3868.5937976512623;3922.5489068756888;4164.708470407103;4264.978675106605;4465.12929978651;58267;58421;58651;58701.70649146727;58998;49937.50062499401;50205.24692129621;51445;51997.48002520002;52097.7395113022;45524.317135243;45629.54370456319;46051;47758.522414776104;47809.52190478051;8887.15572020657;9177.311701622539;9277.48973806447;9299.72100836976;9426.528673566158;35898.30762692756;36653.63346366502;36700.082497937765;37023.07442313964;37326;42664.360034600126;44197.779011104736;46106.30840537462;47276.7636161817;47452.525474746195;11192.160587955774;11354.716132096764;11440.485178166837;11538.711532211426;11707.473163707484;12178.391080445765;12646.810297845725;12951.028672849385;13006.934965325112;13413.262270574822;11519.94240028828;11690.70773230642;11769.293842369143;11890.583829565923;11925.34410607425;11337.546498139982;11407.31556106603;11443.027342675965;11540.769184616092;11777.646670599554;11100.555977761122;11556.537738490366;11732.824007639721;12004;12021.338826364634;9670.323077384797;9731;9805.166560842408;9812.362196457207;9814.214862810815;9687.467189304656;9863.605455781688;9864.802703946025;9999.750006249902;10101.949490252502 22:32:04 INFO - PROCESS | 26308 | __end_tp_report 22:32:04 INFO - PROCESS | 26308 | __start_cc_report 22:32:04 INFO - PROCESS | 26308 | _x_x_mozilla_cycle_collect,720 22:32:04 INFO - PROCESS | 26308 | __end_cc_report 22:32:04 INFO - PROCESS | 26308 | __startTimestamp1458711124041__endTimestamp 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | ------- Summary: start ------- 22:32:04 INFO - PROCESS | 26308 | Number of tests: 6 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#0] dojo.html Cycles:165 Average:5319.60 Median:5494.04 stddev:607.97 (11.1%) stddev-sans-first:583.13 22:32:04 INFO - PROCESS | 26308 | Values: 3047.6 3091.4 3126.3 3142.3 3163.4 5170.7 5274.2 5300.4 5309.1 5315.7 5301.2 5352.5 5370.1 5397.8 5407.6 4768.7 4787.0 4813.6 4816.2 4962.8 4877.3 4893.9 4924.0 4942.8 4959.9 4730.2 4769.4 4863.1 4889.9 4923.0 4851.9 4856.4 4860.9 4863.0 4895.7 4818.5 4823.3 4835.4 4845.3 4846.0 5179.7 5253.0 5300.5 5310.9 5338.7 5718.2 5732.6 5733.3 5736.6 5737.9 5665.9 5732.2 5747.6 5753.7 5754.2 5419.0 5443.3 5451.0 5494.1 5496.1 5850.9 5855.6 5859.7 5874.6 5902.0 5517.8 5620.3 5625.9 5633.5 5639.9 5752.8 5758.0 5770.7 5795.8 5805.0 5685.5 5718.8 5727.8 5730.2 5741.2 5669.5 5687.7 5690.2 5728.7 5747.9 4374.9 4376.4 4382.6 4418.7 4424.6 3869.5 3875.2 3885.6 3888.9 3909.0 5284.0 5383.4 5411.9 5433.7 5471.7 5802.4 5821.1 5826.0 5830.6 5844.3 5397.5 5415.2 5417.6 5423.9 5424.3 4885.4 4969.8 5023.0 5031.0 5058.1 5971.3 5982.7 5988.9 5992.1 6009.1 5775.1 5802.8 5806.9 5807.5 5811.8 5868.6 5948.6 6003.3 6041.8 6044.8 5579.1 5621.7 5622.6 5631.8 5639.3 5656.6 5674.2 5715.4 5717.5 5719.3 5449.3 5572.0 5574.3 5612.1 5655.3 5527.3 5533.1 5536.5 5539.7 5541.7 5400.8 5449.3 5457.1 5458.3 5506.1 5548.9 5613.5 5618.1 5618.4 5629.7 5453.1 5455.5 5459.7 5460.5 5494.0 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#1] ext.html Cycles:180 Average:47774.52 Median:19242.41 stddev:81569.35 (423.9%) stddev-sans-first:81721.14 22:32:04 INFO - PROCESS | 26308 | Values: 557.0 562.8 592.8 610.0 634.0 30174.7 38943.4 39026.4 39058.6 39239.0 40778.4 41264.0 41411.2 41432.2 41490.8 8041.0 8557.9 8851.0 8852.8 10239.3 10511.2 10693.6 10701.7 10729.9 10736.6 10655.0 10751.5 10757.5 10765.1 10767.9 10597.0 10691.9 10699.7 10700.0 10703.9 10506.9 10669.1 10669.7 10672.4 10676.5 41237.4 41421.0 41778.0 41865.6 41920.4 3409.6 3491.3 3634.9 3912.5 4011.5 31973.7 32071.5 32442.2 32509.2 33047.3 4230.8 4331.0 4349.7 4356.2 4356.5 303277.0 303987.0 304245.0 305081.0 307004.0 47965.5 48471.3 48633.5 62278.7 62894.1 47330.3 47507.8 47751.3 47882.5 47991.5 15532.8 15696.8 15729.9 15735.9 15741.2 12342.1 12498.4 12512.7 12515.6 12517.7 16202.3 16211.6 16241.4 16257.6 16302.2 6285.7 6357.2 6373.5 6425.5 6484.4 1026.4 1031.4 1138.9 1142.3 1147.7 31715.7 31896.4 32095.2 32247.0 32473.8 1102.3 1148.5 1198.8 1207.6 1220.0 14725.6 15025.3 15072.5 15151.2 15330.3 393644.0 395476.0 395668.0 397018.0 397793.0 55271.4 55578.4 55774.7 55787.7 56078.4 1223.2 1338.7 1345.7 1360.2 1438.7 28049.0 29489.1 30116.4 30290.2 30443.4 40151.0 40255.0 40445.4 40507.4 40692.4 199762.0 200681.0 200895.0 200932.0 201023.0 33640.7 34993.5 47724.5 47740.8 48245.0 46879.0 47314.3 47576.3 47955.5 47960.0 38186.8 38353.0 38571.0 38585.4 38606.8 37524.4 39192.2 40142.0 40989.8 41274.8 42735.6 42773.0 43070.6 45107.0 45245.5 34189.0 35490.0 35888.0 35944.5 36448.3 27811.0 28855.9 29337.0 29373.7 29473.6 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#2] jquery.html Cycles:180 Average:28038.29 Median:27404.86 stddev:74404.47 (271.5%) stddev-sans-first:74584.20 22:32:04 INFO - PROCESS | 26308 | Values: 377.9 380.7 382.5 388.2 389.5 17399.7 18306.3 18899.9 18924.0 18965.2 13028.6 17202.2 17690.8 17720.1 17748.1 4897.6 5003.7 5030.4 5032.9 5042.0 3866.7 6519.4 6924.3 6929.5 6933.7 6660.2 6898.7 6907.5 6928.4 6934.4 6709.5 6908.0 6914.1 6960.1 6989.4 4231.6 4419.7 6283.8 6489.0 6496.3 22439.6 22864.8 24488.4 24606.1 24629.8 2411.1 2553.0 2600.9 2651.1 2989.7 11917.3 14909.0 14939.2 15223.5 15448.3 2106.4 2196.2 2204.8 2213.5 2262.6 76366.0 77106.6 77746.0 78116.2 78413.0 17212.0 17382.3 17663.3 17703.6 17849.8 27386.9 27422.9 27773.7 27775.2 27920.0 9728.0 10051.3 10104.5 10122.0 10219.7 8544.5 8634.1 8649.3 8733.5 8750.5 7167.7 8459.9 8465.0 8637.7 8653.3 3111.9 3188.9 3206.0 3224.0 3343.9 906.9 1030.1 1058.5 1071.1 1118.9 17019.9 17415.7 18466.2 18472.4 18621.2 964.2 1009.4 1033.4 1040.4 1112.8 10004.6 10125.4 10259.7 10265.9 10315.6 428747.0 430368.0 451664.0 474496.0 474985.0 82135.6 82397.2 83192.6 84725.0 86528.6 1085.3 1102.1 1110.9 1120.1 1123.9 15903.8 16176.5 16209.9 16261.7 16483.3 36216.5 36834.6 37630.6 37955.6 38218.6 53157.0 53780.2 53878.2 54073.0 54209.7 8029.8 8085.8 8187.0 8280.2 8308.8 11236.4 12186.9 12193.3 12504.0 12564.3 10780.3 11106.6 11175.6 11397.6 11426.5 10780.4 10906.7 11057.7 11091.7 11169.7 11075.2 11157.4 11420.5 11496.4 11515.8 7946.0 8034.2 8071.7 8089.6 8214.1 8589.6 10499.2 11258.5 11357.9 11409.7 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#3] mootools.html Cycles:180 Average:5889.50 Median:5301.64 stddev:3131.98 (59.1%) stddev-sans-first:3121.84 22:32:04 INFO - PROCESS | 26308 | Values: 1309.2 1455.5 1474.7 1658.3 1832.3 8046.3 8104.2 8167.3 8209.3 8214.5 8069.4 8244.3 8264.8 8267.7 8277.2 2070.0 2339.9 2372.6 2398.2 2403.6 2403.6 2413.7 2413.8 2415.2 2415.2 2310.2 2345.4 2361.0 2367.9 2368.4 2329.3 2351.9 2381.7 2381.9 2386.1 2317.2 2331.7 2335.6 2399.0 2400.0 8469.1 8471.4 8539.8 8543.3 8552.4 5144.9 5295.2 5296.2 5298.5 5304.4 3498.7 3569.4 3585.3 3620.0 3653.5 1114.2 1121.8 1130.8 1143.1 1147.2 12073.6 12215.8 12216.3 12238.8 12241.6 4663.1 4708.3 4759.6 4776.5 4785.7 10166.5 10184.7 10231.7 10243.4 10256.1 4619.2 5262.4 5272.9 5297.9 5298.9 4667.9 4725.4 4733.1 4741.4 4776.1 3565.6 3650.4 3687.9 3708.1 3747.0 1263.7 1269.2 1274.8 1277.7 1287.4 1351.3 1362.5 1371.0 1395.9 1398.3 8019.4 8107.4 8133.2 8136.4 8136.8 486.1 486.8 489.4 490.5 497.1 5961.6 5993.2 6089.0 6117.1 6146.3 9789.5 9918.7 9952.4 9957.4 10076.8 9742.0 9886.8 9887.9 9949.3 10020.6 7879.8 7935.2 7957.7 7995.0 8016.8 7821.8 7836.5 7928.8 7929.2 8077.4 9249.7 9291.9 9356.8 9464.5 9503.2 9447.5 9561.4 9605.2 9743.1 9803.1 6738.2 7166.4 7304.5 7467.0 7564.7 7916.6 7929.2 7952.0 7968.5 7971.9 6982.5 7114.9 7176.1 7206.8 7305.6 7021.7 7082.8 7126.1 7161.7 7207.3 7435.8 7521.0 7534.8 7559.1 7568.2 6959.5 6963.0 6963.3 6997.5 7025.2 6980.7 7083.7 7106.4 7142.3 7154.0 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#4] prototype.html Cycles:180 Average:5285.05 Median:5905.48 stddev:1587.13 (26.9%) stddev-sans-first:1547.67 22:32:04 INFO - PROCESS | 26308 | Values: 345.2 346.5 349.9 350.4 352.0 5858.2 5883.1 5884.7 5928.1 5935.4 5812.4 5824.3 5831.4 5832.6 5840.3 4248.3 4381.7 4443.9 4446.5 4496.0 4506.3 4509.1 4515.1 4522.7 4523.3 4476.5 4502.7 4502.9 4512.3 4540.8 4311.7 4380.2 4443.6 4445.0 4452.2 3949.5 3955.8 3957.1 3962.1 3964.8 5695.8 5703.1 5708.7 5717.8 5719.4 5267.6 5274.5 5274.7 5275.9 5277.7 6214.7 6215.6 6217.2 6219.5 6219.7 4342.7 4417.6 4431.6 4444.5 4492.3 6460.2 6504.1 6512.4 6537.4 6547.8 4595.2 4597.7 4599.6 4601.8 4611.2 4651.5 4656.0 4659.4 4665.0 4665.7 5307.8 5314.5 5322.1 5322.1 5322.8 6173.7 6269.1 6321.1 6330.2 6333.1 1601.5 1628.8 1639.7 1640.4 1643.0 1039.5 1080.0 1091.9 1110.6 1143.7 5808.2 5818.4 5944.4 6109.1 6120.4 6526.6 6539.3 6545.6 6546.9 6548.1 6049.4 6059.8 6076.8 6081.2 6110.1 3481.1 3518.7 3522.6 3525.0 3528.8 6677.8 6780.8 6809.8 6837.3 6865.3 6651.2 6652.0 6691.1 6697.5 6698.4 6808.6 6817.1 6824.4 6830.9 6833.9 6513.9 6524.5 6547.7 6598.3 6625.2 6250.2 6486.0 6494.7 6504.8 6526.3 6828.7 6849.0 6851.2 6856.4 6856.9 4640.4 4646.9 4647.1 4647.2 4651.0 6519.8 6539.1 6540.1 6541.2 6550.4 6100.6 6293.9 6304.5 6329.7 6333.9 6270.2 6288.8 6318.6 6323.7 6329.1 6196.4 6199.6 6211.2 6212.6 6213.1 6358.5 6368.3 6368.9 6370.2 6370.7 5866.9 5926.3 6203.4 6204.4 6215.3 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - PROCESS | 26308 | [#5] yui.html Cycles:180 Average:14156.72 Median:26000.30 stddev:16183.30 (62.2%) stddev-sans-first:16197.35 22:32:04 INFO - PROCESS | 26308 | Values: 743.4 747.8 748.7 752.5 784.1 13429.6 13610.1 13669.5 13719.7 13742.9 12788.7 12874.7 12898.3 12924.9 12955.5 794.7 811.9 811.9 812.1 813.4 800.4 803.5 804.5 807.9 808.4 829.2 835.7 837.2 837.3 837.6 831.5 836.7 837.4 838.4 838.9 607.2 609.7 610.6 612.1 612.3 13563.3 13619.6 13623.8 13639.7 13670.0 11614.2 11673.2 11790.7 11895.3 12340.5 10498.2 10561.5 10708.1 10714.8 10744.1 3669.2 3726.9 3817.9 3821.8 3843.9 43439.8 50038.5 50313.0 50435.0 51211.2 11232.9 11410.3 11462.0 11498.8 11654.5 20066.2 20093.8 20216.5 20352.1 20360.9 8373.5 8412.6 8436.5 8506.1 8526.2 4718.8 4722.9 4723.8 4812.2 4829.8 3333.4 3353.1 3354.6 3356.9 3359.4 606.8 618.8 630.4 632.2 636.9 588.9 619.6 624.9 627.4 629.3 8368.7 8509.1 8566.3 8587.5 8761.0 604.2 623.7 635.5 639.2 641.1 3868.6 3922.5 4164.7 4265.0 4465.1 58267.0 58421.0 58651.0 58701.7 58998.0 49937.5 50205.2 51445.0 51997.5 52097.7 45524.3 45629.5 46051.0 47758.5 47809.5 8887.2 9177.3 9277.5 9299.7 9426.5 35898.3 36653.6 36700.1 37023.1 37326.0 42664.4 44197.8 46106.3 47276.8 47452.5 11192.2 11354.7 11440.5 11538.7 11707.5 12178.4 12646.8 12951.0 13006.9 13413.3 11519.9 11690.7 11769.3 11890.6 11925.3 11337.5 11407.3 11443.0 11540.8 11777.6 11100.6 11556.5 11732.8 12004.0 12021.3 9670.3 9731.0 9805.2 9812.4 9814.2 9687.5 9863.6 9864.8 9999.8 10101.9 22:32:04 INFO - PROCESS | 26308 | -------- Summary: end -------- 22:32:04 INFO - PROCESS | 26308 | 22:32:04 INFO - TEST-INFO | 26308: exit 0 22:32:04 INFO - TEST-OK | dromaeo_css | took 1111386ms 22:32:04 INFO - TEST-START | kraken 22:32:04 INFO - operating with platform_type : linux_ 22:32:04 INFO - Initialising browser for kraken test... 22:32:04 INFO - TEST-INFO | started process 26496 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHbIgjv/profile http://localhost:38186/getInfo.html) 22:32:09 INFO - PROCESS | 26496 | __metrics Screen width/height:1600/1200 22:32:09 INFO - PROCESS | 26496 | colorDepth:24 22:32:09 INFO - PROCESS | 26496 | Browser inner width/height: 1024/697 22:32:09 INFO - PROCESS | 26496 | __metrics 22:32:09 ERROR - PROCESS | 26496 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:32:10 INFO - TEST-INFO | 26496: exit 0 22:32:10 INFO - Browser initialized. 22:32:10 INFO - Running cycle 1/1 for kraken test... 22:32:10 INFO - TEST-INFO | started process 26580 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHbIgjv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:22 INFO - PROCESS | 26580 | RSS: Main: 156626944 22:32:22 INFO - PROCESS | 26580 | 22:32:24 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38186/tests/kraken/driver.html?testName=audio-beat-detection) 22:32:25 INFO - PROCESS | 26580 | RSS: Main: 198914048 22:32:25 INFO - PROCESS | 26580 | 22:32:28 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38186/tests/kraken/driver.html?testName=audio-dft) 22:32:28 INFO - PROCESS | 26580 | RSS: Main: 186859520 22:32:28 INFO - PROCESS | 26580 | 22:32:31 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38186/tests/kraken/driver.html?testName=audio-fft) 22:32:32 INFO - PROCESS | 26580 | RSS: Main: 170078208 22:32:32 INFO - PROCESS | 26580 | 22:32:34 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38186/tests/kraken/driver.html?testName=audio-oscillator) 22:32:34 INFO - PROCESS | 26580 | RSS: Main: 172789760 22:32:34 INFO - PROCESS | 26580 | 22:32:36 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38186/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:32:37 INFO - PROCESS | 26580 | RSS: Main: 174817280 22:32:37 INFO - PROCESS | 26580 | 22:32:40 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38186/tests/kraken/driver.html?testName=imaging-darkroom) 22:32:41 INFO - PROCESS | 26580 | RSS: Main: 176390144 22:32:41 INFO - PROCESS | 26580 | 22:32:44 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38186/tests/kraken/driver.html?testName=imaging-desaturate) 22:32:44 INFO - PROCESS | 26580 | RSS: Main: 176623616 22:32:44 INFO - PROCESS | 26580 | 22:32:47 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38186/tests/kraken/driver.html?testName=json-parse-financial) 22:32:48 INFO - PROCESS | 26580 | RSS: Main: 175771648 22:32:48 INFO - PROCESS | 26580 | 22:32:49 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38186/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:32:50 INFO - PROCESS | 26580 | RSS: Main: 187613184 22:32:50 INFO - PROCESS | 26580 | 22:32:52 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:32:52 INFO - PROCESS | 26580 | RSS: Main: 184426496 22:32:52 INFO - PROCESS | 26580 | 22:32:54 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:32:55 INFO - PROCESS | 26580 | RSS: Main: 193847296 22:32:55 INFO - PROCESS | 26580 | 22:32:57 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:32:58 INFO - PROCESS | 26580 | RSS: Main: 198696960 22:32:58 INFO - PROCESS | 26580 | 22:33:00 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:33:01 INFO - PROCESS | 26580 | RSS: Main: 196952064 22:33:01 INFO - PROCESS | 26580 | 22:33:02 INFO - PROCESS | 26580 | Cycle 1(1): loaded http://localhost:38186/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38186/tests/kraken/driver.html?testName=ai-astar) 22:33:03 INFO - PROCESS | 26580 | RSS: Main: 258654208 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | __start_tp_report 22:33:03 INFO - PROCESS | 26580 | _x_x_mozilla_page_load 22:33:03 INFO - PROCESS | 26580 | _x_x_mozilla_page_load_details 22:33:03 INFO - PROCESS | 26580 | |i|pagename|runs| 22:33:03 INFO - PROCESS | 26580 | |0;ai-astar;110;109;104;109;109;110;100;109;107;108 22:33:03 INFO - PROCESS | 26580 | |1;audio-beat-detection;150;125;148;170;129;156;143;126;168;127 22:33:03 INFO - PROCESS | 26580 | |2;audio-dft;161;160;163;162;162;161;160;165;161;162 22:33:03 INFO - PROCESS | 26580 | |3;audio-fft;73;74;94;98;72;73;72;120;99;72 22:33:03 INFO - PROCESS | 26580 | |4;audio-oscillator;92;92;146;92;92;92;131;92;92;141 22:33:03 INFO - PROCESS | 26580 | |5;imaging-gaussian-blur;118;117;117;117;118;117;117;117;118;118 22:33:03 INFO - PROCESS | 26580 | |6;imaging-darkroom;145;146;146;146;147;146;146;146;146;146 22:33:03 INFO - PROCESS | 26580 | |7;imaging-desaturate;96;96;96;97;95;96;96;95;96;96 22:33:03 INFO - PROCESS | 26580 | |8;json-parse-financial;57;57;57;56;56;56;57;65;54;55 22:33:03 INFO - PROCESS | 26580 | |9;json-stringify-tinderbox;61;61;60;61;61;61;61;62;61;61 22:33:03 INFO - PROCESS | 26580 | |10;stanford-crypto-aes;76;77;78;77;77;85;77;79;79;78 22:33:03 INFO - PROCESS | 26580 | |11;stanford-crypto-ccm;109;111;107;108;108;111;114;109;109;111 22:33:03 INFO - PROCESS | 26580 | |12;stanford-crypto-pbkdf2;144;147;144;143;143;145;146;144;142;149 22:33:03 INFO - PROCESS | 26580 | |13;stanford-crypto-sha256-iterative;63;64;66;65;63;63;61;64;62;64 22:33:03 INFO - PROCESS | 26580 | __end_tp_report 22:33:03 INFO - PROCESS | 26580 | __start_cc_report 22:33:03 INFO - PROCESS | 26580 | _x_x_mozilla_cycle_collect,1036 22:33:03 INFO - PROCESS | 26580 | __end_cc_report 22:33:03 INFO - PROCESS | 26580 | __startTimestamp1458711183233__endTimestamp 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | ------- Summary: start ------- 22:33:03 INFO - PROCESS | 26580 | Number of tests: 14 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#0] ai-astar Cycles:10 Average:107.50 Median:109.00 stddev:3.17 (2.9%) stddev-sans-first:3.23 22:33:03 INFO - PROCESS | 26580 | Values: 110.0 109.0 104.0 109.0 109.0 110.0 100.0 109.0 107.0 108.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:149.00 stddev:17.15 (11.5%) stddev-sans-first:18.06 22:33:03 INFO - PROCESS | 26580 | Values: 150.0 125.0 148.0 170.0 129.0 156.0 143.0 126.0 168.0 127.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.49 (0.9%) stddev-sans-first:1.56 22:33:03 INFO - PROCESS | 26580 | Values: 161.0 160.0 163.0 162.0 162.0 161.0 160.0 165.0 161.0 162.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#3] audio-fft Cycles:10 Average:84.70 Median:73.50 stddev:16.95 (23.1%) stddev-sans-first:17.44 22:33:03 INFO - PROCESS | 26580 | Values: 73.0 74.0 94.0 98.0 72.0 73.0 72.0 120.0 99.0 72.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:23.15 (25.2%) stddev-sans-first:23.97 22:33:03 INFO - PROCESS | 26580 | Values: 92.0 92.0 146.0 92.0 92.0 92.0 131.0 92.0 92.0 141.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:33:03 INFO - PROCESS | 26580 | Values: 118.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 118.0 118.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 22:33:03 INFO - PROCESS | 26580 | Values: 145.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:33:03 INFO - PROCESS | 26580 | Values: 96.0 96.0 96.0 97.0 95.0 96.0 96.0 95.0 96.0 96.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:2.98 (5.2%) stddev-sans-first:3.16 22:33:03 INFO - PROCESS | 26580 | Values: 57.0 57.0 57.0 56.0 56.0 56.0 57.0 65.0 54.0 55.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 22:33:03 INFO - PROCESS | 26580 | Values: 61.0 61.0 60.0 61.0 61.0 61.0 61.0 62.0 61.0 61.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:2.54 (3.3%) stddev-sans-first:2.55 22:33:03 INFO - PROCESS | 26580 | Values: 76.0 77.0 78.0 77.0 77.0 85.0 77.0 79.0 79.0 78.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#11] stanford-crypto-ccm Cycles:10 Average:109.70 Median:110.00 stddev:2.06 (1.9%) stddev-sans-first:2.17 22:33:03 INFO - PROCESS | 26580 | Values: 109.0 111.0 107.0 108.0 108.0 111.0 114.0 109.0 109.0 111.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.70 Median:144.50 stddev:2.11 (1.5%) stddev-sans-first:2.22 22:33:03 INFO - PROCESS | 26580 | Values: 144.0 147.0 144.0 143.0 143.0 145.0 146.0 144.0 142.0 149.0 22:33:03 INFO - PROCESS | 26580 | 22:33:03 INFO - PROCESS | 26580 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.00 stddev:1.43 (2.2%) stddev-sans-first:1.51 22:33:03 INFO - PROCESS | 26580 | Values: 63.0 64.0 66.0 65.0 63.0 63.0 61.0 64.0 62.0 64.0 22:33:03 INFO - PROCESS | 26580 | -------- Summary: end -------- 22:33:03 INFO - PROCESS | 26580 | 22:33:04 INFO - TEST-INFO | 26580: exit 0 22:33:04 INFO - TEST-OK | kraken | took 59434ms 22:33:04 INFO - SUITE-END | took 1170s 22:33:04 INFO - Completed test suite (00:19:31) 22:33:04 INFO - javascript benchmark 22:33:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3047.5733397324375, 3091.4126315999943, 3126.2965832687646, 3142.3401085772, 3163.446396880546, 5170.741462926858, 5274.235235890794, 5300.443453437391, 5309.097453432916, 5315.654482458637, 5301.151815709478, 5352.545033672132, 5370.060239458089, 5397.784088636459, 5407.567394608432, 4768.737719425439, 4786.952130478686, 4813.6389770767155, 4816.181249187629, 4962.776675049613, 4877.292792545079, 4893.853184404474, 4924.039812236598, 4942.827001054964, 4959.876003099934, 4730.172219861528, 4769.356136921506, 4863.148948933923, 4889.8777530561665, 4922.950770492303, 4851.878703032417, 4856.39295088114, 4860.927086093711, 4862.95137048632, 4895.657303988723, 4818.518148185189, 4823.300621409911, 4835.371401717754, 4845.3216549683075, 4845.951540484621, 5179.689218646893, 5253.028189784902, 5300.496452836975, 5310.920336194921, 5338.706371149585, 5718.170865224519, 5732.5987180897355, 5733.340665823442, 5736.598438109334, 5737.88524229513, 5665.915011274834, 5732.168835518868, 5747.56893233005, 5753.741081651336, 5754.165645981309, 5418.972905135489, 5443.265159203536, 5451.04606693827, 5494.066008778517, 5496.120620700669, 5850.9414905851245, 5855.560832937462, 5859.677717725481, 5874.5887787854435, 5902.026165682614, 5517.779288828482, 5620.297462817148, 5625.8593535161945, 5633.521150702236, 5639.915401268984, 5752.827415177551, 5758.0, 5770.711464426846, 5795.797147099831, 5805.042168042226, 5685.545156387479, 5718.828435146952, 5727.77088916447, 5730.19777231188, 5741.224934633771, 5669.518090962232, 5687.658740475585, 5690.2033715279895, 5728.742206600713, 5747.942520574741, 4374.934375984363, 4376.3654270130655, 4382.561743825656, 4418.734875907456, 4424.646028317791, 3869.5163104611925, 3875.2443273561385, 3885.592012838668, 3888.9027774305305, 3908.9609103908597, 5283.973580132151, 5383.380911195262, 5411.918821217685, 5433.701146436984, 5471.671699697951, 5802.448767367094, 5821.126830975388, 5826.038703613941, 5830.562707796932, 5844.298684157929, 5397.514223719884, 5415.214793854869, 5417.6478528895495, 5423.945760542344, 5424.267723857229, 4885.389326334208, 4969.82605608802, 5023.0, 5030.974845125751, 5058.140116180185, 5971.343152253248, 5982.670953097621, 5988.9101663475085, 5992.131140984533, 6009.06859436788, 5775.104858746985, 5802.796902108406, 5806.883862322646, 5807.477327040587, 5811.767529298781, 5868.618539794899, 5948.5538584605465, 6003.339632640494, 6041.848953776191, 6044.758209671564, 5579.107342825128, 5621.747021383966, 5622.5501959842295, 5631.774729010794, 5639.2668953035845, 5656.60403771732, 5674.177244299636, 5715.371309156073, 5717.485426311652, 5719.342275638355, 5449.264349312951, 5571.9721401392735, 5574.303212098488, 5612.102063669793, 5655.349634792052, 5527.2538207341495, 5533.114701647717, 5536.474034966672, 5539.695316757617, 5541.667499950016, 5400.810971615974, 5449.34607847045, 5457.072297709319, 5458.263134476795, 5506.091494903456, 5548.889022219614, 5613.494785469327, 5618.101103823368, 5618.353889302783, 5629.718514074362, 5453.10023846061, 5455.481729235875, 5459.727013649381, 5460.453954604508, 5494.038543254835], "unit": "score", "name": "dojo.html", "value": 5278.398975559685}, {"lowerIsBetter": false, "replicates": [557.0145515378649, 562.7692646015134, 592.7717828635975, 609.9847503812404, 634.0394302631516, 30174.698253017465, 38943.41584876229, 39026.41460378097, 39058.60941390589, 39239.0, 40778.38832417516, 41264.0, 41411.17177656445, 41432.17135657285, 41490.79254603723, 8040.959795201045, 8557.871631925525, 8851.026387097414, 8852.778680533005, 10239.283250172492, 10511.1591072714, 10693.572257109707, 10701.732456688567, 10729.892701073006, 10736.570537178504, 10655.04104630583, 10751.462426878665, 10757.462126893624, 10765.084967777748, 10767.946160269188, 10597.0, 10691.893081069207, 10699.679009629723, 10700.036996670337, 10703.946480267627, 10506.894931050669, 10669.146468282519, 10669.733256668645, 10672.413017284047, 10676.51955661997, 41237.38143927843, 41421.0, 41777.95555111116, 41865.58134418678, 41920.37119443211, 3409.62494125646, 3491.336646037245, 3634.9273014539563, 3912.471816304819, 4011.4785077939973, 31973.680263197304, 32071.51892721611, 32442.18894527632, 32509.187270318194, 33047.33905321904, 4230.76730779807, 4331.0, 4349.652027837766, 4356.172327257811, 4356.4772227332605, 303277.0, 303987.0, 304245.0, 305081.0, 307004.0, 47965.5203447968, 48471.27293090607, 48633.5136648636, 62278.68860655758, 62894.0565891512, 47330.290045649344, 47507.76246118816, 47751.283730744064, 47882.521174788504, 47991.52008479871, 15532.835037372242, 15696.76454853178, 15729.921350393175, 15735.921320393554, 15741.212939352987, 12342.13605047638, 12498.375081245902, 12512.687182820502, 12515.56195533152, 12517.74964500723, 16202.270897809627, 16211.594710132105, 16241.431549895697, 16257.59356016109, 16302.184890755414, 6285.685715714196, 6357.237131544244, 6373.5143294114405, 6425.453836423956, 6484.383983521652, 1026.38416949831, 1031.4430207687915, 1138.8633363996373, 1142.2860712054965, 1147.6729132197283, 31715.682843171733, 31896.362072758417, 32095.197620059684, 32247.0, 32473.837630811693, 1102.3385968418884, 1148.5176225985106, 1198.7782260281876, 1207.589419597341, 1219.9512019519298, 14725.631859203606, 15025.323860426308, 15072.472463463726, 15151.19983747812, 15330.310136043905, 393644.0, 395476.0, 395668.0, 397018.0, 397793.0, 55271.44728552663, 55578.44421555733, 55774.721126394106, 55787.72106139443, 56078.43921560732, 1223.1988047828686, 1338.7389459055585, 1345.7173993461483, 1360.211077575004, 1438.7122575484946, 28049.0, 29489.11532654024, 30116.397672045998, 30290.242743930696, 30443.391132177676, 40151.0, 40255.0, 40445.39331909965, 40507.3923891136, 40692.38961415522, 199762.0, 200681.0, 200895.0, 200932.0, 201023.0, 33640.663593364734, 34993.47509787406, 47724.522754772006, 47740.7612961933, 48245.0, 46879.0, 47314.29028564505, 47576.286355704, 47955.520444795104, 47960.0, 38186.80906595449, 38353.04117397087, 38571.0, 38585.421218682306, 38606.80696596499, 37524.437133443564, 39192.2161556773, 40142.0, 40989.79505102456, 41274.79362603288, 42735.572644274405, 42773.0, 43070.56929430791, 45107.0, 45245.54754452413, 34189.0, 35490.0, 35888.0, 35944.46083308805, 36448.27103457969, 27811.0, 28855.85572072126, 29337.0, 29373.706262937096, 29473.55789663114], "unit": "score", "name": "ext.html", "value": 19390.883441258055}, {"lowerIsBetter": false, "replicates": [377.886634009797, 380.68593410436387, 382.54859266066046, 388.21774125137836, 389.4761545721004, 17399.652006959852, 18306.26774929003, 18899.905500472512, 18924.0, 18965.241390344403, 13028.60914172574, 17202.225899834506, 17690.823091769078, 17720.113994300296, 17748.112594370294, 4897.583705385046, 5003.749812509378, 5030.37120359955, 5032.949670503284, 5041.974790126044, 3866.671332936704, 6519.380658837426, 6924.272951340113, 6929.549579277356, 6933.722651093975, 6660.200775906874, 6898.724051037952, 6907.516473846833, 6928.376446119848, 6934.4105751010975, 6709.463242940553, 6907.998340240687, 6914.101166848328, 6960.0603918471115, 6989.440844732435, 4231.619154276099, 4419.734815911023, 6283.7800676976085, 6489.02664600309, 6496.285408605049, 22439.55120897573, 22864.77135228643, 24488.387790305384, 24606.13878514261, 24629.753702462924, 2411.131992482705, 2552.9872350638316, 2600.921972340833, 2651.125128707522, 2989.7309242168308, 11917.284962902253, 14909.0, 14939.17834519101, 15223.467178648694, 15448.304826282843, 2106.420734298065, 2196.2093646287326, 2204.8015678589004, 2213.513027134028, 2262.615355389588, 76366.0, 77106.61446692787, 77745.99999999943, 78116.21883781203, 78413.0, 17212.0, 17382.304707811545, 17663.29346826138, 17703.557411064827, 17849.821501784816, 27386.863065684545, 27422.862885685445, 27773.72226277752, 27775.16674499768, 27920.0, 9728.0, 10051.296409251428, 10104.545295461721, 10122.0, 10219.7445063874, 8544.487330760174, 8634.136586341441, 8649.264812491008, 8733.519656418957, 8750.51872147038, 7167.671799675979, 8459.906576496727, 8464.957675211585, 8637.740867773977, 8653.26447251978, 3111.859966301522, 3188.9043328700177, 3206.0184028852923, 3223.9677603224136, 3343.9331213375594, 906.8775715278485, 1030.0729343590679, 1058.5448257249457, 1071.12703146935, 1118.9440527973732, 17019.914900425665, 17415.73876391855, 18466.17318810674, 18472.445826625222, 18621.162047707887, 964.2478866484154, 1009.3590569988111, 1033.3593172233263, 1040.427764729396, 1112.8275117356661, 10004.599816007423, 10125.443100629536, 10259.692209233735, 10265.897341026493, 10315.638952636771, 428747.0, 430368.0, 451664.0, 474496.0, 474985.0, 82135.5893220542, 82397.17602824015, 83192.58403707943, 84725.0, 86528.56735716281, 1085.3487907255583, 1102.1293178389144, 1110.9222354435274, 1120.0647459371353, 1123.9494222759831, 15903.840961590236, 16176.514704558882, 16209.918950405172, 16261.67476650484, 16483.258253378386, 36216.456753148195, 36834.63165368312, 37630.62369376381, 37955.62044379631, 38218.617813821504, 53157.0, 53780.19329710136, 53878.19182712341, 54073.0, 54209.72895135499, 8029.755387914883, 8085.83828323442, 8187.0, 8280.171982801672, 8308.792280193044, 11236.381998989807, 12186.87813121893, 12193.268403895794, 12504.0, 12564.308963006757, 10780.29928054674, 11106.555737770399, 11175.608853690082, 11397.601083962021, 11426.542938282708, 10780.353178809297, 10906.672799816019, 11057.723556911142, 11091.667249982513, 11169.72075698114, 11075.169362297698, 11157.44212789341, 11420.543178272776, 11496.367699776592, 11515.8272625909, 7946.0, 8034.236747508978, 8071.717489887826, 8089.635966381645, 8214.096449390681, 8589.570521474026, 10499.212559058255, 11258.493367798632, 11357.943210283896, 11409.657710268706], "unit": "score", "name": "jquery.html", "value": 9833.312698964655}, {"lowerIsBetter": false, "replicates": [1309.155594641457, 1455.4542046732474, 1474.7124310759407, 1658.2952245295753, 1832.3220408448885, 8046.316063134634, 8104.189581041893, 8167.305779008791, 8209.343252539797, 8214.466059706116, 8069.354451643854, 8244.34045276378, 8264.752057438287, 8267.710630127931, 8277.213664701858, 2070.0477780221117, 2339.94150146246, 2372.632242002492, 2398.1846172301416, 2403.5793736096202, 2403.627437747152, 2413.6741539892064, 2413.768977821306, 2415.2150551070886, 2415.2271273192587, 2310.1798861404127, 2345.44881952741, 2360.976390236093, 2367.92896213113, 2368.3605426534828, 2329.3244958961964, 2351.8824058797077, 2381.7499162587937, 2381.940451488709, 2386.1052105460453, 2317.188983855637, 2331.6619090231993, 2335.6262997920253, 2399.028393500635, 2399.9520009599887, 8469.068402475785, 8471.407001509897, 8539.786505337353, 8543.27382172516, 8552.358573107042, 5144.948550514484, 5295.232191332274, 5296.232046353257, 5298.470152984709, 5304.36347638282, 3498.650134986481, 3569.39320315547, 3585.31878943, 3620.0, 3653.4702468142236, 1114.2033446086007, 1121.8036843552345, 1130.756887269233, 1143.114086582905, 1147.2026941275817, 12073.637790866376, 12215.816762748565, 12216.328101954388, 12238.755224895453, 12241.632751017483, 4663.090697313991, 4708.293755936637, 4759.643026773006, 4776.498467660915, 4785.688930219525, 10166.4916754162, 10184.694459166238, 10231.693049208536, 10243.385396876061, 10256.07695307426, 4619.2378257587125, 5262.447443018505, 5272.868178295496, 5297.920531192035, 5298.92051619226, 4667.906641867144, 4725.385699858996, 4733.100710864925, 4741.35991641124, 4776.140294746979, 3565.5899571549085, 3650.361186792301, 3687.852485900534, 3708.054446116223, 3747.0445036515516, 1263.684078980255, 1269.1877198592908, 1274.7769140400578, 1277.6933535951491, 1287.3884904670206, 1351.3445978700322, 1362.5163067111232, 1370.9862901371057, 1395.9092658977133, 1398.2728980930062, 8019.39854510914, 8107.391945604103, 8133.1866813319375, 8136.389770767216, 8136.837263254702, 486.1371066357244, 486.8442098528508, 489.3833769450518, 490.5021403275678, 497.09032470578757, 5961.612495187799, 5993.160957465958, 6089.0, 6117.082437634391, 6146.293176284785, 9789.46157961319, 9918.702438926844, 9952.402855828674, 9957.352772069793, 10076.848847267296, 9742.0, 9886.80226395468, 9887.901120988698, 9949.303548751517, 10020.64927727526, 7879.803004924923, 7935.206479352134, 7957.721479748181, 7995.0, 8016.839663206703, 7821.843563128819, 7836.529808211526, 7928.84142317162, 7929.246721561442, 8077.353811695168, 9249.676261330822, 9291.93142788588, 9356.81286374255, 9464.526773661428, 9503.23974082058, 9447.480388578695, 9561.378510396802, 9605.231581473325, 9743.123118919048, 9803.068708472683, 6738.191417029988, 7166.355028047292, 7304.452166087458, 7467.0, 7564.697412103455, 7916.643751031333, 7929.246721561442, 7951.960240198762, 7968.482048666818, 7971.920280797118, 6982.476314276551, 7114.928850711427, 7176.138863336433, 7206.819829504095, 7305.634718264172, 7021.719131234693, 7082.787516374517, 7126.073610430611, 7161.749338773118, 7207.279272072751, 7435.776926692208, 7521.0, 7534.773956781305, 7559.055118110236, 7568.243175682388, 6959.478039146983, 6963.0, 6963.303669632996, 6997.545159564612, 7025.156981162088, 6980.650967451708, 7083.716651333889, 7106.395956343768, 7142.285771422816, 7154.034205382415], "unit": "score", "name": "mootools.html", "value": 4724.468862481971}, {"lowerIsBetter": false, "replicates": [345.15437178911674, 346.5148791691631, 349.88104044624805, 350.4322996745272, 351.9771214871033, 5858.179854820318, 5883.058710606304, 5884.6763428011445, 5928.110783382494, 5935.406459354068, 5812.447817457345, 5824.330202026768, 5831.358550559435, 5832.62087964282, 5840.299164100304, 4248.277792775236, 4381.737095774248, 4443.933340999887, 4446.533114023029, 4496.0, 4506.278995360726, 4509.098180363924, 4515.074409746011, 4522.68341216115, 4523.2988886722615, 4476.529964353745, 4502.684812063157, 4502.864914052568, 4512.30059340801, 4540.818367265306, 4311.719738217022, 4380.167768124063, 4443.577860103285, 4445.0, 4452.1986042512335, 3949.5260568731937, 3955.8022098895226, 3957.1096503286817, 3962.1481381503113, 3964.781936993464, 5695.77216911323, 5703.116017017369, 5708.657480551139, 5717.799877004327, 5719.399463056402, 5267.604929630293, 5274.525292723673, 5274.709890955995, 5275.894482110375, 5277.656952298088, 6214.720337584819, 6215.595986260879, 6217.160683307741, 6219.502439804804, 6219.720112594944, 4342.652587792969, 4417.602415782595, 4431.556844315543, 4444.466664000308, 4492.326151077365, 6460.160179176724, 6504.121943537609, 6512.413882750528, 6537.411632953057, 6547.803565893031, 4595.172868883633, 4597.678162528591, 4599.563041511051, 4601.792919318638, 4611.16998940194, 4651.534846515321, 4656.045510670372, 4659.44086709597, 4665.020345727436, 4665.650076244296, 5307.787688492417, 5314.521693047567, 5322.0952438085615, 5322.121849894801, 5322.813701520428, 6173.660448675276, 6269.122322874801, 6321.146645202839, 6330.177076979963, 6333.050042493663, 1601.49552890839, 1628.7638292447766, 1639.7048531264486, 1640.409452597064, 1643.0, 1039.4927355334794, 1079.9676009719722, 1091.939943303126, 1110.5613282753268, 1143.6969203161168, 5808.186853840466, 5818.44724751148, 5944.405559444021, 6109.083637454329, 6120.44915957566, 6526.575772574767, 6539.313372095957, 6545.607263564201, 6546.869062618816, 6548.148740663683, 6049.3950604939155, 6059.757609695563, 6076.848078798065, 6081.2094427725, 6110.052941794029, 3481.112316359312, 3518.683318501347, 3522.647735226457, 3525.0, 3528.835484290178, 6677.833054173588, 6780.813357662488, 6809.829754256084, 6837.3162683731225, 6865.347791959756, 6651.23510796255, 6652.0, 6691.063251144747, 6697.464202863759, 6698.397144256943, 6808.6255255960405, 6817.11377520929, 6824.351686589766, 6830.897536536857, 6833.897491537631, 6513.902291465726, 6524.543281970216, 6547.672616369067, 6598.274189839018, 6625.238097618835, 6250.249970003629, 6485.967570162119, 6494.740210391531, 6504.837379065372, 6526.347365263436, 6828.692708828214, 6849.0, 6851.177858656793, 6856.394570063543, 6856.9314306856295, 4640.350350950937, 4646.93029604563, 4647.093816705845, 4647.209974304309, 4650.976785107271, 6519.8044058678315, 6539.149910511603, 6540.051692504465, 6541.215054193527, 6550.377714117151, 6100.572959892942, 6293.905591416224, 6304.495640348853, 6329.683515824098, 6333.90499142504, 6270.2475702915945, 6288.842778930563, 6318.557700960889, 6323.747050117944, 6329.082283069021, 6196.380361963767, 6199.628022318675, 6211.223597050369, 6212.565120441525, 6213.068039794067, 6358.523110766803, 6368.267649220214, 6368.853606350901, 6370.203724534434, 6370.74517019314, 5866.911996319973, 5926.288845338415, 6203.410675985774, 6204.410580994799, 6215.285242197205], "unit": "score", "name": "prototype.html", "value": 4776.084317109687}, {"lowerIsBetter": false, "replicates": [743.4015617427968, 747.8018325143836, 748.6743266678992, 752.4883079505937, 784.1256998446731, 13429.597112086642, 13610.115342502755, 13669.521566745167, 13719.725605487909, 13742.862571374284, 12788.72112788723, 12874.678133046678, 12898.290594017326, 12924.870751292485, 12955.48178072876, 794.6543253684652, 811.9188081191893, 811.9431639785217, 812.0823469479479, 813.3980854167903, 800.371708209056, 803.5459965119688, 804.4569915307173, 807.8545861744883, 808.3613944983456, 829.2081062585216, 835.7283882738104, 837.1628371628371, 837.3134030095324, 837.5812093953024, 831.4720152703047, 836.7196989008659, 837.3552364679163, 838.3963546246692, 838.8993320801482, 607.2470137030051, 609.7103875659027, 610.6488768957867, 612.1216054961145, 612.3233826621608, 13563.254021028839, 13619.591412257649, 13623.795643065361, 13639.659008524866, 13670.0, 11614.18700690952, 11673.1828771986, 11790.705232369173, 11895.286282823059, 12340.5063797448, 10498.212634052401, 10561.471926403648, 10708.089812365959, 10714.785704285949, 10744.14046876248, 3669.21111960927, 3726.888193354204, 3817.9427308590393, 3821.847126114952, 3843.903902402434, 43439.78280108579, 50038.49961500375, 50313.0, 50435.0, 51211.231831522186, 11232.887671123184, 11410.315381077162, 11462.0, 11498.827517587242, 11654.475548600334, 20066.19735210575, 20093.799062009486, 20216.494587635425, 20352.084156213004, 20360.89819550893, 8373.539455330016, 8412.579371031425, 8436.535990520457, 8506.149385061568, 8526.232639062515, 4718.764061796896, 4722.881927951828, 4723.83466578675, 4812.1578723723205, 4829.806807727722, 3333.4166520858753, 3353.144948038235, 3354.580677415323, 3356.8657253710066, 3359.412102881986, 606.8088552106072, 618.783425800975, 630.404267966767, 632.2349956552531, 636.9363063693594, 588.867504811414, 619.5724949784724, 624.865653884417, 627.4102343796816, 629.2920464477463, 8368.707095251637, 8509.149085091442, 8566.27186689126, 8587.484750914966, 8761.036286008533, 604.2235727090667, 623.7203022951121, 635.5106567942751, 639.2041907824788, 641.0640464921267, 3868.5937976512623, 3922.5489068756888, 4164.708470407103, 4264.978675106605, 4465.12929978651, 58267.0, 58421.0, 58651.0, 58701.70649146727, 58998.0, 49937.50062499401, 50205.24692129621, 51445.0, 51997.48002520002, 52097.7395113022, 45524.317135243, 45629.54370456319, 46051.0, 47758.522414776104, 47809.52190478051, 8887.15572020657, 9177.311701622539, 9277.48973806447, 9299.72100836976, 9426.528673566158, 35898.30762692756, 36653.63346366502, 36700.082497937765, 37023.07442313964, 37326.0, 42664.360034600126, 44197.779011104736, 46106.30840537462, 47276.7636161817, 47452.525474746195, 11192.160587955774, 11354.716132096764, 11440.485178166837, 11538.711532211426, 11707.473163707484, 12178.391080445765, 12646.810297845725, 12951.028672849385, 13006.934965325112, 13413.262270574822, 11519.94240028828, 11690.70773230642, 11769.293842369143, 11890.583829565923, 11925.34410607425, 11337.546498139982, 11407.31556106603, 11443.027342675965, 11540.769184616092, 11777.646670599554, 11100.555977761122, 11556.537738490366, 11732.824007639721, 12004.0, 12021.338826364634, 9670.323077384797, 9731.0, 9805.166560842408, 9812.362196457207, 9814.214862810815, 9687.467189304656, 9863.605455781688, 9864.802703946025, 9999.750006249902, 10101.949490252502], "unit": "score", "name": "yui.html", "value": 6467.777491956052}], "name": "dromaeo_css", "value": 7263.901869451284}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 104.0, 109.0, 109.0, 110.0, 100.0, 109.0, 107.0, 108.0], "unit": "score", "name": "ai-astar", "value": 107.5}, {"lowerIsBetter": true, "replicates": [150.0, 125.0, 148.0, 170.0, 129.0, 156.0, 143.0, 126.0, 168.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 144.2}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 163.0, 162.0, 162.0, 161.0, 160.0, 165.0, 161.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.7}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 94.0, 98.0, 72.0, 73.0, 72.0, 120.0, 99.0, 72.0], "unit": "score", "name": "audio-fft", "value": 84.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 146.0, 92.0, 92.0, 92.0, 131.0, 92.0, 92.0, 141.0], "unit": "score", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.4}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 97.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 56.0, 56.0, 56.0, 57.0, 65.0, 54.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 61.0}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 78.0, 77.0, 77.0, 85.0, 77.0, 79.0, 79.0, 78.0], "unit": "score", "name": "stanford-crypto-aes", "value": 78.3}, {"lowerIsBetter": true, "replicates": [109.0, 111.0, 107.0, 108.0, 108.0, 111.0, 114.0, 109.0, 109.0, 111.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 109.7}, {"lowerIsBetter": true, "replicates": [144.0, 147.0, 144.0, 143.0, 143.0, 145.0, 146.0, 144.0, 142.0, 149.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.7}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 66.0, 65.0, 63.0, 63.0, 61.0, 64.0, 62.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 63.5}], "name": "kraken", "value": 1477.8}]} 22:33:04 INFO - Return code: 0 22:33:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:33:04 ERROR - # TBPL SUCCESS # 22:33:04 INFO - Running post-action listener: _resource_record_post_action 22:33:04 INFO - Running post-run listener: _resource_record_post_run 22:33:05 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546590720; Read time: 28; Write time: 649032 22:33:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 209969152; Read time: 0; Write time: 362428 22:33:05 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291704832; Read time: 28; Write time: 263204 22:33:05 INFO - Running post-run listener: _upload_blobber_files 22:33:05 INFO - Blob upload gear active. 22:33:05 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:33:05 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:33:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 22:33:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 22:33:06 INFO - (blobuploader) - INFO - Open directory for files ... 22:33:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:33:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:33:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:07 INFO - (blobuploader) - INFO - Done attempting. 22:33:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:33:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:33:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:08 INFO - (blobuploader) - INFO - Done attempting. 22:33:08 INFO - (blobuploader) - INFO - Iteration through files over. 22:33:08 INFO - Return code: 0 22:33:08 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:33:08 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:33:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0577cb62de2fea64a2a012e8af83f662b2706ad09daa2aaf901b91b013b7cb80f89333b00764502a2e85f8037e3fd3106b3505052f60d463f7c079db90c3ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d8d8b5007b57d70c8ddecdce9971c421c0123508ee1635cb8a3a9732d7be1aa4a803f013bb8bcfe67910d21ddc216fc7e49a68646aa8c4bfaa5b7c995e019e"} 22:33:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:33:08 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:33:08 INFO - Contents: 22:33:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0577cb62de2fea64a2a012e8af83f662b2706ad09daa2aaf901b91b013b7cb80f89333b00764502a2e85f8037e3fd3106b3505052f60d463f7c079db90c3ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d8d8b5007b57d70c8ddecdce9971c421c0123508ee1635cb8a3a9732d7be1aa4a803f013bb8bcfe67910d21ddc216fc7e49a68646aa8c4bfaa5b7c995e019e"} 22:33:08 INFO - Running post-run listener: copy_logs_to_upload_dir 22:33:08 INFO - Copying logs to upload dir... 22:33:08 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 22:33:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.388663 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-03-22 22:33:08.436597) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-22 22:33:08.442189) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0577cb62de2fea64a2a012e8af83f662b2706ad09daa2aaf901b91b013b7cb80f89333b00764502a2e85f8037e3fd3106b3505052f60d463f7c079db90c3ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d8d8b5007b57d70c8ddecdce9971c421c0123508ee1635cb8a3a9732d7be1aa4a803f013bb8bcfe67910d21ddc216fc7e49a68646aa8c4bfaa5b7c995e019e"} build_url:https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/fiIAcaRORDS4rb15muiXgA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0577cb62de2fea64a2a012e8af83f662b2706ad09daa2aaf901b91b013b7cb80f89333b00764502a2e85f8037e3fd3106b3505052f60d463f7c079db90c3ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d8d8b5007b57d70c8ddecdce9971c421c0123508ee1635cb8a3a9732d7be1aa4a803f013bb8bcfe67910d21ddc216fc7e49a68646aa8c4bfaa5b7c995e019e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-22 22:33:08.469270) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:33:08.469701) ========= 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=00204705a9daa57ed339a427000001b9-1458705234.829846-332127681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 22:33:08.552018) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-22 22:33:08.552384) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-22 22:33:08.552728) ========= ========= Total master_lag: 0.40 =========