builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1474411758.44 results: success (0) buildid: 20160920152520 builduid: b9d6314524e14c65b95aaa0ec4814f6d revision: 94032319b5df3cd54ff718abe58d07473449d491 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.436143) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.436652) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.437078) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.452604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.452915) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 15:49:18-- 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% 46.6M=0s 2016-09-20 15:49:18 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122779 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.589900) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.590284) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022523 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:18.639700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 15:49:18.640071) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 94032319b5df3cd54ff718abe58d07473449d491 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 94032319b5df3cd54ff718abe58d07473449d491 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 15:49:18,682 truncating revision to first 12 chars 2016-09-20 15:49:18,682 Setting DEBUG logging. 2016-09-20 15:49:18,682 attempt 1/10 2016-09-20 15:49:18,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/94032319b5df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 15:49:19,506 unpacking tar archive at: fx-team-94032319b5df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.177805 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 15:49:19.835231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:19.835781) ========= script_repo_revision: 94032319b5df3cd54ff718abe58d07473449d491 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:19.836813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:19.837511) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 15:49:19.849254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-20 15:49:19.849563) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:49:19 INFO - MultiFileLogger online at 20160920 15:49:19 in /builds/slave/test 15:49:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:49:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:49:19 INFO - {'append_to_log': False, 15:49:19 INFO - 'base_work_dir': '/builds/slave/test', 15:49:19 INFO - 'blob_upload_branch': 'Fx-Team', 15:49:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:49:19 INFO - 'branch': 'Fx-Team', 15:49:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:49:19 INFO - 'config_files': ('talos/linux_config.py',), 15:49:19 INFO - 'default_actions': ('clobber', 15:49:19 INFO - 'read-buildbot-config', 15:49:19 INFO - 'download-and-extract', 15:49:19 INFO - 'populate-webroot', 15:49:19 INFO - 'create-virtualenv', 15:49:19 INFO - 'install', 15:49:19 INFO - 'run-tests'), 15:49:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:49:19 INFO - 'download_minidump_stackwalk': True, 15:49:19 INFO - 'download_symbols': 'ondemand', 15:49:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:49:19 INFO - 'tooltool.py': '/tools/tooltool.py', 15:49:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:49:19 INFO - '/tools/misc-python/virtualenv.py')}, 15:49:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:49:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:49:19 INFO - 'installer_path': 'installer.exe', 15:49:19 INFO - 'log_level': 'info', 15:49:19 INFO - 'log_name': 'talos', 15:49:19 INFO - 'log_to_console': True, 15:49:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:49:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:49:19 INFO - 'opt_config_files': (), 15:49:19 INFO - 'pip_index': False, 15:49:19 INFO - 'sps_profile': False, 15:49:19 INFO - 'sps_profile_interval': 0, 15:49:19 INFO - 'suite': 'dromaeojs', 15:49:19 INFO - 'system_bits': '32', 15:49:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:49:19 INFO - 'title': 'talos-linux64-ix-021', 15:49:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:49:19 INFO - 'use_talos_json': True, 15:49:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:49:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:49:19 INFO - 'work_dir': 'build'} 15:49:19 INFO - [mozharness: 2016-09-20 22:49:19.985917Z] Running clobber step. 15:49:19 INFO - Running pre-action listener: _resource_record_pre_action 15:49:19 INFO - Running main action method: clobber 15:49:19 INFO - rmtree: /builds/slave/test/build 15:49:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:49:20 INFO - Running post-action listener: _resource_record_post_action 15:49:20 INFO - [mozharness: 2016-09-20 22:49:20.954559Z] Finished clobber step (success) 15:49:20 INFO - [mozharness: 2016-09-20 22:49:20.954659Z] Running read-buildbot-config step. 15:49:20 INFO - Running pre-action listener: _resource_record_pre_action 15:49:20 INFO - Running main action method: read_buildbot_config 15:49:20 INFO - Using buildbot properties: 15:49:20 INFO - { 15:49:20 INFO - "project": "", 15:49:20 INFO - "product": "firefox", 15:49:20 INFO - "script_repo_revision": "production", 15:49:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:49:20 INFO - "repository": "", 15:49:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 15:49:20 INFO - "buildid": "20160920152520", 15:49:20 INFO - "stage_platform": "linux64-pgo", 15:49:20 INFO - "basedir": "/builds/slave/test", 15:49:20 INFO - "buildnumber": 462, 15:49:20 INFO - "platform": "ubuntu64_hw", 15:49:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:49:20 INFO - "slavebuilddir": "test", 15:49:20 INFO - "branch": "fx-team", 15:49:20 INFO - "revision": "94032319b5df3cd54ff718abe58d07473449d491", 15:49:20 INFO - "slavename": "talos-linux64-ix-021", 15:49:20 INFO - "builduid": "b9d6314524e14c65b95aaa0ec4814f6d", 15:49:20 INFO - "repo_path": "integration/fx-team" 15:49:20 INFO - } 15:49:20 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 15:49:20 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 15:49:20 INFO - Running post-action listener: _resource_record_post_action 15:49:20 INFO - [mozharness: 2016-09-20 22:49:20.957009Z] Finished read-buildbot-config step (success) 15:49:20 INFO - [mozharness: 2016-09-20 22:49:20.957096Z] Running download-and-extract step. 15:49:20 INFO - Running pre-action listener: _resource_record_pre_action 15:49:20 INFO - Running main action method: download_and_extract 15:49:20 INFO - mkdir: /builds/slave/test/build/tests 15:49:20 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:20 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:49:20 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:49:20 INFO - Downloaded 1361 bytes. 15:49:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:49:20 INFO - Using the following test package requirements: 15:49:20 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:49:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:49:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:49:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'jsshell-linux-x86_64.zip'], 15:49:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:49:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:49:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:49:20 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:49:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:49:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:49:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:49:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:49:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:49:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:49:21 INFO - Expected file size: 21941113 15:49:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:49:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:49:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:49:25 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:49:25 INFO - Expected file size: 21941113 15:49:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 15:49:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:49:30 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:49:30 INFO - Expected file size: 14414059 15:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:31 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:49:31 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:49:31 INFO - Downloaded 59568159 bytes. 15:49:31 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:49:31 INFO - mkdir: /builds/slave/test/properties 15:49:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:31 INFO - Contents: 15:49:31 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:49:32 INFO - Running post-action listener: _resource_record_post_action 15:49:32 INFO - Running post-action listener: set_extra_try_arguments 15:49:32 INFO - [mozharness: 2016-09-20 22:49:32.607551Z] Finished download-and-extract step (success) 15:49:32 INFO - [mozharness: 2016-09-20 22:49:32.607779Z] Running populate-webroot step. 15:49:32 INFO - Running pre-action listener: _resource_record_pre_action 15:49:32 INFO - Running main action method: populate_webroot 15:49:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:49:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:49:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:49:32 INFO - '--tppagecycles', 15:49:32 INFO - '7'], 15:49:32 INFO - 'tests': ['tsvgm']}}, 15:49:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:49:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:49:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['damp', 'tps']}, 15:49:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'tests': ['damp', 'tps']}, 15:49:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['dromaeo_dom']}, 15:49:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['basic_compositor_video']}, 15:49:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:49:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['a11yr', 15:49:32 INFO - 'ts_paint', 15:49:32 INFO - 'tpaint', 15:49:32 INFO - 'sessionrestore', 15:49:32 INFO - 'sessionrestore_no_auto_restore', 15:49:32 INFO - 'tabpaint']}, 15:49:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['tsvgx', 15:49:32 INFO - 'tsvgr_opacity', 15:49:32 INFO - 'tart', 15:49:32 INFO - 'tscrollx', 15:49:32 INFO - 'cart']}, 15:49:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:32 INFO - 'tsvgr_opacity', 15:49:32 INFO - 'tart', 15:49:32 INFO - 'tscrollx', 15:49:32 INFO - 'cart']}, 15:49:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'talos_options': ['--disable-e10s'], 15:49:32 INFO - 'tests': ['tp5o']}, 15:49:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'tests': ['tp5o']}, 15:49:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'talos_options': ['--disable-e10s', 15:49:32 INFO - '--xperf_path', 15:49:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:32 INFO - 'tests': ['tp5n']}, 15:49:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:32 INFO - 'talos_options': ['--xperf_path', 15:49:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:32 INFO - 'tests': ['tp5n']}}, 15:49:32 INFO - 'talos.zip': {'path': '', 15:49:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:49:32 INFO - Running post-action listener: _resource_record_post_action 15:49:32 INFO - [mozharness: 2016-09-20 22:49:32.627192Z] Finished populate-webroot step (success) 15:49:32 INFO - [mozharness: 2016-09-20 22:49:32.627280Z] Running create-virtualenv step. 15:49:32 INFO - Running pre-action listener: _resource_record_pre_action 15:49:32 INFO - Running main action method: create_virtualenv 15:49:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:49:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:49:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:32 INFO - Using real prefix '/usr' 15:49:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:35 INFO - Installing pip.................done. 15:49:35 INFO - Return code: 0 15:49:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:35 INFO - Running command: ['/builds/slave/test/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/build 15:49:35 INFO - Copy/paste: /builds/slave/test/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 15:49:35 INFO - Using env: {'DISPLAY': ':0', 15:49:35 INFO - 'HOME': '/home/cltbld', 15:49:35 INFO - 'LANG': 'en_US.UTF-8', 15:49:35 INFO - 'LANGUAGE': 'en_US:en', 15:49:35 INFO - 'LOGNAME': 'cltbld', 15:49:35 INFO - 'MAIL': '/var/mail/cltbld', 15:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:35 INFO - 'MOZ_NO_REMOTE': '1', 15:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:35 INFO - 'NO_EM_RESTART': '1', 15:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:35 INFO - 'PWD': '/builds/slave/test', 15:49:35 INFO - 'SHELL': '/bin/bash', 15:49:35 INFO - 'SHLVL': '1', 15:49:35 INFO - 'TERM': 'linux', 15:49:35 INFO - 'TMOUT': '86400', 15:49:35 INFO - 'USER': 'cltbld', 15:49:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:35 INFO - '_': '/tools/buildbot/bin/python'} 15:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:35 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 15:49:35 INFO - Cleaning up... 15:49:35 INFO - Return code: 0 15:49:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:35 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 15:49:35 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 15:49:35 INFO - Using env: {'DISPLAY': ':0', 15:49:35 INFO - 'HOME': '/home/cltbld', 15:49:35 INFO - 'LANG': 'en_US.UTF-8', 15:49:35 INFO - 'LANGUAGE': 'en_US:en', 15:49:35 INFO - 'LOGNAME': 'cltbld', 15:49:35 INFO - 'MAIL': '/var/mail/cltbld', 15:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:35 INFO - 'MOZ_NO_REMOTE': '1', 15:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:35 INFO - 'NO_EM_RESTART': '1', 15:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:35 INFO - 'PWD': '/builds/slave/test', 15:49:35 INFO - 'SHELL': '/bin/bash', 15:49:35 INFO - 'SHLVL': '1', 15:49:35 INFO - 'TERM': 'linux', 15:49:35 INFO - 'TMOUT': '86400', 15:49:35 INFO - 'USER': 'cltbld', 15:49:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:35 INFO - '_': '/tools/buildbot/bin/python'} 15:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:35 INFO - Downloading/unpacking psutil>=3.1.1 15:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:37 INFO - Installing collected packages: psutil 15:49:37 INFO - Running setup.py install for psutil 15:49:37 INFO - building 'psutil._psutil_linux' extension 15:49:37 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 15:49:37 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 15:49:37 INFO - building 'psutil._psutil_posix' extension 15:49:37 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 15:49:38 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 15:49:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:38 INFO - Successfully installed psutil 15:49:38 INFO - Cleaning up... 15:49:38 INFO - Return code: 0 15:49:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:38 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 15:49:38 INFO - Copy/paste: /builds/slave/test/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.3 15:49:38 INFO - Using env: {'DISPLAY': ':0', 15:49:38 INFO - 'HOME': '/home/cltbld', 15:49:38 INFO - 'LANG': 'en_US.UTF-8', 15:49:38 INFO - 'LANGUAGE': 'en_US:en', 15:49:38 INFO - 'LOGNAME': 'cltbld', 15:49:38 INFO - 'MAIL': '/var/mail/cltbld', 15:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:38 INFO - 'MOZ_NO_REMOTE': '1', 15:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:38 INFO - 'NO_EM_RESTART': '1', 15:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:38 INFO - 'PWD': '/builds/slave/test', 15:49:38 INFO - 'SHELL': '/bin/bash', 15:49:38 INFO - 'SHLVL': '1', 15:49:38 INFO - 'TERM': 'linux', 15:49:38 INFO - 'TMOUT': '86400', 15:49:38 INFO - 'USER': 'cltbld', 15:49:38 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:38 INFO - '_': '/tools/buildbot/bin/python'} 15:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:40 INFO - Installing collected packages: mozsystemmonitor 15:49:40 INFO - Running setup.py install for mozsystemmonitor 15:49:40 INFO - Successfully installed mozsystemmonitor 15:49:40 INFO - Cleaning up... 15:49:40 INFO - Return code: 0 15:49:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:40 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 15:49:40 INFO - Copy/paste: /builds/slave/test/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==2.5.1 15:49:40 INFO - Using env: {'DISPLAY': ':0', 15:49:40 INFO - 'HOME': '/home/cltbld', 15:49:40 INFO - 'LANG': 'en_US.UTF-8', 15:49:40 INFO - 'LANGUAGE': 'en_US:en', 15:49:40 INFO - 'LOGNAME': 'cltbld', 15:49:40 INFO - 'MAIL': '/var/mail/cltbld', 15:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:40 INFO - 'MOZ_NO_REMOTE': '1', 15:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:40 INFO - 'NO_EM_RESTART': '1', 15:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:40 INFO - 'PWD': '/builds/slave/test', 15:49:40 INFO - 'SHELL': '/bin/bash', 15:49:40 INFO - 'SHLVL': '1', 15:49:40 INFO - 'TERM': 'linux', 15:49:40 INFO - 'TMOUT': '86400', 15:49:40 INFO - 'USER': 'cltbld', 15:49:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:40 INFO - '_': '/tools/buildbot/bin/python'} 15:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:40 INFO - Downloading/unpacking jsonschema==2.5.1 15:49:40 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 15:49:40 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 15:49:40 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 15:49:40 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 15:49:40 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 15:49:40 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 15:49:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:42 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 15:49:42 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 15:49:42 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 15:49:42 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 15:49:42 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 15:49:42 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 15:49:42 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:42 INFO - warning: no files found matching '*.txt' 15:49:42 INFO - no previously-included directories found matching 'build' 15:49:42 INFO - no previously-included directories found matching 'dist' 15:49:42 INFO - no previously-included directories found matching '.git*' 15:49:42 INFO - Installing collected packages: jsonschema, functools32 15:49:42 INFO - Running setup.py install for functools32 15:49:43 INFO - warning: no files found matching '*.txt' 15:49:43 INFO - no previously-included directories found matching 'build' 15:49:43 INFO - no previously-included directories found matching 'dist' 15:49:43 INFO - no previously-included directories found matching '.git*' 15:49:43 INFO - Successfully installed jsonschema functools32 15:49:43 INFO - Cleaning up... 15:49:43 INFO - Return code: 0 15:49:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:43 INFO - Running command: ['/builds/slave/test/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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:49:43 INFO - Copy/paste: /builds/slave/test/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 functools32==3.2.3-2 15:49:43 INFO - Using env: {'DISPLAY': ':0', 15:49:43 INFO - 'HOME': '/home/cltbld', 15:49:43 INFO - 'LANG': 'en_US.UTF-8', 15:49:43 INFO - 'LANGUAGE': 'en_US:en', 15:49:43 INFO - 'LOGNAME': 'cltbld', 15:49:43 INFO - 'MAIL': '/var/mail/cltbld', 15:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:43 INFO - 'MOZ_NO_REMOTE': '1', 15:49:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:43 INFO - 'NO_EM_RESTART': '1', 15:49:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:43 INFO - 'PWD': '/builds/slave/test', 15:49:43 INFO - 'SHELL': '/bin/bash', 15:49:43 INFO - 'SHLVL': '1', 15:49:43 INFO - 'TERM': 'linux', 15:49:43 INFO - 'TMOUT': '86400', 15:49:43 INFO - 'USER': 'cltbld', 15:49:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:43 INFO - '_': '/tools/buildbot/bin/python'} 15:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:43 INFO - Cleaning up... 15:49:43 INFO - Return code: 0 15:49:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:43 INFO - Running command: ['/builds/slave/test/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/build 15:49:43 INFO - Copy/paste: /builds/slave/test/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 15:49:43 INFO - Using env: {'DISPLAY': ':0', 15:49:43 INFO - 'HOME': '/home/cltbld', 15:49:43 INFO - 'LANG': 'en_US.UTF-8', 15:49:43 INFO - 'LANGUAGE': 'en_US:en', 15:49:43 INFO - 'LOGNAME': 'cltbld', 15:49:43 INFO - 'MAIL': '/var/mail/cltbld', 15:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:43 INFO - 'MOZ_NO_REMOTE': '1', 15:49:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:43 INFO - 'NO_EM_RESTART': '1', 15:49:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:43 INFO - 'PWD': '/builds/slave/test', 15:49:43 INFO - 'SHELL': '/bin/bash', 15:49:43 INFO - 'SHLVL': '1', 15:49:43 INFO - 'TERM': 'linux', 15:49:43 INFO - 'TMOUT': '86400', 15:49:43 INFO - 'USER': 'cltbld', 15:49:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:43 INFO - '_': '/tools/buildbot/bin/python'} 15:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:43 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:43 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 15:49:43 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 15:49:43 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 15:49:43 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 15:49:43 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 15:49:43 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 15:49:45 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:45 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 15:49:46 INFO - Downloading docopt-0.6.1.tar.gz 15:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:46 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:46 INFO - Running setup.py install for blobuploader 15:49:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:46 INFO - Running setup.py install for requests 15:49:46 INFO - Running setup.py install for docopt 15:49:46 INFO - Successfully installed blobuploader requests docopt 15:49:46 INFO - Cleaning up... 15:49:46 INFO - Return code: 0 15:49:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 15:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 15:49:46 INFO - Using env: {'DISPLAY': ':0', 15:49:46 INFO - 'HOME': '/home/cltbld', 15:49:46 INFO - 'LANG': 'en_US.UTF-8', 15:49:46 INFO - 'LANGUAGE': 'en_US:en', 15:49:46 INFO - 'LOGNAME': 'cltbld', 15:49:46 INFO - 'MAIL': '/var/mail/cltbld', 15:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:46 INFO - 'MOZ_NO_REMOTE': '1', 15:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:46 INFO - 'NO_EM_RESTART': '1', 15:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:46 INFO - 'PWD': '/builds/slave/test', 15:49:46 INFO - 'SHELL': '/bin/bash', 15:49:46 INFO - 'SHLVL': '1', 15:49:46 INFO - 'TERM': 'linux', 15:49:46 INFO - 'TMOUT': '86400', 15:49:46 INFO - 'USER': 'cltbld', 15:49:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:46 INFO - '_': '/tools/buildbot/bin/python'} 15:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:47 INFO - Running setup.py (path:/tmp/pip-YSDfsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:47 INFO - Running setup.py (path:/tmp/pip-DLg3MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:47 INFO - Running setup.py (path:/tmp/pip-Yowf5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:47 INFO - Running setup.py (path:/tmp/pip-y5OSre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:47 INFO - Running setup.py (path:/tmp/pip-8epkEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:47 INFO - Running setup.py (path:/tmp/pip-50xyU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:47 INFO - Running setup.py (path:/tmp/pip-hxTDIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:47 INFO - Running setup.py (path:/tmp/pip-qVUijl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:47 INFO - Running setup.py (path:/tmp/pip-gavIhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:47 INFO - Running setup.py (path:/tmp/pip-cxS5rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:47 INFO - Running setup.py (path:/tmp/pip-pDhy77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:47 INFO - Running setup.py (path:/tmp/pip-kXhvTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:47 INFO - Running setup.py (path:/tmp/pip-I0eSvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:48 INFO - Running setup.py (path:/tmp/pip-MA8dET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:48 INFO - Running setup.py (path:/tmp/pip-8x1JjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:48 INFO - Running setup.py (path:/tmp/pip-08EK50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:48 INFO - Running setup.py (path:/tmp/pip-TweuG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:48 INFO - Running setup.py install for manifestparser 15:49:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:48 INFO - Running setup.py install for mozcrash 15:49:48 INFO - Running setup.py install for mozdebug 15:49:48 INFO - Running setup.py install for mozdevice 15:49:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:49:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:48 INFO - Running setup.py install for mozfile 15:49:48 INFO - Running setup.py install for mozhttpd 15:49:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:48 INFO - Running setup.py install for mozinfo 15:49:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for mozInstall 15:49:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for mozleak 15:49:49 INFO - Running setup.py install for mozlog 15:49:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for moznetwork 15:49:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for mozprocess 15:49:49 INFO - Running setup.py install for mozprofile 15:49:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for mozrunner 15:49:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:49 INFO - Running setup.py install for mozscreenshot 15:49:49 INFO - Running setup.py install for moztest 15:49:50 INFO - Running setup.py install for mozversion 15:49:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:50 INFO - Cleaning up... 15:49:50 INFO - Return code: 0 15:49:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 15:49:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 15:49:50 INFO - Using env: {'DISPLAY': ':0', 15:49:50 INFO - 'HOME': '/home/cltbld', 15:49:50 INFO - 'LANG': 'en_US.UTF-8', 15:49:50 INFO - 'LANGUAGE': 'en_US:en', 15:49:50 INFO - 'LOGNAME': 'cltbld', 15:49:50 INFO - 'MAIL': '/var/mail/cltbld', 15:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:50 INFO - 'MOZ_NO_REMOTE': '1', 15:49:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:50 INFO - 'NO_EM_RESTART': '1', 15:49:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:50 INFO - 'PWD': '/builds/slave/test', 15:49:50 INFO - 'SHELL': '/bin/bash', 15:49:50 INFO - 'SHLVL': '1', 15:49:50 INFO - 'TERM': 'linux', 15:49:50 INFO - 'TMOUT': '86400', 15:49:50 INFO - 'USER': 'cltbld', 15:49:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:50 INFO - '_': '/tools/buildbot/bin/python'} 15:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:50 INFO - Running setup.py (path:/tmp/pip-iwhu5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:50 INFO - Running setup.py (path:/tmp/pip-cZPyTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:50 INFO - Running setup.py (path:/tmp/pip-vPjpMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:50 INFO - Running setup.py (path:/tmp/pip-3HuvNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:50 INFO - Running setup.py (path:/tmp/pip-JGFxKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:50 INFO - Running setup.py (path:/tmp/pip-Ik1dvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:50 INFO - Running setup.py (path:/tmp/pip-iRLdMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:50 INFO - Running setup.py (path:/tmp/pip-nziHV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:50 INFO - Running setup.py (path:/tmp/pip-JdMa_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:51 INFO - Running setup.py (path:/tmp/pip-zO9Erd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:51 INFO - Running setup.py (path:/tmp/pip-Jr9C7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:51 INFO - Running setup.py (path:/tmp/pip-vKbzLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:51 INFO - Running setup.py (path:/tmp/pip-sJoyP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:51 INFO - Running setup.py (path:/tmp/pip-K28Nk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:51 INFO - Running setup.py (path:/tmp/pip-pTgVsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:51 INFO - Running setup.py (path:/tmp/pip-IiiGEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:51 INFO - Running setup.py (path:/tmp/pip-W4cx5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:53 INFO - Downloading blessings-1.6.tar.gz 15:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:53 INFO - Installing collected packages: blessings 15:49:53 INFO - Running setup.py install for blessings 15:49:53 INFO - Successfully installed blessings 15:49:53 INFO - Cleaning up... 15:49:53 INFO - Return code: 0 15:49:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:53 INFO - Reading from file tmpfile_stdout 15:49:53 INFO - Current package versions: 15:49:53 INFO - argparse == 1.2.1 15:49:53 INFO - blessings == 1.6 15:49:53 INFO - blobuploader == 1.2.4 15:49:53 INFO - docopt == 0.6.1 15:49:53 INFO - functools32 == 3.2.3-2 15:49:53 INFO - jsonschema == 2.5.1 15:49:53 INFO - manifestparser == 1.1 15:49:53 INFO - mozInstall == 1.12 15:49:53 INFO - mozcrash == 0.17 15:49:53 INFO - mozdebug == 0.1 15:49:53 INFO - mozdevice == 0.48 15:49:53 INFO - mozfile == 1.2 15:49:53 INFO - mozhttpd == 0.7 15:49:53 INFO - mozinfo == 0.9 15:49:53 INFO - mozleak == 0.1 15:49:53 INFO - mozlog == 3.4 15:49:53 INFO - moznetwork == 0.27 15:49:53 INFO - mozprocess == 0.23 15:49:53 INFO - mozprofile == 0.28 15:49:53 INFO - mozrunner == 6.12 15:49:53 INFO - mozscreenshot == 0.1 15:49:53 INFO - mozsystemmonitor == 0.3 15:49:53 INFO - moztest == 0.7 15:49:53 INFO - mozversion == 1.4 15:49:53 INFO - psutil == 3.1.1 15:49:53 INFO - requests == 1.2.3 15:49:53 INFO - wsgiref == 0.1.2 15:49:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 15:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 15:49:53 INFO - Using env: {'DISPLAY': ':0', 15:49:53 INFO - 'HOME': '/home/cltbld', 15:49:53 INFO - 'LANG': 'en_US.UTF-8', 15:49:53 INFO - 'LANGUAGE': 'en_US:en', 15:49:53 INFO - 'LOGNAME': 'cltbld', 15:49:53 INFO - 'MAIL': '/var/mail/cltbld', 15:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:53 INFO - 'MOZ_NO_REMOTE': '1', 15:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:53 INFO - 'NO_EM_RESTART': '1', 15:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:53 INFO - 'PWD': '/builds/slave/test', 15:49:53 INFO - 'SHELL': '/bin/bash', 15:49:53 INFO - 'SHLVL': '1', 15:49:53 INFO - 'TERM': 'linux', 15:49:53 INFO - 'TMOUT': '86400', 15:49:53 INFO - 'USER': 'cltbld', 15:49:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:53 INFO - '_': '/tools/buildbot/bin/python'} 15:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:54 INFO - Cleaning up... 15:49:54 INFO - Return code: 0 15:49:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7f1e529d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa9790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:49:54 INFO - Running command: ['/builds/slave/test/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/build 15:49:54 INFO - Copy/paste: /builds/slave/test/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 15:49:54 INFO - Using env: {'DISPLAY': ':0', 15:49:54 INFO - 'HOME': '/home/cltbld', 15:49:54 INFO - 'LANG': 'en_US.UTF-8', 15:49:54 INFO - 'LANGUAGE': 'en_US:en', 15:49:54 INFO - 'LOGNAME': 'cltbld', 15:49:54 INFO - 'MAIL': '/var/mail/cltbld', 15:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:54 INFO - 'MOZ_NO_REMOTE': '1', 15:49:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:54 INFO - 'NO_EM_RESTART': '1', 15:49:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:54 INFO - 'PWD': '/builds/slave/test', 15:49:54 INFO - 'SHELL': '/bin/bash', 15:49:54 INFO - 'SHLVL': '1', 15:49:54 INFO - 'TERM': 'linux', 15:49:54 INFO - 'TMOUT': '86400', 15:49:54 INFO - 'USER': 'cltbld', 15:49:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:54 INFO - '_': '/tools/buildbot/bin/python'} 15:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:54 INFO - Cleaning up... 15:49:54 INFO - Return code: 0 15:49:54 INFO - Running post-action listener: _resource_record_post_action 15:49:54 INFO - Running post-action listener: _start_resource_monitoring 15:49:54 INFO - Starting resource monitoring. 15:49:54 INFO - [mozharness: 2016-09-20 22:49:54.440924Z] Finished create-virtualenv step (success) 15:49:54 INFO - [mozharness: 2016-09-20 22:49:54.441445Z] Running install step. 15:49:54 INFO - Running pre-action listener: _resource_record_pre_action 15:49:54 INFO - Running main action method: install 15:49:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:54 INFO - Reading from file tmpfile_stdout 15:49:54 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:54 INFO - Reading from file tmpfile_stdout 15:49:54 INFO - Output received: 15:49:54 INFO - Usage: mozinstall [options] installer 15:49:54 INFO - Options: 15:49:54 INFO - -h, --help show this help message and exit 15:49:54 INFO - -d DEST, --destination=DEST 15:49:54 INFO - Directory to install application into. [default: 15:49:54 INFO - "/builds/slave/test"] 15:49:54 INFO - --app=APP Application being installed. [default: firefox] 15:49:54 INFO - mkdir: /builds/slave/test/build/application 15:49:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:50:11 INFO - Reading from file tmpfile_stdout 15:50:11 INFO - Output received: 15:50:11 INFO - /builds/slave/test/build/application/firefox/firefox 15:50:11 INFO - Running post-action listener: _resource_record_post_action 15:50:11 INFO - [mozharness: 2016-09-20 22:50:11.684600Z] Finished install step (success) 15:50:11 INFO - [mozharness: 2016-09-20 22:50:11.684846Z] Running run-tests step. 15:50:11 INFO - Running pre-action listener: _resource_record_pre_action 15:50:11 INFO - Running main action method: run_tests 15:50:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:50:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:50:11 INFO - Python 2.7.3 15:50:11 INFO - Return code: 0 15:50:11 INFO - grabbing minidump binary from tooltool 15:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:11 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa6930>, '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 0x1aa7830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa7cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:50:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:50:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:50:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:50:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:50:11 INFO - Return code: 0 15:50:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:50:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:50:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:50:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:50:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:50:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:50:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:50:11 INFO - Using env: {'DISPLAY': ':0', 15:50:11 INFO - 'HOME': '/home/cltbld', 15:50:11 INFO - 'LANG': 'en_US.UTF-8', 15:50:11 INFO - 'LANGUAGE': 'en_US:en', 15:50:11 INFO - 'LOGNAME': 'cltbld', 15:50:11 INFO - 'MAIL': '/var/mail/cltbld', 15:50:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:50:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:11 INFO - 'MOZ_NO_REMOTE': '1', 15:50:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:50:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:11 INFO - 'NO_EM_RESTART': '1', 15:50:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:11 INFO - 'PWD': '/builds/slave/test', 15:50:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:50:11 INFO - 'SHELL': '/bin/bash', 15:50:11 INFO - 'SHLVL': '1', 15:50:11 INFO - 'TERM': 'linux', 15:50:11 INFO - 'TMOUT': '86400', 15:50:11 INFO - 'USER': 'cltbld', 15:50:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474411757.436780-366277469', 15:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:11 INFO - '_': '/tools/buildbot/bin/python'} 15:50:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:50:11 INFO - mozversion application_buildid: 20160920123020 15:50:11 INFO - mozversion application_changeset: 94032319b5df3cd54ff718abe58d07473449d491 15:50:11 INFO - mozversion application_display_name: Nightly 15:50:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:50:11 INFO - mozversion application_name: Firefox 15:50:11 INFO - mozversion application_remotingname: firefox 15:50:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:50:11 INFO - mozversion application_vendor: Mozilla 15:50:11 INFO - mozversion application_version: 52.0a1 15:50:11 INFO - mozversion platform_buildid: 20160920123020 15:50:11 INFO - mozversion platform_changeset: 94032319b5df3cd54ff718abe58d07473449d491 15:50:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:50:11 INFO - mozversion platform_version: 52.0a1 15:50:11 INFO - using testdate: 1474411811 15:50:11 INFO - actual date: 1474411811 15:50:11 INFO - starting webserver on 'localhost:44006' 15:50:11 INFO - SUITE-START | Running 2 tests 15:50:11 INFO - TEST-START | dromaeo_css 15:50:11 INFO - operating with platform_type : linux_ 15:50:11 INFO - Initialising browser for dromaeo_css test... 15:50:11 INFO - TEST-INFO | started process 22229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzqH0EL/profile http://localhost:44006/getInfo.html) 15:50:17 INFO - PROCESS | 22229 | __metrics Screen width/height:1600/1200 15:50:17 INFO - PROCESS | 22229 | colorDepth:24 15:50:17 INFO - PROCESS | 22229 | Browser inner width/height: 1024/697 15:50:17 INFO - PROCESS | 22229 | __metrics 15:50:18 INFO - PROCESS | 22229 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:50:18 INFO - PROCESS | 22229 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:50:19 INFO - PROCESS | 22229 | console.error: PushService: 15:50:19 INFO - PROCESS | 22229 | stateChangeProcessEnqueue: Error transitioning state 15:50:19 INFO - PROCESS | 22229 | UnknownError 15:50:19 INFO - TEST-INFO | 22229: exit 0 15:50:19 INFO - Browser initialized. 15:50:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:50:19 INFO - TEST-INFO | started process 22311 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzqH0EL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:32 INFO - PROCESS | 22311 | RSS: Main: 177410048 15:50:32 INFO - PROCESS | 22311 | 15:50:35 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.15004 seconds. 15:50:47 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 15:50:52 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:51:09 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 15:51:13 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:51:15 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 15:51:23 INFO - PROCESS | 22311 | 1474411883737 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] 15:51:26 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 15:51:31 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 15:51:34 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:51:36 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:51:39 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 15:51:42 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 15:51:49 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 15:51:51 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:51:58 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 15:52:06 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 15:52:09 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:52:17 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 15:52:25 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:52:35 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 15:52:50 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:52:52 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 15:52:56 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:52:58 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:53:11 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.10003 seconds. 15:53:14 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:53:19 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:53:21 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 15:53:27 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 15:53:28 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 2.03332 seconds. 15:53:31 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:53:33 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44006/tests/dromaeo/cssquery-ext.html) 15:53:34 INFO - PROCESS | 22311 | RSS: Main: 324083712 15:53:34 INFO - PROCESS | 22311 | 15:53:55 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:54:01 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:54:20 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:54:52 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:55:10 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:55:38 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:56:00 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:56:11 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 15:56:15 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:56:37 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:56:37 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-ext.html (next: http://localhost:44006/tests/dromaeo/cssquery-jquery.html) 15:56:38 INFO - PROCESS | 22311 | RSS: Main: 279252992 15:56:38 INFO - PROCESS | 22311 | 15:56:44 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.05001 seconds. 15:57:00 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:57:02 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:57:04 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:57:20 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:57:47 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:57:53 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:58:01 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:58:25 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:58:37 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:58:53 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:59:11 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:59:17 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:59:24 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 15:59:26 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:59:32 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:59:40 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44006/tests/dromaeo/cssquery-mootools.html) 15:59:40 INFO - PROCESS | 22311 | RSS: Main: 217255936 15:59:40 INFO - PROCESS | 22311 | 15:59:44 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:59:53 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:00:19 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:28 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:00:38 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:00:43 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:55 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:01:14 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:01:16 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:01:21 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:01:52 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:02:06 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:02:08 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:02:26 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:02:42 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:02:42 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44006/tests/dromaeo/cssquery-prototype.html) 16:02:43 INFO - PROCESS | 22311 | RSS: Main: 215560192 16:02:43 INFO - PROCESS | 22311 | 16:02:46 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:02:53 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:03:04 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:03:16 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:03:30 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:03:47 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:03:50 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:03:57 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 16:04:04 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 16:04:07 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:04:21 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:04:26 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:04:35 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:04:39 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:05:04 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:05:08 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:05:11 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:05:21 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:05:23 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:05:46 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44006/tests/dromaeo/cssquery-yui.html) 16:05:46 INFO - PROCESS | 22311 | RSS: Main: 216035328 16:05:46 INFO - PROCESS | 22311 | 16:05:50 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:05:57 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:06:05 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 16:06:09 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:06:29 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:06:53 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:07:00 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:07:18 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:07:36 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:07:38 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:07:54 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:07:56 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:08:02 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:08:10 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:08:22 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:08:44 INFO - PROCESS | 22311 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:08:49 INFO - PROCESS | 22311 | Cycle 1(1): loaded http://localhost:44006/tests/dromaeo/cssquery-yui.html (next: http://localhost:44006/tests/dromaeo/cssquery-dojo.html) 16:08:49 INFO - PROCESS | 22311 | RSS: Main: 309796864 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | __start_tp_report 16:08:49 INFO - PROCESS | 22311 | _x_x_mozilla_page_load 16:08:49 INFO - PROCESS | 22311 | _x_x_mozilla_page_load_details 16:08:49 INFO - PROCESS | 22311 | |i|pagename|runs| 16:08:49 INFO - PROCESS | 22311 | |0;dojo.html;3100.565920771092;3117.7505799536034;3137.2470607054315;3170.0489853044082;3189.2345836999125;5385.434529374418;5425.511703946644;5481.424450432707;5531.834044978652;5555.972220138899;5495.422980587041;5509.559235261179;5552.361478429982;5593.161025846125;5656;4887.144749668793;4900.509949005107;4936.160852655057;4969.105560999019;4988.176950803131;5052.115879721053;5148.150555158413;5149.17613181891;5154.768035438415;5196.974015129919;4877.195262781637;5085.8728531787;5090.745462726849;5150.639455238135;5179.818706345279;4902.068606964665;4953.306537084811;5003.4996500350035;5023.522765337287;5118.232265160255;5001.599872010231;5004.224345226459;5010.198368261061;5012.27322038306;5071.873203169914;5506.80726174586;5519.806806761784;5525.309336332958;5544.750486228089;5552.861178470571;5644.153376993443;5678.290213723285;5861.17943487912;5911.29064512257;5920.082387229986;5976;5993.520518358521;5998.220231369937;6002;6058.182145410357;5537.972310138463;5554.361248456439;5555.77776888928;5556.805511807108;5559.527440167551;5763.077907534775;5813.88372232553;6001.4298641628975;6057.969710151421;6085;5776.393478684761;5825.941740582624;5832.679202643895;5889.1755154278435;5901.527877769769;5848.824535263864;5897.852553686193;5915.053591425437;5915.40845915405;5920.407959204045;5751;5796.3913789052385;5809.099589563624;5893.14549390336;5896.64620122794;5771.134329850472;5913.172155898178;5921.3782552831335;5933.080372542263;5980.0431930890845;4518.728876267369;4521.480029796616;4521.59305662492;4528.45658520973;4584.747838868828;3961.9896926283614;3974.6621537169085;4029.012891841459;4050.5544390117066;4066.1257829566784;5520.365158006881;5544.473275038865;5569.888602227933;5577.972110139423;5684.289463817023;5876.609142720501;5936.851578710567;5940.55445841555;5941.673207973517;5978.372270911578;5296.947030529723;5576.163575463713;5595.132754423071;5597.468240517144;5607.8317650469735;4769.594584460359;4894.339264199288;4901.044296362175;4921.5078492151215;5003.824866129741;6081.6351018939;6082.726277317532;6093;6093.878122437526;6151.3541078186145;5938.6436813791315;5946.672932988728;5949.553783466171;5951.49412299942;5955.047192449188;5818.94181058184;5828.154917537018;5961.7615295387695;5989.760409583568;6000.639961602318;5690.743916523849;5746.770129194785;5749.8275051748515;5759.798407055733;5804.738786754522;5786.392428795084;5834.970825145847;5837.09525023622;5856.736446859987;5868.94131058684;5647.407022262603;5695.515881150149;5700.971495142498;5705.686187259575;5755.482006619257;5706.743196556082;5712.80005199816;5722.341930677859;5814.04068328721;5841.094630332305;5691.26013618227;5704.087346024616;5725.198472213977;5758.395368486249;5768.1636162757;5473.75368108444;5582.33012038558;5842.970785146047;5861.1501332307425;5865.706714664335;5613.46672066153;5616.3260408749575;5638.126090455986;5671.234383358194;5771.711414429178 16:08:49 INFO - PROCESS | 22311 | |1;ext.html;548.1005481005482;579.0474669169216;606.445102731001;620.6524346366035;622.7415622516655;32237.51643725343;42794.572054279444;43613.563864361306;43674.563254367444;43689.56310436895;46017.769911150484;46288;46393.30410043852;46409.303860442036;46491.767541162335;7977.481463704869;8911.554422278876;9091.136342047495;9094.49980251086;9096.545172741337;8166.714165004226;8436.662533498653;8439.240468357846;10586.311889727143;10872.238943273975;11050.834237486444;11099.056580190694;11114.110871130331;11115.944420277887;11127;10922.563097476092;10943.467914491977;10964.780704385947;10987.340759554372;10999.065079468215;8047.839043219162;8266.875996859992;10345.534450949724;10665.786684266272;10696.251262411633;42767;43213.56786432127;43260.783696081635;43296;43321.350179747016;3337.9499307510405;3360.7311415086733;3548.0952357148763;3585.354636165489;3724.2923844469465;30206.09381718552;32405.675943240738;32669.6733032669;32678.183045423815;32739.50890736641;4243.575642435762;4345.913081738379;4487.887802804923;4502.549745025472;4549.31760235964;331321;332708;333476;333779;335832;58298;58552;58594;58613.41386586165;58727;57308.71345643245;57496;57517.424825752045;57587.71206143942;57637.42362576321;15701.214939253221;16144.515664530083;16145.838541614434;16147.111908845127;16185.676286474205;11115.72210694739;11944.820827687592;12028.398580070962;12029;12038.87961120395;16387.344506219855;17984.37054703079;18086.920004199423;18468.44594662162;18938.337158199396;6901.482388820858;6955.443564514928;6988.161420629557;7190.9280907191305;7230.240824713434;922.4465320807462;935.3125452792192;1000.3397757480174;1044.5143008500932;1070.4540684250933;31240.843795780875;31419.528707069414;31482.212944676106;31889.362212755615;31906.361872762416;1020.9387436753818;1023.6007956896816;1047.5914393386586;1078.8543546621106;1089.6186334782763;15563.455279065178;16574.585635359213;16638.916805415894;16676.416325428552;16798.496045118667;582690;583586;585527;585710;586753;57017.42982570204;57221.14168287479;57288.427115729144;57344;57366.139507907414;1191.1602320364123;1249.787536118862;1297.5588299978053;1305.2038256663427;1348.7639663059122;30177.547336790405;31703.365932680757;32609.510857336685;32659.510107348884;33376.83311583524;44713;44996;45083.54916450794;45106.77446612746;45159;410721;411173;411883.34641595;414076;416076;27552.44895102127;28539.857300713364;28607.713922861338;29868.253293667833;30048.549271760505;46405.3039204419;47347.28979065248;47397.289040665106;47413;47646.761766190946;39330.213395731356;39461.408078879416;39918.201635966536;39924.40113398359;41928.58071419247;39240.62638838313;39617;40115.79942100271;41201.587984119775;41411.7929410351;37953.4306985401;38957.41563876601;40074.1985160301;40234.79882600568;40247.1950560993;28997.56503652489;30508.694913050585;31067.68932310648;31814.299052424598;33734.83132584322;27801;27921.68719634349;28059.630551993287;28086.57870131909;28090.43819123647 16:08:49 INFO - PROCESS | 22311 | |2;jquery.html;382.0677546785844;397.63417655756683;411.2679430613508;412.8782009307254;428.38526714165175;13371.665708357295;15123.470678526253;18890.149943252534;19147.138378772954;19776.802231977676;17900.28398864044;18462.907685461552;18467.630647387043;18554.443366698986;19291.710624340645;5491.835244942648;5554.1391084381985;5858.7656493740415;5889;5891.263592050994;5704.515116215106;5739.885202295952;5774.307083150028;5787.739551720183;5788.942110578903;5555.083411237141;5688.4311568842995;5763.423657634245;5766.308043034821;5768.278965129359;5606.159076138591;5630.521405680522;5632.577556683224;5635.070213414781;5711.2575365202465;4851.490593487703;5012.398512178525;5038.118329292396;5049.798008079672;5103.872403189913;22794.31617051491;22814;22864.771352286596;22992;23106.884465577732;2318.826088043387;2368.301351101429;2408.434018005767;2597.129961462912;2768.764655004327;14350.206224221973;14640.633984150374;14666.779998300033;14746.63133421673;15356.616084597863;1891.5743957609566;1895.1566552883971;1935.951601209981;1998.580298137393;2003.8697484663596;83418.58290708507;84693;85445;86485.56757216237;89124.99999999935;10559.472026398804;10581.470926453647;10881.347119172717;10981.890181098246;11026;15175.544733658007;15204.16377099248;15209;15213.923930380499;15231;7606.268373962596;7829.138794732575;7976.84046319082;8061.153578874251;8239.752807415787;6922.58464492122;7218.566885986857;7351.154617218981;7364;7639.350655194371;6560.901586476207;6589.703463344161;6623.138991931114;6675.632840193739;6689.364510371507;1859.6466671332403;1949.814767597076;1960.460873259865;1992.2329902987453;2005.2079428625902;857.4769390671685;940.501534186882;995.6316163019729;997.9251556133319;1012.4431562640518;17941.282348706165;19679.60640787176;19702.60594788125;19850.20599176017;20016.099275532637;969.2100937735722;988.1057642833248;999.7000899730052;1001.5743309093606;1010.7523656704008;10866.239363244496;11233.71915702114;11427.60003399877;11448.25586336902;11465.656030318936;500703;540677;548853;549320;557538;88944.5552772245;91547.54226228826;99539.50230248948;102166.4891675537;103390.48304758428;465.9906801863944;482.7586206896552;483.24372357260773;485.0154187000395;487.52466345313036;10601;10746.462676866282;11427;11748.765024699629;11750.35373054456;41186.588134119476;41401.37897931594;41703;42220.81148495713;42452;59397.703011484664;60659.69670151621;61266;62052.68973655103;62531;8282;8328.500289982621;8337.791555211168;8372.823066002762;8644.351673624378;12163.817542737042;12872.420741066488;12876.35618219104;13013.934930325666;13181.40683669218;10550.103241224582;10924.563017479211;11294.94352528232;11381.601643942422;11622;11032.558697652004;11286.266392684449;11400.771984560428;11409;11688.532458701557;11305.830412543653;11336.376499292619;11355;11462.770744585228;11490.88509114898;7913.129555748748;8070.152633973348;8257.95871020641;8471.703490377808;8887.422317549339;4668.5564871337165;5525.889482210414;10948.178886583379;11133.88866111361;11506.137039721887 16:08:49 INFO - PROCESS | 22311 | |3;mootools.html;1321.6960099177195;1480.4818313590245;1506.1490258004226;1639.2951031056648;1805.4222648752395;8179.591020448976;9146.222571081458;9369.718908432758;9383.15551600355;9740.951295243514;9502.429854208734;9538.236941044717;9561.856572151424;9563.043695630433;9564.85652715208;2408.7952524035436;2430.2587710748217;2431.9270421887372;2435.1233555919853;2437.317551085699;2348.729896061958;2412.0592968742203;2419.080749315258;2425.551273014498;2427.854328740272;2405.7955073818657;2413.6379543068506;2414.8188885833547;2447.889844956981;2454.8036157107476;2359.2686267257204;2384.129792625688;2385.7017872765905;2387.164492427654;2393.4255778613006;2345.073695890114;2348.97651023491;2378.607529757588;2380.750021247761;2388.7730665586914;9216.447013179164;9479.099485548863;9591.088846559565;9592.040795920422;9596.040395960348;5110.054639891595;5326.227696983954;5334.573234141299;5336.38631557372;5338.199270109476;3319.867205311785;3328.8169150696704;3378.9662103378896;3389;3442.8278586070846;1023.800358930009;1028.9897101029044;1037.4034929915329;1057.920655950799;1058.2750815691277;13424.932875335659;13463.057585968985;13481.393337299845;13517.729645407135;13621.795673064911;4326.891827704332;4461.687681862239;4551.385562949025;4622.930656040162;4624.676272660881;10443.68668939933;10458.058774710313;10459.895401045893;10466.372017678812;10470.633527826642;5147.742612869341;5229.555487783581;5238.161894097002;5241.580673546107;5242.423333433319;4286.249906266453;4409.845655402046;4714.693544919569;4801.807927682923;4817.9036419272115;3793.639604237593;3902.453656488094;3949.960500395017;3956.624120708528;3967.6230758077936;1159.930404175752;1166.1720178673067;1168.7837750016274;1170.0814860334597;1188.1920294199854;782.5734974438944;881.5460038080394;886.242262865241;889.0886840988039;898.7977705016319;5978.312494063239;5988.131720900532;6046.667433291123;6166.630002199882;6275.180197742604;938.5593114569103;962.9903700962901;964.363520076746;974.0503009565617;1032.2825636182781;4012.3179059559943;4200.873973780792;4204.222218889515;4207.97896010518;4242.193983143193;11794.233374830781;12110.091743119301;12194.024478041736;12205.877941220651;12212.694682633006;11760.117991150699;12048.75902481963;12130.87869121315;12138.271703697807;12148.088893333035;8773.605187766565;8814.664114009793;8899.199072083544;8901.065388134153;8939.374243802871;6029.397060293935;6165.106059621419;6325.4939604832425;6331.461825744679;6363.745450181941;10792.784144317227;10806.405647689453;11045.447727613426;11135.777284454103;11293.322400655987;10880.405297590542;11268.154888383568;11397.08823294118;11397.373144476802;11459.942700286778;6828.9658551706925;7029.683664235019;7727.6136193188995;7786.4549481538015;8063.27430531255;9121.49831759233;9414.95292523533;9427.670031548863;9428.198603118812;9443.00848410907;8310.54292013945;8402.285805706584;8418.242358187792;8439.957800211205;8608.956955215184;7986.920130798618;8156.9184308156155;8271.296939760188;8308.29379502749;8538.274246688852;6936.41040511542;6946.235914049552;6973.4072603829245;7031.6835742390695;7065.293470652894;4953.108440480604;4959.602113273836;5052.747362631927;5104.953484535736;5163.4578369270685;5152.227165924991;5167.431582525844;5185.792568297225;5300.522952934254;5327.547158491571 16:08:49 INFO - PROCESS | 22311 | |4;prototype.html;323.89149634872314;324.8960332693538;332.6773030160744;336.9208236064523;337.20419809250166;5352.57179425646;5375.973120134394;5395.190721391788;5406.810761623344;5418.864528386787;5286.810467644778;5311.389190243123;5318.680879147244;5324.54741346985;5330.493603107709;3906.453096566483;4160.875173744777;4224.809883555232;4228.365745138239;4234.407182994383;4170.728902621335;4179.6029377209115;4181.60274773896;4185.30495149465;4188.664906807463;4129.318662420697;4131.979340103296;4135.855245066423;4153.605407486284;4169.124483858393;3915.353966595508;3951.01224693828;4026;4046.615571520701;4143.7720925349095;3492.4935884296633;3502.6497350265026;3502.702270307027;3511.4206155984234;3523.735719821024;5089.847304580869;5104.183330667112;5116.079105760961;5122.641415100945;5157.839486115594;4432.312991486325;4481.462224533045;4615.8153673853;4662.696924699918;4700.29495575663;5521.4478552144865;5528.861778455571;5539.335279766414;5541.174001259386;5544.63959842609;3940.290747665419;3952.7628342299554;3955.406688996645;3961.2473630010204;3968.960310396888;5642.915356269659;5727.451284178543;5773.682447465388;5776.480116789509;5791.391903850119;4015.9196816063513;4028.657564107025;4031.879043628696;4042.4946881639794;4046.9797651012145;4093.5906409359422;4106;4108.91782164355;4109.2808758467145;4111.876643700693;4657.673962822638;4699.976500117477;4712.88217794551;4760.024195040009;4774.880627984258;5576.776928922879;5600.85997850057;5601.551875849922;5616.719164041781;5617.241672374259;1413.6112569043596;1428.7856821476653;1433.731147934065;1436.8347640021532;1442.65376309687;894.3069121430813;895.4627223665879;898.2170391772327;915.9175674189222;927.1642148545917;4727.75431669954;4752.528685083737;4754.286856971413;4790.060025689531;4836.637252206028;4965.21252349154;4976.308055261156;5005.223145783195;5028.723420211923;5096.898062038738;4624.005838744686;4757.726622960489;4760.238361862085;4775.233163729442;4882.509919940785;2649.2184805482043;2692.8384296942245;2698.662667166604;2711.3763834318215;2715.832192157352;5201.7919283229;5239.842804715865;5246.895062098737;5270.92093618596;5284.207368894697;4938.234573641092;5050.282903812721;5120.385553733501;5178.922316165261;5222.328854766438;5159.587233021424;5163.896722065538;5169.844904652867;5181.730476033396;5232.979568984088;5045.621578381562;5052.9747351263;5114.769835357492;5121.820736274212;5140;5026.547610715053;5110.259012443249;5250.501202385767;5766.826995190151;5789.8552536186935;6027.095935609694;6028;6033.487153591994;6034.577579569388;6036.456718895321;4106.486689163855;4125.381192820981;4142.565030671736;4152.4601801766785;4166.41670166183;5743.62666426681;5790.826275211751;5801.723620803416;5804.854878628068;5835.649861008353;5610.270664813548;5613.438656134354;5619.438056194512;5625.409332020079;5649.943500564942;5415.133578627559;5607.915881261866;5644.971775141263;5645.350784659817;5655.4910058094965;5560.388357280777;5578.86052848682;5587.888242235052;5589.413111623384;5605.439456054362;5408.864138530953;5528.834134975958;5550.47270509301;5660.9150862737915;5702.6293290935955;5570.387257401601;5584.190292407659;5586;5592.273004509388;5601.271834661468 16:08:49 INFO - PROCESS | 22311 | |5;yui.html;734.3904559215852;742.2651574940809;747.3012733094554;779.5829231361223;811.5798589632562;14694.118352898833;16209.594760131002;16219.594510137253;16255.674886502278;16269.02385856848;14962.850371496283;15011.699766004673;15051.473198438056;15077.246137693126;15084.547463576082;654.8592052708667;669.4075742967484;778.4667502760599;778.6457161991281;803.3733687723555;793.1235984237421;807.3823525003377;809.11402014794;809.4536188073057;810.1129263456505;844.0377969115214;844.8521508735977;845.2561745663808;846.5555583318776;846.9153084691543;671.768239957214;687.7249100359821;812.4800127918137;814.0963530481162;839.0728245288959;616.556079622671;617.0805499805258;617.3702823120437;617.8239201827503;618.5422787137486;15143.924280378527;15166.165860877647;15166.24168791556;15178.24108794567;15209.543713688496;13092.279924604143;13139.60581182566;13597.86402135976;14211.573652790434;14307.57077287683;12442.315672638002;12512.249265044036;12588.433520491508;12921.095523313372;12927;4378.1462614790125;4426.977865110686;4527.3435351873795;4599.333096700993;4637.930431043537;80939.5953020237;81552.59223703903;83303.16696832955;83724;84974.57512712458;10777.407242601575;11089.390083545482;11183.273087249303;11277;11373.203875728785;20890.582188356148;20953.161873525194;20994.160233590486;21034.78965210359;21055.052522636517;7655.349295309849;7982.800429989181;8043.396745244013;8166.4283500154415;8224.054233763074;4283;4346.087321662423;4509.233430316854;4547.999440123168;4702.27114797207;3349.3755528568627;3500.0374896903554;3504.8948531544092;3507.4914137449928;3509.0350153707936;597.7071235094772;603.7464265008707;610.3133974278936;610.7373829253464;611.3092205807409;658.5127006015609;686.8317262270775;695.7390978383106;696.9860602787916;735.7277807211366;9161.404508707046;9819.954242514645;10112.595496180218;10361.896381036093;10662;564.8361975027278;610.9969450152721;642.2774378823823;668.4017804065431;718.1489934427719;4182.309918863414;4411.625011873963;4421.579949904753;4449.488308844459;4485.416895803524;71694.2830571698;116464;123629.38185309016;124223;125691;63009.68495157495;65616.34383656198;65861;66467;107650.46174769233;95256.52371738097;98487.62855157391;98621.01378986261;98892.50553747329;100687;8539.359548034046;8547.657713928795;8566.957165214135;8659.220670139717;8700.173483519056;56729.432705672414;56793;57476.425235747105;58015.70992145181;58796;46534.30198546957;60880.695596521735;79514;80362;80872.59563702144;12332.691682708004;12395.442205100611;12568.183068100545;12745.553905613258;12942.547010854574;12757.744845103232;13032.609021729364;13105.082644214814;13166.934165329112;13327.200367977952;11830.11274154459;13274.535391261259;14249.358778854768;14353.497627582985;14454.783178252545;14064.226467544384;14210.21843798601;14427.639309017359;14544.127352358892;14599;13231.933840330737;13865.376058077209;14217.218053007184;14356.138631682132;14403.495877644233;10312.896871031493;10750.784984300113;11024.614138505114;11314.547418103184;11473.483693233991;10504.579816807242;11869.228500147463;12131.696707582381;12222.388880556115;12688.429020694275 16:08:49 INFO - PROCESS | 22311 | __end_tp_report 16:08:49 INFO - PROCESS | 22311 | __start_cc_report 16:08:49 INFO - PROCESS | 22311 | _x_x_mozilla_cycle_collect,1173 16:08:49 INFO - PROCESS | 22311 | __end_cc_report 16:08:49 INFO - PROCESS | 22311 | __startTimestamp1474412929779__endTimestamp 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | ------- Summary: start ------- 16:08:49 INFO - PROCESS | 22311 | Number of tests: 6 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#0] dojo.html Cycles:165 Average:5463.59 Median:5678.29 stddev:622.44 (11.0%) stddev-sans-first:596.11 16:08:49 INFO - PROCESS | 22311 | Values: 3100.6 3117.8 3137.2 3170.0 3189.2 5385.4 5425.5 5481.4 5531.8 5556.0 5495.4 5509.6 5552.4 5593.2 5656.0 4887.1 4900.5 4936.2 4969.1 4988.2 5052.1 5148.2 5149.2 5154.8 5197.0 4877.2 5085.9 5090.7 5150.6 5179.8 4902.1 4953.3 5003.5 5023.5 5118.2 5001.6 5004.2 5010.2 5012.3 5071.9 5506.8 5519.8 5525.3 5544.8 5552.9 5644.2 5678.3 5861.2 5911.3 5920.1 5976.0 5993.5 5998.2 6002.0 6058.2 5538.0 5554.4 5555.8 5556.8 5559.5 5763.1 5813.9 6001.4 6058.0 6085.0 5776.4 5825.9 5832.7 5889.2 5901.5 5848.8 5897.9 5915.1 5915.4 5920.4 5751.0 5796.4 5809.1 5893.1 5896.6 5771.1 5913.2 5921.4 5933.1 5980.0 4518.7 4521.5 4521.6 4528.5 4584.7 3962.0 3974.7 4029.0 4050.6 4066.1 5520.4 5544.5 5569.9 5578.0 5684.3 5876.6 5936.9 5940.6 5941.7 5978.4 5296.9 5576.2 5595.1 5597.5 5607.8 4769.6 4894.3 4901.0 4921.5 5003.8 6081.6 6082.7 6093.0 6093.9 6151.4 5938.6 5946.7 5949.6 5951.5 5955.0 5818.9 5828.2 5961.8 5989.8 6000.6 5690.7 5746.8 5749.8 5759.8 5804.7 5786.4 5835.0 5837.1 5856.7 5868.9 5647.4 5695.5 5701.0 5705.7 5755.5 5706.7 5712.8 5722.3 5814.0 5841.1 5691.3 5704.1 5725.2 5758.4 5768.2 5473.8 5582.3 5843.0 5861.2 5865.7 5613.5 5616.3 5638.1 5671.2 5771.7 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#1] ext.html Cycles:180 Average:60062.61 Median:39099.02 stddev:121189.84 (310.0%) stddev-sans-first:121447.43 16:08:49 INFO - PROCESS | 22311 | Values: 548.1 579.0 606.4 620.7 622.7 32237.5 42794.6 43613.6 43674.6 43689.6 46017.8 46288.0 46393.3 46409.3 46491.8 7977.5 8911.6 9091.1 9094.5 9096.5 8166.7 8436.7 8439.2 10586.3 10872.2 11050.8 11099.1 11114.1 11115.9 11127.0 10922.6 10943.5 10964.8 10987.3 10999.1 8047.8 8266.9 10345.5 10665.8 10696.3 42767.0 43213.6 43260.8 43296.0 43321.4 3337.9 3360.7 3548.1 3585.4 3724.3 30206.1 32405.7 32669.7 32678.2 32739.5 4243.6 4345.9 4487.9 4502.5 4549.3 331321.0 332708.0 333476.0 333779.0 335832.0 58298.0 58552.0 58594.0 58613.4 58727.0 57308.7 57496.0 57517.4 57587.7 57637.4 15701.2 16144.5 16145.8 16147.1 16185.7 11115.7 11944.8 12028.4 12029.0 12038.9 16387.3 17984.4 18086.9 18468.4 18938.3 6901.5 6955.4 6988.2 7190.9 7230.2 922.4 935.3 1000.3 1044.5 1070.5 31240.8 31419.5 31482.2 31889.4 31906.4 1020.9 1023.6 1047.6 1078.9 1089.6 15563.5 16574.6 16638.9 16676.4 16798.5 582690.0 583586.0 585527.0 585710.0 586753.0 57017.4 57221.1 57288.4 57344.0 57366.1 1191.2 1249.8 1297.6 1305.2 1348.8 30177.5 31703.4 32609.5 32659.5 33376.8 44713.0 44996.0 45083.5 45106.8 45159.0 410721.0 411173.0 411883.3 414076.0 416076.0 27552.4 28539.9 28607.7 29868.3 30048.5 46405.3 47347.3 47397.3 47413.0 47646.8 39330.2 39461.4 39918.2 39924.4 41928.6 39240.6 39617.0 40115.8 41201.6 41411.8 37953.4 38957.4 40074.2 40234.8 40247.2 28997.6 30508.7 31067.7 31814.3 33734.8 27801.0 27921.7 28059.6 28086.6 28090.4 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#2] jquery.html Cycles:180 Average:30498.93 Median:20798.93 stddev:89019.17 (428.0%) stddev-sans-first:89240.17 16:08:49 INFO - PROCESS | 22311 | Values: 382.1 397.6 411.3 412.9 428.4 13371.7 15123.5 18890.1 19147.1 19776.8 17900.3 18462.9 18467.6 18554.4 19291.7 5491.8 5554.1 5858.8 5889.0 5891.3 5704.5 5739.9 5774.3 5787.7 5788.9 5555.1 5688.4 5763.4 5766.3 5768.3 5606.2 5630.5 5632.6 5635.1 5711.3 4851.5 5012.4 5038.1 5049.8 5103.9 22794.3 22814.0 22864.8 22992.0 23106.9 2318.8 2368.3 2408.4 2597.1 2768.8 14350.2 14640.6 14666.8 14746.6 15356.6 1891.6 1895.2 1936.0 1998.6 2003.9 83418.6 84693.0 85445.0 86485.6 89125.0 10559.5 10581.5 10881.3 10981.9 11026.0 15175.5 15204.2 15209.0 15213.9 15231.0 7606.3 7829.1 7976.8 8061.2 8239.8 6922.6 7218.6 7351.2 7364.0 7639.4 6560.9 6589.7 6623.1 6675.6 6689.4 1859.6 1949.8 1960.5 1992.2 2005.2 857.5 940.5 995.6 997.9 1012.4 17941.3 19679.6 19702.6 19850.2 20016.1 969.2 988.1 999.7 1001.6 1010.8 10866.2 11233.7 11427.6 11448.3 11465.7 500703.0 540677.0 548853.0 549320.0 557538.0 88944.6 91547.5 99539.5 102166.5 103390.5 466.0 482.8 483.2 485.0 487.5 10601.0 10746.5 11427.0 11748.8 11750.4 41186.6 41401.4 41703.0 42220.8 42452.0 59397.7 60659.7 61266.0 62052.7 62531.0 8282.0 8328.5 8337.8 8372.8 8644.4 12163.8 12872.4 12876.4 13013.9 13181.4 10550.1 10924.6 11294.9 11381.6 11622.0 11032.6 11286.3 11400.8 11409.0 11688.5 11305.8 11336.4 11355.0 11462.8 11490.9 7913.1 8070.2 8258.0 8471.7 8887.4 4668.6 5525.9 10948.2 11133.9 11506.1 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#3] mootools.html Cycles:180 Average:6092.40 Median:4587.16 stddev:3652.31 (79.6%) stddev-sans-first:3644.96 16:08:49 INFO - PROCESS | 22311 | Values: 1321.7 1480.5 1506.1 1639.3 1805.4 8179.6 9146.2 9369.7 9383.2 9741.0 9502.4 9538.2 9561.9 9563.0 9564.9 2408.8 2430.3 2431.9 2435.1 2437.3 2348.7 2412.1 2419.1 2425.6 2427.9 2405.8 2413.6 2414.8 2447.9 2454.8 2359.3 2384.1 2385.7 2387.2 2393.4 2345.1 2349.0 2378.6 2380.8 2388.8 9216.4 9479.1 9591.1 9592.0 9596.0 5110.1 5326.2 5334.6 5336.4 5338.2 3319.9 3328.8 3379.0 3389.0 3442.8 1023.8 1029.0 1037.4 1057.9 1058.3 13424.9 13463.1 13481.4 13517.7 13621.8 4326.9 4461.7 4551.4 4622.9 4624.7 10443.7 10458.1 10459.9 10466.4 10470.6 5147.7 5229.6 5238.2 5241.6 5242.4 4286.2 4409.8 4714.7 4801.8 4817.9 3793.6 3902.5 3950.0 3956.6 3967.6 1159.9 1166.2 1168.8 1170.1 1188.2 782.6 881.5 886.2 889.1 898.8 5978.3 5988.1 6046.7 6166.6 6275.2 938.6 963.0 964.4 974.1 1032.3 4012.3 4200.9 4204.2 4208.0 4242.2 11794.2 12110.1 12194.0 12205.9 12212.7 11760.1 12048.8 12130.9 12138.3 12148.1 8773.6 8814.7 8899.2 8901.1 8939.4 6029.4 6165.1 6325.5 6331.5 6363.7 10792.8 10806.4 11045.4 11135.8 11293.3 10880.4 11268.2 11397.1 11397.4 11459.9 6829.0 7029.7 7727.6 7786.5 8063.3 9121.5 9415.0 9427.7 9428.2 9443.0 8310.5 8402.3 8418.2 8440.0 8609.0 7986.9 8156.9 8271.3 8308.3 8538.3 6936.4 6946.2 6973.4 7031.7 7065.3 4953.1 4959.6 5052.7 5105.0 5163.5 5152.2 5167.4 5185.8 5300.5 5327.5 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#4] prototype.html Cycles:180 Average:4554.06 Median:5100.54 stddev:1337.89 (26.2%) stddev-sans-first:1303.42 16:08:49 INFO - PROCESS | 22311 | Values: 323.9 324.9 332.7 336.9 337.2 5352.6 5376.0 5395.2 5406.8 5418.9 5286.8 5311.4 5318.7 5324.5 5330.5 3906.5 4160.9 4224.8 4228.4 4234.4 4170.7 4179.6 4181.6 4185.3 4188.7 4129.3 4132.0 4135.9 4153.6 4169.1 3915.4 3951.0 4026.0 4046.6 4143.8 3492.5 3502.6 3502.7 3511.4 3523.7 5089.8 5104.2 5116.1 5122.6 5157.8 4432.3 4481.5 4615.8 4662.7 4700.3 5521.4 5528.9 5539.3 5541.2 5544.6 3940.3 3952.8 3955.4 3961.2 3969.0 5642.9 5727.5 5773.7 5776.5 5791.4 4015.9 4028.7 4031.9 4042.5 4047.0 4093.6 4106.0 4108.9 4109.3 4111.9 4657.7 4700.0 4712.9 4760.0 4774.9 5576.8 5600.9 5601.6 5616.7 5617.2 1413.6 1428.8 1433.7 1436.8 1442.7 894.3 895.5 898.2 915.9 927.2 4727.8 4752.5 4754.3 4790.1 4836.6 4965.2 4976.3 5005.2 5028.7 5096.9 4624.0 4757.7 4760.2 4775.2 4882.5 2649.2 2692.8 2698.7 2711.4 2715.8 5201.8 5239.8 5246.9 5270.9 5284.2 4938.2 5050.3 5120.4 5178.9 5222.3 5159.6 5163.9 5169.8 5181.7 5233.0 5045.6 5053.0 5114.8 5121.8 5140.0 5026.5 5110.3 5250.5 5766.8 5789.9 6027.1 6028.0 6033.5 6034.6 6036.5 4106.5 4125.4 4142.6 4152.5 4166.4 5743.6 5790.8 5801.7 5804.9 5835.6 5610.3 5613.4 5619.4 5625.4 5649.9 5415.1 5607.9 5645.0 5645.4 5655.5 5560.4 5578.9 5587.9 5589.4 5605.4 5408.9 5528.8 5550.5 5660.9 5702.6 5570.4 5584.2 5586.0 5592.3 5601.3 16:08:49 INFO - PROCESS | 22311 | 16:08:49 INFO - PROCESS | 22311 | [#5] yui.html Cycles:180 Average:20546.66 Median:4438.23 stddev:29710.23 (669.4%) stddev-sans-first:29756.33 16:08:49 INFO - PROCESS | 22311 | Values: 734.4 742.3 747.3 779.6 811.6 14694.1 16209.6 16219.6 16255.7 16269.0 14962.9 15011.7 15051.5 15077.2 15084.5 654.9 669.4 778.5 778.6 803.4 793.1 807.4 809.1 809.5 810.1 844.0 844.9 845.3 846.6 846.9 671.8 687.7 812.5 814.1 839.1 616.6 617.1 617.4 617.8 618.5 15143.9 15166.2 15166.2 15178.2 15209.5 13092.3 13139.6 13597.9 14211.6 14307.6 12442.3 12512.2 12588.4 12921.1 12927.0 4378.1 4427.0 4527.3 4599.3 4637.9 80939.6 81552.6 83303.2 83724.0 84974.6 10777.4 11089.4 11183.3 11277.0 11373.2 20890.6 20953.2 20994.2 21034.8 21055.1 7655.3 7982.8 8043.4 8166.4 8224.1 4283.0 4346.1 4509.2 4548.0 4702.3 3349.4 3500.0 3504.9 3507.5 3509.0 597.7 603.7 610.3 610.7 611.3 658.5 686.8 695.7 697.0 735.7 9161.4 9820.0 10112.6 10361.9 10662.0 564.8 611.0 642.3 668.4 718.1 4182.3 4411.6 4421.6 4449.5 4485.4 71694.3 116464.0 123629.4 124223.0 125691.0 63009.7 65616.3 65861.0 66467.0 107650.5 95256.5 98487.6 98621.0 98892.5 100687.0 8539.4 8547.7 8567.0 8659.2 8700.2 56729.4 56793.0 57476.4 58015.7 58796.0 46534.3 60880.7 79514.0 80362.0 80872.6 12332.7 12395.4 12568.2 12745.6 12942.5 12757.7 13032.6 13105.1 13166.9 13327.2 11830.1 13274.5 14249.4 14353.5 14454.8 14064.2 14210.2 14427.6 14544.1 14599.0 13231.9 13865.4 14217.2 14356.1 14403.5 10312.9 10750.8 11024.6 11314.5 11473.5 10504.6 11869.2 12131.7 12222.4 12688.4 16:08:49 INFO - PROCESS | 22311 | -------- Summary: end -------- 16:08:49 INFO - PROCESS | 22311 | 16:08:50 INFO - TEST-INFO | 22311: exit 0 16:08:50 INFO - TEST-OK | dromaeo_css | took 1118778ms 16:08:50 INFO - TEST-START | kraken 16:08:50 INFO - operating with platform_type : linux_ 16:08:50 INFO - Initialising browser for kraken test... 16:08:50 INFO - TEST-INFO | started process 22613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprBMH0R/profile http://localhost:44006/getInfo.html) 16:08:55 INFO - PROCESS | 22613 | __metrics Screen width/height:1600/1200 16:08:55 INFO - PROCESS | 22613 | colorDepth:24 16:08:55 INFO - PROCESS | 22613 | Browser inner width/height: 1024/697 16:08:55 INFO - PROCESS | 22613 | __metrics 16:08:56 INFO - PROCESS | 22613 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:08:56 INFO - PROCESS | 22613 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:57 INFO - PROCESS | 22613 | console.error: PushService: 16:08:57 INFO - PROCESS | 22613 | stateChangeProcessEnqueue: Error transitioning state 16:08:57 INFO - PROCESS | 22613 | UnknownError 16:08:57 INFO - TEST-INFO | 22613: exit 0 16:08:57 INFO - Browser initialized. 16:08:57 INFO - Running cycle 1/1 for kraken test... 16:08:57 INFO - TEST-INFO | started process 22696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprBMH0R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:09 INFO - PROCESS | 22696 | RSS: Main: 173043712 16:09:09 INFO - PROCESS | 22696 | 16:09:12 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44006/tests/kraken/driver.html?testName=audio-beat-detection) 16:09:13 INFO - PROCESS | 22696 | RSS: Main: 232718336 16:09:13 INFO - PROCESS | 22696 | 16:09:15 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44006/tests/kraken/driver.html?testName=audio-dft) 16:09:16 INFO - PROCESS | 22696 | RSS: Main: 217231360 16:09:16 INFO - PROCESS | 22696 | 16:09:19 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44006/tests/kraken/driver.html?testName=audio-fft) 16:09:20 INFO - PROCESS | 22696 | RSS: Main: 298094592 16:09:20 INFO - PROCESS | 22696 | 16:09:22 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44006/tests/kraken/driver.html?testName=audio-oscillator) 16:09:22 INFO - PROCESS | 22696 | RSS: Main: 206860288 16:09:22 INFO - PROCESS | 22696 | 16:09:25 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44006/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:09:25 INFO - PROCESS | 22696 | RSS: Main: 211791872 16:09:25 INFO - PROCESS | 22696 | 16:09:28 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44006/tests/kraken/driver.html?testName=imaging-darkroom) 16:09:29 INFO - PROCESS | 22696 | RSS: Main: 248635392 16:09:29 INFO - PROCESS | 22696 | 16:09:32 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44006/tests/kraken/driver.html?testName=imaging-desaturate) 16:09:33 INFO - PROCESS | 22696 | RSS: Main: 247402496 16:09:33 INFO - PROCESS | 22696 | 16:09:36 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44006/tests/kraken/driver.html?testName=json-parse-financial) 16:09:36 INFO - PROCESS | 22696 | RSS: Main: 247730176 16:09:36 INFO - PROCESS | 22696 | 16:09:38 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44006/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:09:38 INFO - PROCESS | 22696 | RSS: Main: 209895424 16:09:38 INFO - PROCESS | 22696 | 16:09:40 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:09:41 INFO - PROCESS | 22696 | RSS: Main: 217858048 16:09:41 INFO - PROCESS | 22696 | 16:09:43 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:43 INFO - PROCESS | 22696 | RSS: Main: 222580736 16:09:43 INFO - PROCESS | 22696 | 16:09:46 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:47 INFO - PROCESS | 22696 | RSS: Main: 223621120 16:09:47 INFO - PROCESS | 22696 | 16:09:49 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:50 INFO - PROCESS | 22696 | RSS: Main: 221380608 16:09:50 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | Cycle 1(1): loaded http://localhost:44006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44006/tests/kraken/driver.html?testName=ai-astar) 16:09:52 INFO - PROCESS | 22696 | RSS: Main: 272240640 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | __start_tp_report 16:09:52 INFO - PROCESS | 22696 | _x_x_mozilla_page_load 16:09:52 INFO - PROCESS | 22696 | _x_x_mozilla_page_load_details 16:09:52 INFO - PROCESS | 22696 | |i|pagename|runs| 16:09:52 INFO - PROCESS | 22696 | |0;ai-astar;109;112;111;111;113;111;112;112;111;111 16:09:52 INFO - PROCESS | 22696 | |1;audio-beat-detection;145;119;118;173;119;122;122;118;157;119 16:09:52 INFO - PROCESS | 22696 | |2;audio-dft;162;161;161;161;161;164;161;162;162;162 16:09:52 INFO - PROCESS | 22696 | |3;audio-fft;75;75;76;105;76;77;76;77;99;86 16:09:52 INFO - PROCESS | 22696 | |4;audio-oscillator;83;82;113;82;83;82;111;82;82;108 16:09:52 INFO - PROCESS | 22696 | |5;imaging-gaussian-blur;116;118;117;117;118;117;117;117;118;117 16:09:52 INFO - PROCESS | 22696 | |6;imaging-darkroom;146;147;147;147;146;146;145;146;145;145 16:09:52 INFO - PROCESS | 22696 | |7;imaging-desaturate;96;96;96;96;96;95;96;96;96;96 16:09:52 INFO - PROCESS | 22696 | |8;json-parse-financial;51;50;50;51;51;50;51;51;51;51 16:09:52 INFO - PROCESS | 22696 | |9;json-stringify-tinderbox;60;61;60;61;61;60;60;60;60;61 16:09:52 INFO - PROCESS | 22696 | |10;stanford-crypto-aes;89;82;84;79;79;78;85;79;78;78 16:09:52 INFO - PROCESS | 22696 | |11;stanford-crypto-ccm;125;123;124;126;132;131;138;128;164;137 16:09:52 INFO - PROCESS | 22696 | |12;stanford-crypto-pbkdf2;165;167;167;165;169;165;163;162;165;164 16:09:52 INFO - PROCESS | 22696 | |13;stanford-crypto-sha256-iterative;65;64;68;66;65;66;65;65;65;63 16:09:52 INFO - PROCESS | 22696 | __end_tp_report 16:09:52 INFO - PROCESS | 22696 | __start_cc_report 16:09:52 INFO - PROCESS | 22696 | _x_x_mozilla_cycle_collect,1650 16:09:52 INFO - PROCESS | 22696 | __end_cc_report 16:09:52 INFO - PROCESS | 22696 | __startTimestamp1474412992365__endTimestamp 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | ------- Summary: start ------- 16:09:52 INFO - PROCESS | 22696 | Number of tests: 14 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:1.06 (1.0%) stddev-sans-first:0.73 16:09:52 INFO - PROCESS | 22696 | Values: 109.0 112.0 111.0 111.0 113.0 111.0 112.0 112.0 111.0 111.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:122.00 stddev:19.91 (16.3%) stddev-sans-first:20.48 16:09:52 INFO - PROCESS | 22696 | Values: 145.0 119.0 118.0 173.0 119.0 122.0 122.0 118.0 157.0 119.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 16:09:52 INFO - PROCESS | 22696 | Values: 162.0 161.0 161.0 161.0 161.0 164.0 161.0 162.0 162.0 162.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.50 stddev:11.00 (14.4%) stddev-sans-first:11.36 16:09:52 INFO - PROCESS | 22696 | Values: 75.0 75.0 76.0 105.0 76.0 77.0 76.0 77.0 99.0 86.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:82.00 stddev:13.77 (16.8%) stddev-sans-first:14.31 16:09:52 INFO - PROCESS | 22696 | Values: 83.0 82.0 113.0 82.0 83.0 82.0 111.0 82.0 82.0 108.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 16:09:52 INFO - PROCESS | 22696 | Values: 116.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 118.0 117.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 16:09:52 INFO - PROCESS | 22696 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 145.0 146.0 145.0 145.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:09:52 INFO - PROCESS | 22696 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 16:09:52 INFO - PROCESS | 22696 | Values: 51.0 50.0 50.0 51.0 51.0 50.0 51.0 51.0 51.0 51.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 16:09:52 INFO - PROCESS | 22696 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 60.0 60.0 60.0 61.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.50 stddev:3.78 (4.7%) stddev-sans-first:2.73 16:09:52 INFO - PROCESS | 22696 | Values: 89.0 82.0 84.0 79.0 79.0 78.0 85.0 79.0 78.0 78.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#11] stanford-crypto-ccm Cycles:10 Average:132.80 Median:131.50 stddev:12.14 (9.2%) stddev-sans-first:12.54 16:09:52 INFO - PROCESS | 22696 | Values: 125.0 123.0 124.0 126.0 132.0 131.0 138.0 128.0 164.0 137.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:2.04 (1.2%) stddev-sans-first:2.17 16:09:52 INFO - PROCESS | 22696 | Values: 165.0 167.0 167.0 165.0 169.0 165.0 163.0 162.0 165.0 164.0 16:09:52 INFO - PROCESS | 22696 | 16:09:52 INFO - PROCESS | 22696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.32 (2.0%) stddev-sans-first:1.39 16:09:52 INFO - PROCESS | 22696 | Values: 65.0 64.0 68.0 66.0 65.0 66.0 65.0 65.0 65.0 63.0 16:09:52 INFO - PROCESS | 22696 | -------- Summary: end -------- 16:09:52 INFO - PROCESS | 22696 | 16:09:53 INFO - TEST-INFO | 22696: exit 0 16:09:53 INFO - TEST-OK | kraken | took 62854ms 16:09:53 INFO - SUITE-END | took 1181s 16:09:54 INFO - Completed test suite (00:19:42) 16:09:54 INFO - javascript benchmark 16:09:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.565920771092, 3117.7505799536034, 3137.2470607054315, 3170.0489853044082, 3189.2345836999125, 5385.434529374418, 5425.511703946644, 5481.424450432707, 5531.834044978652, 5555.972220138899, 5495.422980587041, 5509.559235261179, 5552.361478429982, 5593.161025846125, 5656.0, 4887.144749668793, 4900.509949005107, 4936.160852655057, 4969.105560999019, 4988.176950803131, 5052.115879721053, 5148.150555158413, 5149.17613181891, 5154.768035438415, 5196.974015129919, 4877.195262781637, 5085.8728531787, 5090.745462726849, 5150.639455238135, 5179.818706345279, 4902.068606964665, 4953.306537084811, 5003.4996500350035, 5023.522765337287, 5118.232265160255, 5001.599872010231, 5004.224345226459, 5010.198368261061, 5012.27322038306, 5071.873203169914, 5506.80726174586, 5519.806806761784, 5525.309336332958, 5544.750486228089, 5552.861178470571, 5644.153376993443, 5678.290213723285, 5861.17943487912, 5911.29064512257, 5920.082387229986, 5976.0, 5993.520518358521, 5998.220231369937, 6002.0, 6058.182145410357, 5537.972310138463, 5554.361248456439, 5555.77776888928, 5556.805511807108, 5559.527440167551, 5763.077907534775, 5813.88372232553, 6001.4298641628975, 6057.969710151421, 6085.0, 5776.393478684761, 5825.941740582624, 5832.679202643895, 5889.1755154278435, 5901.527877769769, 5848.824535263864, 5897.852553686193, 5915.053591425437, 5915.40845915405, 5920.407959204045, 5751.0, 5796.3913789052385, 5809.099589563624, 5893.14549390336, 5896.64620122794, 5771.134329850472, 5913.172155898178, 5921.3782552831335, 5933.080372542263, 5980.0431930890845, 4518.728876267369, 4521.480029796616, 4521.59305662492, 4528.45658520973, 4584.747838868828, 3961.9896926283614, 3974.6621537169085, 4029.012891841459, 4050.5544390117066, 4066.1257829566784, 5520.365158006881, 5544.473275038865, 5569.888602227933, 5577.972110139423, 5684.289463817023, 5876.609142720501, 5936.851578710567, 5940.55445841555, 5941.673207973517, 5978.372270911578, 5296.947030529723, 5576.163575463713, 5595.132754423071, 5597.468240517144, 5607.8317650469735, 4769.594584460359, 4894.339264199288, 4901.044296362175, 4921.5078492151215, 5003.824866129741, 6081.6351018939, 6082.726277317532, 6093.0, 6093.878122437526, 6151.3541078186145, 5938.6436813791315, 5946.672932988728, 5949.553783466171, 5951.49412299942, 5955.047192449188, 5818.94181058184, 5828.154917537018, 5961.7615295387695, 5989.760409583568, 6000.639961602318, 5690.743916523849, 5746.770129194785, 5749.8275051748515, 5759.798407055733, 5804.738786754522, 5786.392428795084, 5834.970825145847, 5837.09525023622, 5856.736446859987, 5868.94131058684, 5647.407022262603, 5695.515881150149, 5700.971495142498, 5705.686187259575, 5755.482006619257, 5706.743196556082, 5712.80005199816, 5722.341930677859, 5814.04068328721, 5841.094630332305, 5691.26013618227, 5704.087346024616, 5725.198472213977, 5758.395368486249, 5768.1636162757, 5473.75368108444, 5582.33012038558, 5842.970785146047, 5861.1501332307425, 5865.706714664335, 5613.46672066153, 5616.3260408749575, 5638.126090455986, 5671.234383358194, 5771.711414429178], "value": 5421.240016664114, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.1005481005482, 579.0474669169216, 606.445102731001, 620.6524346366035, 622.7415622516655, 32237.51643725343, 42794.572054279444, 43613.563864361306, 43674.563254367444, 43689.56310436895, 46017.769911150484, 46288.0, 46393.30410043852, 46409.303860442036, 46491.767541162335, 7977.481463704869, 8911.554422278876, 9091.136342047495, 9094.49980251086, 9096.545172741337, 8166.714165004226, 8436.662533498653, 8439.240468357846, 10586.311889727143, 10872.238943273975, 11050.834237486444, 11099.056580190694, 11114.110871130331, 11115.944420277887, 11127.0, 10922.563097476092, 10943.467914491977, 10964.780704385947, 10987.340759554372, 10999.065079468215, 8047.839043219162, 8266.875996859992, 10345.534450949724, 10665.786684266272, 10696.251262411633, 42767.0, 43213.56786432127, 43260.783696081635, 43296.0, 43321.350179747016, 3337.9499307510405, 3360.7311415086733, 3548.0952357148763, 3585.354636165489, 3724.2923844469465, 30206.09381718552, 32405.675943240738, 32669.6733032669, 32678.183045423815, 32739.50890736641, 4243.575642435762, 4345.913081738379, 4487.887802804923, 4502.549745025472, 4549.31760235964, 331321.0, 332708.0, 333476.0, 333779.0, 335832.0, 58298.0, 58552.0, 58594.0, 58613.41386586165, 58727.0, 57308.71345643245, 57496.0, 57517.424825752045, 57587.71206143942, 57637.42362576321, 15701.214939253221, 16144.515664530083, 16145.838541614434, 16147.111908845127, 16185.676286474205, 11115.72210694739, 11944.820827687592, 12028.398580070962, 12029.0, 12038.87961120395, 16387.344506219855, 17984.37054703079, 18086.920004199423, 18468.44594662162, 18938.337158199396, 6901.482388820858, 6955.443564514928, 6988.161420629557, 7190.9280907191305, 7230.240824713434, 922.4465320807462, 935.3125452792192, 1000.3397757480174, 1044.5143008500932, 1070.4540684250933, 31240.843795780875, 31419.528707069414, 31482.212944676106, 31889.362212755615, 31906.361872762416, 1020.9387436753818, 1023.6007956896816, 1047.5914393386586, 1078.8543546621106, 1089.6186334782763, 15563.455279065178, 16574.585635359213, 16638.916805415894, 16676.416325428552, 16798.496045118667, 582690.0, 583586.0, 585527.0, 585710.0, 586753.0, 57017.42982570204, 57221.14168287479, 57288.427115729144, 57344.0, 57366.139507907414, 1191.1602320364123, 1249.787536118862, 1297.5588299978053, 1305.2038256663427, 1348.7639663059122, 30177.547336790405, 31703.365932680757, 32609.510857336685, 32659.510107348884, 33376.83311583524, 44713.0, 44996.0, 45083.54916450794, 45106.77446612746, 45159.0, 410721.0, 411173.0, 411883.34641595, 414076.0, 416076.0, 27552.44895102127, 28539.857300713364, 28607.713922861338, 29868.253293667833, 30048.549271760505, 46405.3039204419, 47347.28979065248, 47397.289040665106, 47413.0, 47646.761766190946, 39330.213395731356, 39461.408078879416, 39918.201635966536, 39924.40113398359, 41928.58071419247, 39240.62638838313, 39617.0, 40115.79942100271, 41201.587984119775, 41411.7929410351, 37953.4306985401, 38957.41563876601, 40074.1985160301, 40234.79882600568, 40247.1950560993, 28997.56503652489, 30508.694913050585, 31067.68932310648, 31814.299052424598, 33734.83132584322, 27801.0, 27921.68719634349, 28059.630551993287, 28086.57870131909, 28090.43819123647], "value": 19991.9513430777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.0677546785844, 397.63417655756683, 411.2679430613508, 412.8782009307254, 428.38526714165175, 13371.665708357295, 15123.470678526253, 18890.149943252534, 19147.138378772954, 19776.802231977676, 17900.28398864044, 18462.907685461552, 18467.630647387043, 18554.443366698986, 19291.710624340645, 5491.835244942648, 5554.1391084381985, 5858.7656493740415, 5889.0, 5891.263592050994, 5704.515116215106, 5739.885202295952, 5774.307083150028, 5787.739551720183, 5788.942110578903, 5555.083411237141, 5688.4311568842995, 5763.423657634245, 5766.308043034821, 5768.278965129359, 5606.159076138591, 5630.521405680522, 5632.577556683224, 5635.070213414781, 5711.2575365202465, 4851.490593487703, 5012.398512178525, 5038.118329292396, 5049.798008079672, 5103.872403189913, 22794.31617051491, 22814.0, 22864.771352286596, 22992.0, 23106.884465577732, 2318.826088043387, 2368.301351101429, 2408.434018005767, 2597.129961462912, 2768.764655004327, 14350.206224221973, 14640.633984150374, 14666.779998300033, 14746.63133421673, 15356.616084597863, 1891.5743957609566, 1895.1566552883971, 1935.951601209981, 1998.580298137393, 2003.8697484663596, 83418.58290708507, 84693.0, 85445.0, 86485.56757216237, 89124.99999999935, 10559.472026398804, 10581.470926453647, 10881.347119172717, 10981.890181098246, 11026.0, 15175.544733658007, 15204.16377099248, 15209.0, 15213.923930380499, 15231.0, 7606.268373962596, 7829.138794732575, 7976.84046319082, 8061.153578874251, 8239.752807415787, 6922.58464492122, 7218.566885986857, 7351.154617218981, 7364.0, 7639.350655194371, 6560.901586476207, 6589.703463344161, 6623.138991931114, 6675.632840193739, 6689.364510371507, 1859.6466671332403, 1949.814767597076, 1960.460873259865, 1992.2329902987453, 2005.2079428625902, 857.4769390671685, 940.501534186882, 995.6316163019729, 997.9251556133319, 1012.4431562640518, 17941.282348706165, 19679.60640787176, 19702.60594788125, 19850.20599176017, 20016.099275532637, 969.2100937735722, 988.1057642833248, 999.7000899730052, 1001.5743309093606, 1010.7523656704008, 10866.239363244496, 11233.71915702114, 11427.60003399877, 11448.25586336902, 11465.656030318936, 500703.0, 540677.0, 548853.0, 549320.0, 557538.0, 88944.5552772245, 91547.54226228826, 99539.50230248948, 102166.4891675537, 103390.48304758428, 465.9906801863944, 482.7586206896552, 483.24372357260773, 485.0154187000395, 487.52466345313036, 10601.0, 10746.462676866282, 11427.0, 11748.765024699629, 11750.35373054456, 41186.588134119476, 41401.37897931594, 41703.0, 42220.81148495713, 42452.0, 59397.703011484664, 60659.69670151621, 61266.0, 62052.68973655103, 62531.0, 8282.0, 8328.500289982621, 8337.791555211168, 8372.823066002762, 8644.351673624378, 12163.817542737042, 12872.420741066488, 12876.35618219104, 13013.934930325666, 13181.40683669218, 10550.103241224582, 10924.563017479211, 11294.94352528232, 11381.601643942422, 11622.0, 11032.558697652004, 11286.266392684449, 11400.771984560428, 11409.0, 11688.532458701557, 11305.830412543653, 11336.376499292619, 11355.0, 11462.770744585228, 11490.88509114898, 7913.129555748748, 8070.152633973348, 8257.95871020641, 8471.703490377808, 8887.422317549339, 4668.5564871337165, 5525.889482210414, 10948.178886583379, 11133.88866111361, 11506.137039721887], "value": 8980.402622485564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.6960099177195, 1480.4818313590245, 1506.1490258004226, 1639.2951031056648, 1805.4222648752395, 8179.591020448976, 9146.222571081458, 9369.718908432758, 9383.15551600355, 9740.951295243514, 9502.429854208734, 9538.236941044717, 9561.856572151424, 9563.043695630433, 9564.85652715208, 2408.7952524035436, 2430.2587710748217, 2431.9270421887372, 2435.1233555919853, 2437.317551085699, 2348.729896061958, 2412.0592968742203, 2419.080749315258, 2425.551273014498, 2427.854328740272, 2405.7955073818657, 2413.6379543068506, 2414.8188885833547, 2447.889844956981, 2454.8036157107476, 2359.2686267257204, 2384.129792625688, 2385.7017872765905, 2387.164492427654, 2393.4255778613006, 2345.073695890114, 2348.97651023491, 2378.607529757588, 2380.750021247761, 2388.7730665586914, 9216.447013179164, 9479.099485548863, 9591.088846559565, 9592.040795920422, 9596.040395960348, 5110.054639891595, 5326.227696983954, 5334.573234141299, 5336.38631557372, 5338.199270109476, 3319.867205311785, 3328.8169150696704, 3378.9662103378896, 3389.0, 3442.8278586070846, 1023.800358930009, 1028.9897101029044, 1037.4034929915329, 1057.920655950799, 1058.2750815691277, 13424.932875335659, 13463.057585968985, 13481.393337299845, 13517.729645407135, 13621.795673064911, 4326.891827704332, 4461.687681862239, 4551.385562949025, 4622.930656040162, 4624.676272660881, 10443.68668939933, 10458.058774710313, 10459.895401045893, 10466.372017678812, 10470.633527826642, 5147.742612869341, 5229.555487783581, 5238.161894097002, 5241.580673546107, 5242.423333433319, 4286.249906266453, 4409.845655402046, 4714.693544919569, 4801.807927682923, 4817.9036419272115, 3793.639604237593, 3902.453656488094, 3949.960500395017, 3956.624120708528, 3967.6230758077936, 1159.930404175752, 1166.1720178673067, 1168.7837750016274, 1170.0814860334597, 1188.1920294199854, 782.5734974438944, 881.5460038080394, 886.242262865241, 889.0886840988039, 898.7977705016319, 5978.312494063239, 5988.131720900532, 6046.667433291123, 6166.630002199882, 6275.180197742604, 938.5593114569103, 962.9903700962901, 964.363520076746, 974.0503009565617, 1032.2825636182781, 4012.3179059559943, 4200.873973780792, 4204.222218889515, 4207.97896010518, 4242.193983143193, 11794.233374830781, 12110.091743119301, 12194.024478041736, 12205.877941220651, 12212.694682633006, 11760.117991150699, 12048.75902481963, 12130.87869121315, 12138.271703697807, 12148.088893333035, 8773.605187766565, 8814.664114009793, 8899.199072083544, 8901.065388134153, 8939.374243802871, 6029.397060293935, 6165.106059621419, 6325.4939604832425, 6331.461825744679, 6363.745450181941, 10792.784144317227, 10806.405647689453, 11045.447727613426, 11135.777284454103, 11293.322400655987, 10880.405297590542, 11268.154888383568, 11397.08823294118, 11397.373144476802, 11459.942700286778, 6828.9658551706925, 7029.683664235019, 7727.6136193188995, 7786.4549481538015, 8063.27430531255, 9121.49831759233, 9414.95292523533, 9427.670031548863, 9428.198603118812, 9443.00848410907, 8310.54292013945, 8402.285805706584, 8418.242358187792, 8439.957800211205, 8608.956955215184, 7986.920130798618, 8156.9184308156155, 8271.296939760188, 8308.29379502749, 8538.274246688852, 6936.41040511542, 6946.235914049552, 6973.4072603829245, 7031.6835742390695, 7065.293470652894, 4953.108440480604, 4959.602113273836, 5052.747362631927, 5104.953484535736, 5163.4578369270685, 5152.227165924991, 5167.431582525844, 5185.792568297225, 5300.522952934254, 5327.547158491571], "value": 4767.3705224189025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.89149634872314, 324.8960332693538, 332.6773030160744, 336.9208236064523, 337.20419809250166, 5352.57179425646, 5375.973120134394, 5395.190721391788, 5406.810761623344, 5418.864528386787, 5286.810467644778, 5311.389190243123, 5318.680879147244, 5324.54741346985, 5330.493603107709, 3906.453096566483, 4160.875173744777, 4224.809883555232, 4228.365745138239, 4234.407182994383, 4170.728902621335, 4179.6029377209115, 4181.60274773896, 4185.30495149465, 4188.664906807463, 4129.318662420697, 4131.979340103296, 4135.855245066423, 4153.605407486284, 4169.124483858393, 3915.353966595508, 3951.01224693828, 4026.0, 4046.615571520701, 4143.7720925349095, 3492.4935884296633, 3502.6497350265026, 3502.702270307027, 3511.4206155984234, 3523.735719821024, 5089.847304580869, 5104.183330667112, 5116.079105760961, 5122.641415100945, 5157.839486115594, 4432.312991486325, 4481.462224533045, 4615.8153673853, 4662.696924699918, 4700.29495575663, 5521.4478552144865, 5528.861778455571, 5539.335279766414, 5541.174001259386, 5544.63959842609, 3940.290747665419, 3952.7628342299554, 3955.406688996645, 3961.2473630010204, 3968.960310396888, 5642.915356269659, 5727.451284178543, 5773.682447465388, 5776.480116789509, 5791.391903850119, 4015.9196816063513, 4028.657564107025, 4031.879043628696, 4042.4946881639794, 4046.9797651012145, 4093.5906409359422, 4106.0, 4108.91782164355, 4109.2808758467145, 4111.876643700693, 4657.673962822638, 4699.976500117477, 4712.88217794551, 4760.024195040009, 4774.880627984258, 5576.776928922879, 5600.85997850057, 5601.551875849922, 5616.719164041781, 5617.241672374259, 1413.6112569043596, 1428.7856821476653, 1433.731147934065, 1436.8347640021532, 1442.65376309687, 894.3069121430813, 895.4627223665879, 898.2170391772327, 915.9175674189222, 927.1642148545917, 4727.75431669954, 4752.528685083737, 4754.286856971413, 4790.060025689531, 4836.637252206028, 4965.21252349154, 4976.308055261156, 5005.223145783195, 5028.723420211923, 5096.898062038738, 4624.005838744686, 4757.726622960489, 4760.238361862085, 4775.233163729442, 4882.509919940785, 2649.2184805482043, 2692.8384296942245, 2698.662667166604, 2711.3763834318215, 2715.832192157352, 5201.7919283229, 5239.842804715865, 5246.895062098737, 5270.92093618596, 5284.207368894697, 4938.234573641092, 5050.282903812721, 5120.385553733501, 5178.922316165261, 5222.328854766438, 5159.587233021424, 5163.896722065538, 5169.844904652867, 5181.730476033396, 5232.979568984088, 5045.621578381562, 5052.9747351263, 5114.769835357492, 5121.820736274212, 5140.0, 5026.547610715053, 5110.259012443249, 5250.501202385767, 5766.826995190151, 5789.8552536186935, 6027.095935609694, 6028.0, 6033.487153591994, 6034.577579569388, 6036.456718895321, 4106.486689163855, 4125.381192820981, 4142.565030671736, 4152.4601801766785, 4166.41670166183, 5743.62666426681, 5790.826275211751, 5801.723620803416, 5804.854878628068, 5835.649861008353, 5610.270664813548, 5613.438656134354, 5619.438056194512, 5625.409332020079, 5649.943500564942, 5415.133578627559, 5607.915881261866, 5644.971775141263, 5645.350784659817, 5655.4910058094965, 5560.388357280777, 5578.86052848682, 5587.888242235052, 5589.413111623384, 5605.439456054362, 5408.864138530953, 5528.834134975958, 5550.47270509301, 5660.9150862737915, 5702.6293290935955, 5570.387257401601, 5584.190292407659, 5586.0, 5592.273004509388, 5601.271834661468], "value": 4129.829966551231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.3904559215852, 742.2651574940809, 747.3012733094554, 779.5829231361223, 811.5798589632562, 14694.118352898833, 16209.594760131002, 16219.594510137253, 16255.674886502278, 16269.02385856848, 14962.850371496283, 15011.699766004673, 15051.473198438056, 15077.246137693126, 15084.547463576082, 654.8592052708667, 669.4075742967484, 778.4667502760599, 778.6457161991281, 803.3733687723555, 793.1235984237421, 807.3823525003377, 809.11402014794, 809.4536188073057, 810.1129263456505, 844.0377969115214, 844.8521508735977, 845.2561745663808, 846.5555583318776, 846.9153084691543, 671.768239957214, 687.7249100359821, 812.4800127918137, 814.0963530481162, 839.0728245288959, 616.556079622671, 617.0805499805258, 617.3702823120437, 617.8239201827503, 618.5422787137486, 15143.924280378527, 15166.165860877647, 15166.24168791556, 15178.24108794567, 15209.543713688496, 13092.279924604143, 13139.60581182566, 13597.86402135976, 14211.573652790434, 14307.57077287683, 12442.315672638002, 12512.249265044036, 12588.433520491508, 12921.095523313372, 12927.0, 4378.1462614790125, 4426.977865110686, 4527.3435351873795, 4599.333096700993, 4637.930431043537, 80939.5953020237, 81552.59223703903, 83303.16696832955, 83724.0, 84974.57512712458, 10777.407242601575, 11089.390083545482, 11183.273087249303, 11277.0, 11373.203875728785, 20890.582188356148, 20953.161873525194, 20994.160233590486, 21034.78965210359, 21055.052522636517, 7655.349295309849, 7982.800429989181, 8043.396745244013, 8166.4283500154415, 8224.054233763074, 4283.0, 4346.087321662423, 4509.233430316854, 4547.999440123168, 4702.27114797207, 3349.3755528568627, 3500.0374896903554, 3504.8948531544092, 3507.4914137449928, 3509.0350153707936, 597.7071235094772, 603.7464265008707, 610.3133974278936, 610.7373829253464, 611.3092205807409, 658.5127006015609, 686.8317262270775, 695.7390978383106, 696.9860602787916, 735.7277807211366, 9161.404508707046, 9819.954242514645, 10112.595496180218, 10361.896381036093, 10662.0, 564.8361975027278, 610.9969450152721, 642.2774378823823, 668.4017804065431, 718.1489934427719, 4182.309918863414, 4411.625011873963, 4421.579949904753, 4449.488308844459, 4485.416895803524, 71694.2830571698, 116464.0, 123629.38185309016, 124223.0, 125691.0, 63009.68495157495, 65616.34383656198, 65861.0, 66467.0, 107650.46174769233, 95256.52371738097, 98487.62855157391, 98621.01378986261, 98892.50553747329, 100687.0, 8539.359548034046, 8547.657713928795, 8566.957165214135, 8659.220670139717, 8700.173483519056, 56729.432705672414, 56793.0, 57476.425235747105, 58015.70992145181, 58796.0, 46534.30198546957, 60880.695596521735, 79514.0, 80362.0, 80872.59563702144, 12332.691682708004, 12395.442205100611, 12568.183068100545, 12745.553905613258, 12942.547010854574, 12757.744845103232, 13032.609021729364, 13105.082644214814, 13166.934165329112, 13327.200367977952, 11830.11274154459, 13274.535391261259, 14249.358778854768, 14353.497627582985, 14454.783178252545, 14064.226467544384, 14210.21843798601, 14427.639309017359, 14544.127352358892, 14599.0, 13231.933840330737, 13865.376058077209, 14217.218053007184, 14356.138631682132, 14403.495877644233, 10312.896871031493, 10750.784984300113, 11024.614138505114, 11314.547418103184, 11473.483693233991, 10504.579816807242, 11869.228500147463, 12131.696707582381, 12222.388880556115, 12688.429020694275], "value": 7448.085765990706, "unit": "score"}], "name": "dromaeo_css", "value": 7229.222851409021, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 111.0, 111.0, 113.0, 111.0, 112.0, 112.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 118.0, 173.0, 119.0, 122.0, 122.0, 118.0, 157.0, 119.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 164.0, 161.0, 162.0, 162.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 105.0, 76.0, 77.0, 76.0, 77.0, 99.0, 86.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 113.0, 82.0, 83.0, 82.0, 111.0, 82.0, 82.0, 108.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 84.0, 79.0, 79.0, 78.0, 85.0, 79.0, 78.0, 78.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 123.0, 124.0, 126.0, 132.0, 131.0, 138.0, 128.0, 164.0, 137.0], "value": 132.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 167.0, 165.0, 169.0, 165.0, 163.0, 162.0, 165.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 68.0, 66.0, 65.0, 66.0, 65.0, 65.0, 65.0, 63.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1491.7, "alertThreshold": 2.0}]} 16:09:54 INFO - Return code: 0 16:09:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:09:54 INFO - # TBPL SUCCESS # 16:09:54 INFO - Running post-action listener: _resource_record_post_action 16:09:54 INFO - [mozharness: 2016-09-20 23:09:54.188135Z] Finished run-tests step (success) 16:09:54 INFO - Running post-run listener: _resource_record_post_run 16:09:54 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 404144128; Read time: 16; Write time: 1061488 16:09:54 INFO - TinderboxPrint: CPU usage
13.2% 16:09:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:09:54 INFO - TinderboxPrint: I/O write bytes / time
404,144,128 / 1,061,488 16:09:54 INFO - TinderboxPrint: CPU idle
8,306.0 (86.8%) 16:09:54 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 16:09:54 INFO - TinderboxPrint: Swap in / out
0 / 0 16:09:54 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103505920; Read time: 0; Write time: 233732 16:09:54 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 299261952; Read time: 0; Write time: 825968 16:09:54 INFO - Running post-run listener: _upload_blobber_files 16:09:54 INFO - Blob upload gear active. 16:09:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:09:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:09:55 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:00 INFO - (blobuploader) - INFO - Done attempting. 16:10:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:10:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:01 INFO - (blobuploader) - INFO - Done attempting. 16:10:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:10:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:10:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:02 INFO - (blobuploader) - INFO - Done attempting. 16:10:02 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:02 INFO - Return code: 0 16:10:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1274f63af59abe8918eb85cf70bb7c6692879ff87e12f172625cbc0818ebce08a4708750541fa4c36b60eabe618e8e15484f1daeabbb1acd990824e50328c901", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0954d24e58adb2d7c0cd9580b80759cd73e8e26240a99b3da1d16b5eda87989e9fef558c4662108b100ea02492b40b8eb8ffb7361bfcbd687c8a04360c69dc7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ab1b3b745be363808b3fd581e1da6c89a2037e6ac39c5a3356ff70f7162c380ace1c5dee01d4773617cdb7f21c587bf1c22edadb0032cbc1e4cb2e10029d931"} 16:10:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:02 INFO - Contents: 16:10:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1274f63af59abe8918eb85cf70bb7c6692879ff87e12f172625cbc0818ebce08a4708750541fa4c36b60eabe618e8e15484f1daeabbb1acd990824e50328c901", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0954d24e58adb2d7c0cd9580b80759cd73e8e26240a99b3da1d16b5eda87989e9fef558c4662108b100ea02492b40b8eb8ffb7361bfcbd687c8a04360c69dc7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ab1b3b745be363808b3fd581e1da6c89a2037e6ac39c5a3356ff70f7162c380ace1c5dee01d4773617cdb7f21c587bf1c22edadb0032cbc1e4cb2e10029d931"} 16:10:02 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:02 INFO - Copying logs to upload dir... 16:10:02 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.304424 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-20 16:10:02.172455) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 16:10:02.176509) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1274f63af59abe8918eb85cf70bb7c6692879ff87e12f172625cbc0818ebce08a4708750541fa4c36b60eabe618e8e15484f1daeabbb1acd990824e50328c901", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0954d24e58adb2d7c0cd9580b80759cd73e8e26240a99b3da1d16b5eda87989e9fef558c4662108b100ea02492b40b8eb8ffb7361bfcbd687c8a04360c69dc7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ab1b3b745be363808b3fd581e1da6c89a2037e6ac39c5a3356ff70f7162c380ace1c5dee01d4773617cdb7f21c587bf1c22edadb0032cbc1e4cb2e10029d931"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011448 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474399820/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1274f63af59abe8918eb85cf70bb7c6692879ff87e12f172625cbc0818ebce08a4708750541fa4c36b60eabe618e8e15484f1daeabbb1acd990824e50328c901", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0954d24e58adb2d7c0cd9580b80759cd73e8e26240a99b3da1d16b5eda87989e9fef558c4662108b100ea02492b40b8eb8ffb7361bfcbd687c8a04360c69dc7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ab1b3b745be363808b3fd581e1da6c89a2037e6ac39c5a3356ff70f7162c380ace1c5dee01d4773617cdb7f21c587bf1c22edadb0032cbc1e4cb2e10029d931"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 16:10:02.202399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 16:10:02.202768) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1474411757.436780-366277469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004543 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 16:10:02.288169) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 16:10:02.288517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 16:10:02.288836) ========= ========= Total master_lag: 0.17 =========