builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1474455146.68 results: success (0) buildid: 20160921030608 builduid: 705e265f565c414bac9f138d2c69ccc1 revision: bd6a9044d4bf00c5f4710e6104f611620dd7bc74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.678463) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.678941) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.679259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.693980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.694277) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 03:52:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-09-21 03:52:26 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126526 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.834993) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.835340) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014596 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:26.884600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 03:52:26.884990) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bd6a9044d4bf00c5f4710e6104f611620dd7bc74 --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 bd6a9044d4bf00c5f4710e6104f611620dd7bc74 --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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 03:52:27,107 truncating revision to first 12 chars 2016-09-21 03:52:27,107 Setting DEBUG logging. 2016-09-21 03:52:27,107 attempt 1/10 2016-09-21 03:52:27,107 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bd6a9044d4bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 03:52:28,498 unpacking tar archive at: fx-team-bd6a9044d4bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.723543 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 03:52:28.624672) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:28.625050) ========= script_repo_revision: bd6a9044d4bf00c5f4710e6104f611620dd7bc74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:28.625511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:28.625843) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 03:52:28.636210) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-21 03:52:28.636516) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:52:28 INFO - MultiFileLogger online at 20160921 03:52:28 in /builds/slave/test 03:52:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:52:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:52:28 INFO - {'append_to_log': False, 03:52:28 INFO - 'base_work_dir': '/builds/slave/test', 03:52:28 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:52:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:52:28 INFO - 'branch': 'Fx-Team-Non-PGO', 03:52:28 INFO - 'buildbot_json_path': 'buildprops.json', 03:52:28 INFO - 'config_files': ('talos/linux_config.py',), 03:52:28 INFO - 'default_actions': ('clobber', 03:52:28 INFO - 'read-buildbot-config', 03:52:28 INFO - 'download-and-extract', 03:52:28 INFO - 'populate-webroot', 03:52:28 INFO - 'create-virtualenv', 03:52:28 INFO - 'install', 03:52:28 INFO - 'run-tests'), 03:52:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:52:28 INFO - 'download_minidump_stackwalk': True, 03:52:28 INFO - 'download_symbols': 'ondemand', 03:52:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:52:28 INFO - 'tooltool.py': '/tools/tooltool.py', 03:52:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:52:28 INFO - '/tools/misc-python/virtualenv.py')}, 03:52:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:52:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:52:28 INFO - 'installer_path': 'installer.exe', 03:52:28 INFO - 'log_level': 'info', 03:52:28 INFO - 'log_name': 'talos', 03:52:28 INFO - 'log_to_console': True, 03:52:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:52:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:52:28 INFO - 'opt_config_files': (), 03:52:28 INFO - 'pip_index': False, 03:52:28 INFO - 'sps_profile': False, 03:52:28 INFO - 'sps_profile_interval': 0, 03:52:28 INFO - 'suite': 'dromaeojs', 03:52:28 INFO - 'system_bits': '32', 03:52:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:52:28 INFO - 'title': 'talos-linux64-ix-002', 03:52:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:52:28 INFO - 'use_talos_json': True, 03:52:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:52:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:52:28 INFO - 'work_dir': 'build'} 03:52:28 INFO - [mozharness: 2016-09-21 10:52:28.958772Z] Running clobber step. 03:52:28 INFO - Running pre-action listener: _resource_record_pre_action 03:52:28 INFO - Running main action method: clobber 03:52:28 INFO - rmtree: /builds/slave/test/build 03:52:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:52:29 INFO - Running post-action listener: _resource_record_post_action 03:52:29 INFO - [mozharness: 2016-09-21 10:52:29.926825Z] Finished clobber step (success) 03:52:29 INFO - [mozharness: 2016-09-21 10:52:29.926924Z] Running read-buildbot-config step. 03:52:29 INFO - Running pre-action listener: _resource_record_pre_action 03:52:29 INFO - Running main action method: read_buildbot_config 03:52:29 INFO - Using buildbot properties: 03:52:29 INFO - { 03:52:29 INFO - "project": "", 03:52:29 INFO - "product": "firefox", 03:52:29 INFO - "script_repo_revision": "production", 03:52:29 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:52:29 INFO - "repository": "", 03:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 03:52:29 INFO - "buildid": "20160921030608", 03:52:29 INFO - "pgo_build": "False", 03:52:29 INFO - "basedir": "/builds/slave/test", 03:52:29 INFO - "buildnumber": 3338, 03:52:29 INFO - "slavename": "talos-linux64-ix-002", 03:52:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:52:29 INFO - "platform": "ubuntu64_hw", 03:52:29 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:52:29 INFO - "branch": "fx-team", 03:52:29 INFO - "repo_path": "integration/fx-team", 03:52:29 INFO - "revision": "bd6a9044d4bf00c5f4710e6104f611620dd7bc74", 03:52:29 INFO - "stage_platform": "linux64", 03:52:29 INFO - "builduid": "705e265f565c414bac9f138d2c69ccc1", 03:52:29 INFO - "slavebuilddir": "test" 03:52:29 INFO - } 03:52:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 03:52:29 INFO - Running post-action listener: _resource_record_post_action 03:52:29 INFO - [mozharness: 2016-09-21 10:52:29.929401Z] Finished read-buildbot-config step (success) 03:52:29 INFO - [mozharness: 2016-09-21 10:52:29.929493Z] Running download-and-extract step. 03:52:29 INFO - Running pre-action listener: _resource_record_pre_action 03:52:29 INFO - Running main action method: download_and_extract 03:52:29 INFO - mkdir: /builds/slave/test/build/tests 03:52:29 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')]} 03:52:29 INFO - trying https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 03:52:29 INFO - Downloading https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/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 03:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/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 03:52:30 INFO - Downloaded 1361 bytes. 03:52:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 03:52:30 INFO - Using the following test package requirements: 03:52:30 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 03:52:30 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:52:30 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:52:30 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'jsshell-linux-x86_64.zip'], 03:52:30 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:52:30 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 03:52:30 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:52:30 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:52:30 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:52:30 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 03:52:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:52:30 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:52:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 03:52:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:52:30 INFO - Fetch https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 03:52:31 INFO - Expected file size: 20369760 03:52:35 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 03:52:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 03:52:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:52:35 INFO - Fetch https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 03:52:35 INFO - Expected file size: 20369760 03:52:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 03:52:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:52:40 INFO - Fetch https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 03:52:41 INFO - Expected file size: 14414059 03:52:41 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')]} 03:52:41 INFO - trying https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 03:52:41 INFO - Downloading https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/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 03:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/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 03:52:42 INFO - Downloaded 55601605 bytes. 03:52:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 03:52:42 INFO - mkdir: /builds/slave/test/properties 03:52:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:42 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:42 INFO - Contents: 03:52:42 INFO - build_url:https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 03:52:43 INFO - Running post-action listener: _resource_record_post_action 03:52:43 INFO - Running post-action listener: set_extra_try_arguments 03:52:43 INFO - [mozharness: 2016-09-21 10:52:43.333050Z] Finished download-and-extract step (success) 03:52:43 INFO - [mozharness: 2016-09-21 10:52:43.333278Z] Running populate-webroot step. 03:52:43 INFO - Running pre-action listener: _resource_record_pre_action 03:52:43 INFO - Running main action method: populate_webroot 03:52:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:43 INFO - '--tppagecycles', 03:52:43 INFO - '7'], 03:52:43 INFO - 'tests': ['tsvgm']}}, 03:52:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['damp', 'tps']}, 03:52:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'tests': ['damp', 'tps']}, 03:52:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['dromaeo_dom']}, 03:52:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['basic_compositor_video']}, 03:52:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['a11yr', 03:52:43 INFO - 'ts_paint', 03:52:43 INFO - 'tpaint', 03:52:43 INFO - 'sessionrestore', 03:52:43 INFO - 'sessionrestore_no_auto_restore', 03:52:43 INFO - 'tabpaint']}, 03:52:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['tsvgx', 03:52:43 INFO - 'tsvgr_opacity', 03:52:43 INFO - 'tart', 03:52:43 INFO - 'tscrollx', 03:52:43 INFO - 'cart']}, 03:52:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:43 INFO - 'tsvgr_opacity', 03:52:43 INFO - 'tart', 03:52:43 INFO - 'tscrollx', 03:52:43 INFO - 'cart']}, 03:52:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'talos_options': ['--disable-e10s'], 03:52:43 INFO - 'tests': ['tp5o']}, 03:52:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'tests': ['tp5o']}, 03:52:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'talos_options': ['--disable-e10s', 03:52:43 INFO - '--xperf_path', 03:52:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:43 INFO - 'tests': ['tp5n']}, 03:52:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:43 INFO - 'talos_options': ['--xperf_path', 03:52:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:43 INFO - 'tests': ['tp5n']}}, 03:52:43 INFO - 'talos.zip': {'path': '', 03:52:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:43 INFO - Running post-action listener: _resource_record_post_action 03:52:43 INFO - [mozharness: 2016-09-21 10:52:43.354338Z] Finished populate-webroot step (success) 03:52:43 INFO - [mozharness: 2016-09-21 10:52:43.354427Z] Running create-virtualenv step. 03:52:43 INFO - Running pre-action listener: _resource_record_pre_action 03:52:43 INFO - Running main action method: create_virtualenv 03:52:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:43 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 03:52:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:43 INFO - Using real prefix '/usr' 03:52:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:45 INFO - Installing pip.................done. 03:52:45 INFO - Return code: 0 03:52:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52:45 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')]} 03:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:45 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')]} 03:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:45 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:45 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 03:52:45 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 03:52:45 INFO - Using env: {'DISPLAY': ':0', 03:52:45 INFO - 'HOME': '/home/cltbld', 03:52:45 INFO - 'LANG': 'en_US.UTF-8', 03:52:45 INFO - 'LANGUAGE': 'en_US:en', 03:52:45 INFO - 'LOGNAME': 'cltbld', 03:52:45 INFO - 'MAIL': '/var/mail/cltbld', 03:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:45 INFO - 'MOZ_NO_REMOTE': '1', 03:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:45 INFO - 'NO_EM_RESTART': '1', 03:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:45 INFO - 'PWD': '/builds/slave/test', 03:52:45 INFO - 'SHELL': '/bin/bash', 03:52:45 INFO - 'SHLVL': '1', 03:52:45 INFO - 'TERM': 'linux', 03:52:45 INFO - 'TMOUT': '86400', 03:52:45 INFO - 'USER': 'cltbld', 03:52:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:45 INFO - '_': '/tools/buildbot/bin/python'} 03:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:46 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 03:52:46 INFO - Cleaning up... 03:52:46 INFO - Return code: 0 03:52:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:46 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:46 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 03:52:46 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 03:52:46 INFO - Using env: {'DISPLAY': ':0', 03:52:46 INFO - 'HOME': '/home/cltbld', 03:52:46 INFO - 'LANG': 'en_US.UTF-8', 03:52:46 INFO - 'LANGUAGE': 'en_US:en', 03:52:46 INFO - 'LOGNAME': 'cltbld', 03:52:46 INFO - 'MAIL': '/var/mail/cltbld', 03:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:46 INFO - 'MOZ_NO_REMOTE': '1', 03:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:46 INFO - 'NO_EM_RESTART': '1', 03:52:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:46 INFO - 'PWD': '/builds/slave/test', 03:52:46 INFO - 'SHELL': '/bin/bash', 03:52:46 INFO - 'SHLVL': '1', 03:52:46 INFO - 'TERM': 'linux', 03:52:46 INFO - 'TMOUT': '86400', 03:52:46 INFO - 'USER': 'cltbld', 03:52:46 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:46 INFO - '_': '/tools/buildbot/bin/python'} 03:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:46 INFO - Downloading/unpacking psutil>=3.1.1 03:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:48 INFO - Installing collected packages: psutil 03:52:48 INFO - Running setup.py install for psutil 03:52:48 INFO - building 'psutil._psutil_linux' extension 03:52:48 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 03:52:48 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 03:52:48 INFO - building 'psutil._psutil_posix' extension 03:52:48 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 03:52:48 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 03:52:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:48 INFO - Successfully installed psutil 03:52:48 INFO - Cleaning up... 03:52:48 INFO - Return code: 0 03:52:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:48 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')]} 03:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:48 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')]} 03:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:48 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:48 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 03:52:48 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 03:52:48 INFO - Using env: {'DISPLAY': ':0', 03:52:48 INFO - 'HOME': '/home/cltbld', 03:52:48 INFO - 'LANG': 'en_US.UTF-8', 03:52:48 INFO - 'LANGUAGE': 'en_US:en', 03:52:48 INFO - 'LOGNAME': 'cltbld', 03:52:48 INFO - 'MAIL': '/var/mail/cltbld', 03:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:48 INFO - 'MOZ_NO_REMOTE': '1', 03:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:48 INFO - 'NO_EM_RESTART': '1', 03:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:48 INFO - 'PWD': '/builds/slave/test', 03:52:48 INFO - 'SHELL': '/bin/bash', 03:52:48 INFO - 'SHLVL': '1', 03:52:48 INFO - 'TERM': 'linux', 03:52:48 INFO - 'TMOUT': '86400', 03:52:48 INFO - 'USER': 'cltbld', 03:52:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:48 INFO - '_': '/tools/buildbot/bin/python'} 03:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:52:51 INFO - Installing collected packages: mozsystemmonitor 03:52:51 INFO - Running setup.py install for mozsystemmonitor 03:52:51 INFO - Successfully installed mozsystemmonitor 03:52:51 INFO - Cleaning up... 03:52:51 INFO - Return code: 0 03:52:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:52:51 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')]} 03:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 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')]} 03:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:51 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 03:52:51 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 03:52:51 INFO - Using env: {'DISPLAY': ':0', 03:52:51 INFO - 'HOME': '/home/cltbld', 03:52:51 INFO - 'LANG': 'en_US.UTF-8', 03:52:51 INFO - 'LANGUAGE': 'en_US:en', 03:52:51 INFO - 'LOGNAME': 'cltbld', 03:52:51 INFO - 'MAIL': '/var/mail/cltbld', 03:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:51 INFO - 'MOZ_NO_REMOTE': '1', 03:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:51 INFO - 'NO_EM_RESTART': '1', 03:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:51 INFO - 'PWD': '/builds/slave/test', 03:52:51 INFO - 'SHELL': '/bin/bash', 03:52:51 INFO - 'SHLVL': '1', 03:52:51 INFO - 'TERM': 'linux', 03:52:51 INFO - 'TMOUT': '86400', 03:52:51 INFO - 'USER': 'cltbld', 03:52:51 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:51 INFO - '_': '/tools/buildbot/bin/python'} 03:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:51 INFO - Downloading/unpacking jsonschema==2.5.1 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:52:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:52:53 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 03:52:53 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 03:52:53 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 03:52:53 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 03:52:53 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 03:52:53 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 03:52:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:52:53 INFO - warning: no files found matching '*.txt' 03:52:53 INFO - no previously-included directories found matching 'build' 03:52:53 INFO - no previously-included directories found matching 'dist' 03:52:53 INFO - no previously-included directories found matching '.git*' 03:52:53 INFO - Installing collected packages: jsonschema, functools32 03:52:53 INFO - Running setup.py install for functools32 03:52:53 INFO - warning: no files found matching '*.txt' 03:52:53 INFO - no previously-included directories found matching 'build' 03:52:53 INFO - no previously-included directories found matching 'dist' 03:52:53 INFO - no previously-included directories found matching '.git*' 03:52:53 INFO - Successfully installed jsonschema functools32 03:52:53 INFO - Cleaning up... 03:52:53 INFO - Return code: 0 03:52:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:53 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:53 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 03:52:53 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 03:52:53 INFO - Using env: {'DISPLAY': ':0', 03:52:53 INFO - 'HOME': '/home/cltbld', 03:52:53 INFO - 'LANG': 'en_US.UTF-8', 03:52:53 INFO - 'LANGUAGE': 'en_US:en', 03:52:53 INFO - 'LOGNAME': 'cltbld', 03:52:53 INFO - 'MAIL': '/var/mail/cltbld', 03:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:53 INFO - 'MOZ_NO_REMOTE': '1', 03:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:53 INFO - 'NO_EM_RESTART': '1', 03:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:53 INFO - 'PWD': '/builds/slave/test', 03:52:53 INFO - 'SHELL': '/bin/bash', 03:52:53 INFO - 'SHLVL': '1', 03:52:53 INFO - 'TERM': 'linux', 03:52:53 INFO - 'TMOUT': '86400', 03:52:53 INFO - 'USER': 'cltbld', 03:52:53 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:53 INFO - '_': '/tools/buildbot/bin/python'} 03:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:52:54 INFO - Cleaning up... 03:52:54 INFO - Return code: 0 03:52:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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', '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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:52: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 blobuploader==1.2.4 03:52:54 INFO - Using env: {'DISPLAY': ':0', 03:52:54 INFO - 'HOME': '/home/cltbld', 03:52:54 INFO - 'LANG': 'en_US.UTF-8', 03:52:54 INFO - 'LANGUAGE': 'en_US:en', 03:52:54 INFO - 'LOGNAME': 'cltbld', 03:52:54 INFO - 'MAIL': '/var/mail/cltbld', 03:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:54 INFO - 'MOZ_NO_REMOTE': '1', 03:52:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:54 INFO - 'NO_EM_RESTART': '1', 03:52:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:54 INFO - 'PWD': '/builds/slave/test', 03:52:54 INFO - 'SHELL': '/bin/bash', 03:52:54 INFO - 'SHLVL': '1', 03:52:54 INFO - 'TERM': 'linux', 03:52:54 INFO - 'TMOUT': '86400', 03:52:54 INFO - 'USER': 'cltbld', 03:52:54 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:54 INFO - '_': '/tools/buildbot/bin/python'} 03:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:52:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:52:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 INFO - Downloading docopt-0.6.1.tar.gz 03:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:52:56 INFO - Installing collected packages: blobuploader, requests, docopt 03:52:56 INFO - Running setup.py install for blobuploader 03:52:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:52:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:52:57 INFO - Running setup.py install for requests 03:52:57 INFO - Running setup.py install for docopt 03:52:57 INFO - Successfully installed blobuploader requests docopt 03:52:57 INFO - Cleaning up... 03:52:57 INFO - Return code: 0 03:52:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:57 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:52:57 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 03:52:57 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 03:52:57 INFO - Using env: {'DISPLAY': ':0', 03:52:57 INFO - 'HOME': '/home/cltbld', 03:52:57 INFO - 'LANG': 'en_US.UTF-8', 03:52:57 INFO - 'LANGUAGE': 'en_US:en', 03:52:57 INFO - 'LOGNAME': 'cltbld', 03:52:57 INFO - 'MAIL': '/var/mail/cltbld', 03:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:57 INFO - 'MOZ_NO_REMOTE': '1', 03:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:57 INFO - 'NO_EM_RESTART': '1', 03:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:57 INFO - 'PWD': '/builds/slave/test', 03:52:57 INFO - 'SHELL': '/bin/bash', 03:52:57 INFO - 'SHLVL': '1', 03:52:57 INFO - 'TERM': 'linux', 03:52:57 INFO - 'TMOUT': '86400', 03:52:57 INFO - 'USER': 'cltbld', 03:52:57 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:57 INFO - '_': '/tools/buildbot/bin/python'} 03:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:57 INFO - Running setup.py (path:/tmp/pip-9AQgJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:57 INFO - Running setup.py (path:/tmp/pip-m4KTOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:57 INFO - Running setup.py (path:/tmp/pip-awJqzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:58 INFO - Running setup.py (path:/tmp/pip-PEpSvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:58 INFO - Running setup.py (path:/tmp/pip-S2y8AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:58 INFO - Running setup.py (path:/tmp/pip-M6fvgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:58 INFO - Running setup.py (path:/tmp/pip-i7PYB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:58 INFO - Running setup.py (path:/tmp/pip-6AjFej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:58 INFO - Running setup.py (path:/tmp/pip-7Nkcss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:58 INFO - Running setup.py (path:/tmp/pip-AX34tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:58 INFO - Running setup.py (path:/tmp/pip-CP7gAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:58 INFO - Running setup.py (path:/tmp/pip-yFDzsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:58 INFO - Running setup.py (path:/tmp/pip-G5Eev0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:58 INFO - Running setup.py (path:/tmp/pip-_Tn_Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:58 INFO - Running setup.py (path:/tmp/pip-drwdt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:58 INFO - Running setup.py (path:/tmp/pip-bGN0fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:58 INFO - Running setup.py (path:/tmp/pip-wFpAgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:52:59 INFO - Running setup.py install for manifestparser 03:52:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Running setup.py install for mozcrash 03:52:59 INFO - Running setup.py install for mozdebug 03:52:59 INFO - Running setup.py install for mozdevice 03:52:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Running setup.py install for mozfile 03:52:59 INFO - Running setup.py install for mozhttpd 03:52:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Running setup.py install for mozinfo 03:52:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Running setup.py install for mozInstall 03:52:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:52:59 INFO - Running setup.py install for mozleak 03:52:59 INFO - Running setup.py install for mozlog 03:53:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Running setup.py install for moznetwork 03:53:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Running setup.py install for mozprocess 03:53:00 INFO - Running setup.py install for mozprofile 03:53:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Running setup.py install for mozrunner 03:53:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Running setup.py install for mozscreenshot 03:53:00 INFO - Running setup.py install for moztest 03:53:00 INFO - Running setup.py install for mozversion 03:53:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:53:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:53:00 INFO - Cleaning up... 03:53:00 INFO - Return code: 0 03:53:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:00 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')]} 03:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:00 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')]} 03:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:00 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:53:00 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 03:53:00 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 03:53:00 INFO - Using env: {'DISPLAY': ':0', 03:53:00 INFO - 'HOME': '/home/cltbld', 03:53:00 INFO - 'LANG': 'en_US.UTF-8', 03:53:00 INFO - 'LANGUAGE': 'en_US:en', 03:53:00 INFO - 'LOGNAME': 'cltbld', 03:53:00 INFO - 'MAIL': '/var/mail/cltbld', 03:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:00 INFO - 'MOZ_NO_REMOTE': '1', 03:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:00 INFO - 'NO_EM_RESTART': '1', 03:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:00 INFO - 'PWD': '/builds/slave/test', 03:53:00 INFO - 'SHELL': '/bin/bash', 03:53:00 INFO - 'SHLVL': '1', 03:53:00 INFO - 'TERM': 'linux', 03:53:00 INFO - 'TMOUT': '86400', 03:53:00 INFO - 'USER': 'cltbld', 03:53:00 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:00 INFO - '_': '/tools/buildbot/bin/python'} 03:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:01 INFO - Running setup.py (path:/tmp/pip-P48FvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:01 INFO - Running setup.py (path:/tmp/pip-loWOeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:01 INFO - Running setup.py (path:/tmp/pip-h3Y_Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:01 INFO - Running setup.py (path:/tmp/pip-jhdZNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:01 INFO - Running setup.py (path:/tmp/pip-NNOSEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:01 INFO - Running setup.py (path:/tmp/pip-0JQlg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:01 INFO - Running setup.py (path:/tmp/pip-u2isNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:01 INFO - Running setup.py (path:/tmp/pip-yHVRtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:01 INFO - Running setup.py (path:/tmp/pip-j_4OlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:01 INFO - Running setup.py (path:/tmp/pip-l1euM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:01 INFO - Running setup.py (path:/tmp/pip-5fmXnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:01 INFO - Running setup.py (path:/tmp/pip-YUJFyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:01 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)) 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:01 INFO - Running setup.py (path:/tmp/pip-VtmCkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:02 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)) 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:02 INFO - Running setup.py (path:/tmp/pip-h_xRZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:02 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)) 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:02 INFO - Running setup.py (path:/tmp/pip-ppor1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:02 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)) 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:02 INFO - Running setup.py (path:/tmp/pip-CeoDQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:02 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)) 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:02 INFO - Running setup.py (path:/tmp/pip-zdo0Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:02 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)) 03:53:02 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)) 03:53:02 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)) 03:53:02 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)) 03:53:02 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)) 03:53:02 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)) 03:53:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:53:02 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 03:53:02 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 03:53:02 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 03:53:02 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 03:53:02 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 03:53:02 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 03:53:04 INFO - Downloading blessings-1.6.tar.gz 03:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:53:04 INFO - Installing collected packages: blessings 03:53:04 INFO - Running setup.py install for blessings 03:53:04 INFO - Successfully installed blessings 03:53:04 INFO - Cleaning up... 03:53:04 INFO - Return code: 0 03:53:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:53:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:04 INFO - Reading from file tmpfile_stdout 03:53:04 INFO - Current package versions: 03:53:04 INFO - argparse == 1.2.1 03:53:04 INFO - blessings == 1.6 03:53:04 INFO - blobuploader == 1.2.4 03:53:04 INFO - docopt == 0.6.1 03:53:04 INFO - functools32 == 3.2.3-2 03:53:04 INFO - jsonschema == 2.5.1 03:53:04 INFO - manifestparser == 1.1 03:53:04 INFO - mozInstall == 1.12 03:53:04 INFO - mozcrash == 0.17 03:53:04 INFO - mozdebug == 0.1 03:53:04 INFO - mozdevice == 0.48 03:53:04 INFO - mozfile == 1.2 03:53:04 INFO - mozhttpd == 0.7 03:53:04 INFO - mozinfo == 0.9 03:53:04 INFO - mozleak == 0.1 03:53:04 INFO - mozlog == 3.4 03:53:04 INFO - moznetwork == 0.27 03:53:04 INFO - mozprocess == 0.23 03:53:04 INFO - mozprofile == 0.28 03:53:04 INFO - mozrunner == 6.12 03:53:04 INFO - mozscreenshot == 0.1 03:53:04 INFO - mozsystemmonitor == 0.3 03:53:04 INFO - moztest == 0.7 03:53:04 INFO - mozversion == 1.4 03:53:04 INFO - psutil == 3.1.1 03:53:04 INFO - requests == 1.2.3 03:53:04 INFO - wsgiref == 0.1.2 03:53:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:04 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')]} 03:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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')]} 03:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:53:04 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 03:53:04 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 03:53:04 INFO - Using env: {'DISPLAY': ':0', 03:53:04 INFO - 'HOME': '/home/cltbld', 03:53:04 INFO - 'LANG': 'en_US.UTF-8', 03:53:04 INFO - 'LANGUAGE': 'en_US:en', 03:53:04 INFO - 'LOGNAME': 'cltbld', 03:53:04 INFO - 'MAIL': '/var/mail/cltbld', 03:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:04 INFO - 'MOZ_NO_REMOTE': '1', 03:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:04 INFO - 'NO_EM_RESTART': '1', 03:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:04 INFO - 'PWD': '/builds/slave/test', 03:53:04 INFO - 'SHELL': '/bin/bash', 03:53:04 INFO - 'SHLVL': '1', 03:53:04 INFO - 'TERM': 'linux', 03:53:04 INFO - 'TMOUT': '86400', 03:53:04 INFO - 'USER': 'cltbld', 03:53:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:04 INFO - '_': '/tools/buildbot/bin/python'} 03:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 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)) 03:53:04 INFO - Cleaning up... 03:53:04 INFO - Return code: 0 03:53:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:53:04 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')]} 03:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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')]} 03:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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 0x7febe9630f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', '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 03:53:04 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 03:53:04 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 03:53:04 INFO - Using env: {'DISPLAY': ':0', 03:53:04 INFO - 'HOME': '/home/cltbld', 03:53:04 INFO - 'LANG': 'en_US.UTF-8', 03:53:04 INFO - 'LANGUAGE': 'en_US:en', 03:53:04 INFO - 'LOGNAME': 'cltbld', 03:53:04 INFO - 'MAIL': '/var/mail/cltbld', 03:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:04 INFO - 'MOZ_NO_REMOTE': '1', 03:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:04 INFO - 'NO_EM_RESTART': '1', 03:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:04 INFO - 'PWD': '/builds/slave/test', 03:53:04 INFO - 'SHELL': '/bin/bash', 03:53:04 INFO - 'SHLVL': '1', 03:53:04 INFO - 'TERM': 'linux', 03:53:04 INFO - 'TMOUT': '86400', 03:53:04 INFO - 'USER': 'cltbld', 03:53:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:04 INFO - '_': '/tools/buildbot/bin/python'} 03:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:53:05 INFO - Cleaning up... 03:53:05 INFO - Return code: 0 03:53:05 INFO - Running post-action listener: _resource_record_post_action 03:53:05 INFO - Running post-action listener: _start_resource_monitoring 03:53:05 INFO - Starting resource monitoring. 03:53:05 INFO - [mozharness: 2016-09-21 10:53:05.201363Z] Finished create-virtualenv step (success) 03:53:05 INFO - [mozharness: 2016-09-21 10:53:05.201934Z] Running install step. 03:53:05 INFO - Running pre-action listener: _resource_record_pre_action 03:53:05 INFO - Running main action method: install 03:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:05 INFO - Reading from file tmpfile_stdout 03:53:05 INFO - Detecting whether we're running mozinstall >=1.0... 03:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:53:05 INFO - Reading from file tmpfile_stdout 03:53:05 INFO - Output received: 03:53:05 INFO - Usage: mozinstall [options] installer 03:53:05 INFO - Options: 03:53:05 INFO - -h, --help show this help message and exit 03:53:05 INFO - -d DEST, --destination=DEST 03:53:05 INFO - Directory to install application into. [default: 03:53:05 INFO - "/builds/slave/test"] 03:53:05 INFO - --app=APP Application being installed. [default: firefox] 03:53:05 INFO - mkdir: /builds/slave/test/build/application 03:53:05 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'] 03:53:05 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 03:53:21 INFO - Reading from file tmpfile_stdout 03:53:21 INFO - Output received: 03:53:21 INFO - /builds/slave/test/build/application/firefox/firefox 03:53:21 INFO - Running post-action listener: _resource_record_post_action 03:53:21 INFO - [mozharness: 2016-09-21 10:53:21.539726Z] Finished install step (success) 03:53:21 INFO - [mozharness: 2016-09-21 10:53:21.539968Z] Running run-tests step. 03:53:21 INFO - Running pre-action listener: _resource_record_pre_action 03:53:21 INFO - Running main action method: run_tests 03:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:21 INFO - Python 2.7.3 03:53:21 INFO - Return code: 0 03:53:21 INFO - grabbing minidump binary from tooltool 03:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:21 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 0x10c3f60>, '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 0x10c4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:21 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 03:53:21 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 03:53:21 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 03:53:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:21 INFO - Return code: 0 03:53:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:53:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:53:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 03:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 03:53:21 INFO - Using env: {'DISPLAY': ':0', 03:53:21 INFO - 'HOME': '/home/cltbld', 03:53:21 INFO - 'LANG': 'en_US.UTF-8', 03:53:21 INFO - 'LANGUAGE': 'en_US:en', 03:53:21 INFO - 'LOGNAME': 'cltbld', 03:53:21 INFO - 'MAIL': '/var/mail/cltbld', 03:53:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:21 INFO - 'MOZ_NO_REMOTE': '1', 03:53:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:21 INFO - 'NO_EM_RESTART': '1', 03:53:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:21 INFO - 'PWD': '/builds/slave/test', 03:53:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:21 INFO - 'SHELL': '/bin/bash', 03:53:21 INFO - 'SHLVL': '1', 03:53:21 INFO - 'TERM': 'linux', 03:53:21 INFO - 'TMOUT': '86400', 03:53:21 INFO - 'USER': 'cltbld', 03:53:21 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072', 03:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:21 INFO - '_': '/tools/buildbot/bin/python'} 03:53:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 03:53:21 INFO - mozversion application_buildid: 20160921030608 03:53:21 INFO - mozversion application_changeset: bd6a9044d4bf00c5f4710e6104f611620dd7bc74 03:53:21 INFO - mozversion application_display_name: Nightly 03:53:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:21 INFO - mozversion application_name: Firefox 03:53:21 INFO - mozversion application_remotingname: firefox 03:53:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:53:21 INFO - mozversion application_vendor: Mozilla 03:53:21 INFO - mozversion application_version: 52.0a1 03:53:21 INFO - mozversion platform_buildid: 20160921030608 03:53:21 INFO - mozversion platform_changeset: bd6a9044d4bf00c5f4710e6104f611620dd7bc74 03:53:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:53:21 INFO - mozversion platform_version: 52.0a1 03:53:21 INFO - using testdate: 1474455201 03:53:21 INFO - actual date: 1474455201 03:53:21 INFO - starting webserver on 'localhost:39051' 03:53:21 INFO - SUITE-START | Running 2 tests 03:53:21 INFO - TEST-START | dromaeo_css 03:53:21 INFO - operating with platform_type : linux_ 03:53:21 INFO - Initialising browser for dromaeo_css test... 03:53:21 INFO - TEST-INFO | started process 2792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjMjb4T/profile http://localhost:39051/getInfo.html) 03:53:26 INFO - PROCESS | 2792 | __metrics Screen width/height:1600/1200 03:53:26 INFO - PROCESS | 2792 | colorDepth:24 03:53:26 INFO - PROCESS | 2792 | Browser inner width/height: 1024/697 03:53:26 INFO - PROCESS | 2792 | __metrics 03:53:27 INFO - PROCESS | 2792 | 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" 03:53:27 INFO - PROCESS | 2792 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:53:27 INFO - PROCESS | 2792 | console.error: PushService: 03:53:27 INFO - PROCESS | 2792 | stateChangeProcessEnqueue: Error transitioning state 03:53:27 INFO - PROCESS | 2792 | UnknownError 03:53:28 INFO - TEST-INFO | 2792: exit 0 03:53:28 INFO - Browser initialized. 03:53:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:28 INFO - TEST-INFO | started process 2919 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjMjb4T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:40 INFO - PROCESS | 2919 | RSS: Main: 174923776 03:53:40 INFO - PROCESS | 2919 | 03:53:50 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:54:09 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:54:24 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 03:54:28 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 03:54:31 INFO - PROCESS | 2919 | 1474455271450 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] 03:54:34 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:54:39 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.46673 seconds. 03:54:51 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:54:56 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 03:55:03 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 03:55:07 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:55:16 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:55:20 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:55:34 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 03:55:38 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00322 seconds. 03:55:40 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 03:55:58 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 03:56:02 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 03:56:06 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 03:56:08 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:56:22 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:56:23 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 03:56:25 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:56:36 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 03:56:44 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39051/tests/dromaeo/cssquery-ext.html) 03:56:45 INFO - PROCESS | 2919 | RSS: Main: 271826944 03:56:45 INFO - PROCESS | 2919 | 03:56:49 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:57:00 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:57:02 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:57:04 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:57:14 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:57:51 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:57:55 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 03:58:09 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:58:11 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:58:13 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:58:27 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:58:29 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:58:35 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:58:45 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:58:47 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:58:50 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:58:59 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:59:00 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 2.0001 seconds. 03:59:04 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:59:05 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:07 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:59:12 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 03:59:15 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:59:16 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 03:59:17 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 2.03342 seconds. 03:59:18 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:26 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:28 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:59:30 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:32 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:34 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:38 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:40 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:59:47 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 03:59:48 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-ext.html (next: http://localhost:39051/tests/dromaeo/cssquery-jquery.html) 03:59:49 INFO - PROCESS | 2919 | RSS: Main: 280743936 03:59:49 INFO - PROCESS | 2919 | 03:59:52 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 03:59:53 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:59:59 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:00:07 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:00:09 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:00:13 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:00:25 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:00:33 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:00:36 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:00:38 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:00:44 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:00:51 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:00:52 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:01:04 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:01:14 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:01:28 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:01:35 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 04:02:00 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:02:04 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:02:21 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:02:23 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:02:27 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:02:29 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:02:31 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:02:33 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:02:35 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:02:37 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:02:39 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:02:49 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 04:02:51 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39051/tests/dromaeo/cssquery-mootools.html) 04:02:51 INFO - PROCESS | 2919 | RSS: Main: 237174784 04:02:51 INFO - PROCESS | 2919 | 04:02:55 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:02:56 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:03:12 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:03:20 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:03:34 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:03:36 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:03:45 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:03:55 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:04:23 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 04:04:25 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:04:27 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:04:33 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:04:57 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:05:01 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:05:03 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:05:07 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:05:09 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:05:11 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:05:25 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:05:41 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:05:46 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:05:54 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:05:54 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39051/tests/dromaeo/cssquery-prototype.html) 04:05:54 INFO - PROCESS | 2919 | RSS: Main: 219983872 04:05:54 INFO - PROCESS | 2919 | 04:06:16 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:06:21 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:06:46 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:06:49 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:06:52 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:07:00 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:07:03 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:07:06 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:07:24 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:07:30 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 04:07:34 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:07:50 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 04:07:52 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 04:08:18 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:08:21 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:08:23 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:08:31 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:08:33 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:08:35 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:08:37 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:08:39 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:08:45 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:08:47 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:08:49 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:08:51 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:08:53 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:08:55 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:08:57 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:08:57 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39051/tests/dromaeo/cssquery-yui.html) 04:08:58 INFO - PROCESS | 2919 | RSS: Main: 222056448 04:08:58 INFO - PROCESS | 2919 | 04:09:01 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:09:19 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:09:39 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:09:43 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:09:45 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:09:49 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:10:02 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:10:05 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:10:21 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:10:28 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:10:32 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:10:38 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:11:04 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:11:08 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:11:10 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 04:11:14 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:11:16 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:11:24 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:11:32 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:11:59 INFO - PROCESS | 2919 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:12:01 INFO - PROCESS | 2919 | Cycle 1(1): loaded http://localhost:39051/tests/dromaeo/cssquery-yui.html (next: http://localhost:39051/tests/dromaeo/cssquery-dojo.html) 04:12:01 INFO - PROCESS | 2919 | RSS: Main: 290193408 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | __start_tp_report 04:12:01 INFO - PROCESS | 2919 | _x_x_mozilla_page_load 04:12:01 INFO - PROCESS | 2919 | _x_x_mozilla_page_load_details 04:12:01 INFO - PROCESS | 2919 | |i|pagename|runs| 04:12:01 INFO - PROCESS | 2919 | |0;dojo.html;2531.379811946073;2542.4787918476713;2590.456004239109;2594.675665541807;2606.2181345596327;4546.8408605698805;4577.0617023510185;4666.043461090477;4685.672002959795;4688.296755486675;4623.907521849561;4632.768361581924;4640.211164102102;4647.5352464753505;4661.044485880395;4256.361545768136;4290.099079193373;4321.719088259253;4335.219660461115;4344.978275108636;4298.720583162084;4325.697201195918;4337.219300525904;4341.522432532419;4374.300111982084;4291.291936830419;4354.695171338008;4392.12157568486;4421.403110580062;4424.247877860756;4211.220924129046;4253.085586598865;4343.609075183217;4370.759608221547;4395.978020109911;4236.258654735439;4253.638440732541;4283.828646854122;4285.164392943346;4286.592773686495;4600.194965880957;4613.76931153441;4672.0889426561835;4683.531646835323;4713.95286047142;5025.246213068032;5036.597072234249;5039.244113382985;5042.168042273021;5057.165567681328;4916.410030796292;4962.9007419851405;5025.798968041274;5086;5087.211482220262;4721.504242054603;4730.905381892412;4736.313234580966;4743.02767932573;4778.713277203309;5191.948080519146;5199.168133098686;5201.375834899761;5205.843824685266;5262.736863156827;4820.903581928342;4864.075825593126;4887.291342755351;4925.605951523869;4940.135476291706;5033.295338652591;5036.924446133311;5037.899242015139;5039.546440820343;5084.77118529667;4998.925016124761;5002.474740152305;5002.699838009731;5050.242463630411;5061.519155680205;4898.118338699069;4953;4955.925661115086;4991.051700176955;5013.623978201649;4254.659627229814;4283.464566929134;4322.459692538433;4323.459567554056;4327;3740.738148329591;3753.962460375416;3778.6599206071587;3805.6004119567046;3826.789526576065;4306.5262821089655;4347.804348804311;4348.804303806336;4390.495093064338;4401.713888597231;4881.29221262922;5017.874553136201;5020.046191223656;5023.070731914608;5037.395512538519;4278.957210427856;4298.097399546068;4319.676024298127;4333.956660433355;4360.978195109004;4527.049319642846;4530.886727831765;4532.79602417892;4535.070310586322;4592.977035114803;4709.529047095331;4718.693284936468;4719.197736384892;4721.150192965162;4778.784954677048;4932.4081110267;4983.327250821093;5000.274960130833;5024.346834911438;5038.974805125951;4741.57325840676;4743.430788305287;4745.098431298043;4753.691010084472;4789.353437286061;4958.305837182726;4958.950410495849;4961.975190124026;4963.181075122564;5025.321581586439;4917.237828136645;4970.8757281068265;4978.253262010728;5010.69935803853;5023.6985780853265;4908.8772780680765;4910.410750709938;4912.778924948315;4955.5044495550155;4976.054549635558;4896.290037944407;4909;4911.656184067081;4912.287718280901;4972.303877457087;4734.384530011076;4787.40157480315;4798.054682403163;4831.178699621002;4914.754262286943;4976.402831660224;4986.925196121989;5000.624953128603;5059.038782631435;5063.215201643604;4831.299461578122;4835.105505481357;4838.491958344323;4848.006158737521;4873.098476781806 04:12:01 INFO - PROCESS | 2919 | |1;ext.html;568.4088547910171;577.6505214345322;577.6707276852196;584.5411352088611;587.7178954102029;28553.857230713842;37754.62245377545;39229;39364.40953385702;42856.357154642705;40647.39028914569;41102;41160.794196029055;41174.38238426426;41223;7670.539767613933;7825.9217407826045;7826.491278066907;7827.099883513412;7830.6084695764985;7698.538087714755;7794.298513133816;7796.532208067506;7798.8440231195345;7799.8050048748955;7804.219578042207;7846.72536461224;7846.960765196166;7857.214278572125;7858.410619203554;7747.147813740484;7769.3784497240085;7769.922300776976;7785.649645765954;7793.298603125718;7654.19630938749;7687.231276872324;7712.537247765152;7760.573168475732;7764.456488045839;38325.80837095824;38652.420213696816;38675.61324386748;38709.22581548381;38784.80607596944;2824.308044529083;2875.870585823643;2884.423115376933;2926.0636596289196;2963.303623648441;27705.584416233774;28202.15393538197;28646.427071458664;28664.713352866413;28960.855195724096;3444.1045328214595;3452.3267962747273;3457.2357981593154;3564.1802385451233;3582.8566857325677;247538.7623061891;247613;248540;249903;249926;53660.73169634205;53706.7314663432;53745.46254537483;54044.45955540394;54216;32104.518432223536;32972.83513582417;33034;35128.47307290393;55650.72174639182;13363.799543006862;13453.865461345262;13472.595822125351;13534;13535.187888726708;10509.369437833755;10535.578576856993;10554.841677374845;10560.683179504627;10560.788784224425;17863.553411164823;17881.195346209453;18016.189271482814;18059.27762889496;18130.45608631743;6481.611103333815;6666.233383160901;6740.764073257413;6789.830254243585;6875.381215690511;747.9401647868158;823.642207440607;859.3297228162044;864.0840708848641;956.168133723651;25759.356016099373;27789.86105069502;28179.57592393694;28402.289942751595;28702.138935831957;769.5959621198915;926.1757036237753;956.3926906414338;988.7874107067015;999.2705325112709;13594.728105437836;14449.31542456688;14529.200893950723;14758.55724328272;14875.851241487662;346949;349871;349875;351659;351738;49291;49578.50421495811;49599.752001240486;49650;49859;1024.5594394410475;1072.4905669806778;1138.8405623212923;1156.1848896527927;1171.5255321594768;25157.87421062883;29238.269043273238;29422.558661619663;29710.85144574263;29812.850935745184;41773.164536708486;51184.23223651574;52880.73559632177;53067.734661326445;53090;336797;339378;340012;340777;341110;24353;25087.74912250854;25288.81579276207;38245;38660.80669596634;40784;41266.58733412628;41381.37927931143;41429;41647.1670566593;34907.30185396328;35171.472427914116;35293.64706352904;35353;35436.29127417489;35203.471947921316;35613;35676.64323356837;35838.46242306315;36103.27793444064;34247.31505369829;35008.67582172466;35333.646663533036;35483.29033419369;35604.64395356013;29817.850910746176;32152;32451.513227301137;32500.187495312806;32661;24130;24715.87642061778;24848.254552363458;24919.377015574755;25182.62226066647 04:12:01 INFO - PROCESS | 2919 | |2;jquery.html;347.3053892215569;356.25364860617015;366.7322854316348;369.4845690262084;392.7682667226336;12672.176308539938;13010.544630937908;14070.859291407083;16180.514584562452;16920.915395423006;16261.10563918984;16402.671946561062;16550.751738723928;16691.081990490547;16696;4685.789139488723;5032;5079.034983353151;5088.4911508849;5090.643654944155;3606;3804.029972357045;5109.054824857395;5193.350831146106;5194.324737784102;5667.4615911488545;5940.37626049263;5963.582549221556;6056.394360563931;6062.363451837559;5801.9129713054335;5942.910856337158;5976.701164941757;5982.760689572412;6048.637081775108;5261.921071183935;5277.23480095384;5285.365756109291;5290.761915713802;5290.92063619046;19456.805431945784;20101.39695809128;20264.594708105902;20287.087081081387;20330.695039574417;1729.44657709533;1867;2090.4251330883976;2217.9889100554556;2331.2423462374795;12465.813012804816;13241.404136813866;13609.795853062213;13667.248301343423;13729.107608005448;1695.2710334556136;1753.7194048952106;1754.4473490850469;1795.8742887997846;1807.2951548895942;65130.348696512905;67998.9800152998;68220;68592.657036715;69013.65493172553;9529.809403811885;9577.856332155023;9856.112949834405;10011.798584169946;10151.593936242467;13901.513447029507;13911.30443477822;13912.165270083828;13912.30438478072;13913.373898174606;7092.5744455331815;7182.748603798842;7266.091738532684;7313.853722925512;7378.631068446556;6750.3924646782025;6887.414569761526;7178.964105179545;7350.191478937312;7366.742164024341;3968.166684996183;4060.5127384713433;4181.749095054306;4257.106007738348;4379.167958087953;1722.3196837249216;1791.7312403139372;1798.856091512676;1829.6066345735464;1829.7987221405635;643.5045015338163;776.9494982826211;783.3705891745157;803.5901690138072;912.1061359867234;15905.602359941095;15959.122248276457;16820.74768878468;17533.386331478338;17555.29778808859;829.2163905109734;836.7364280251701;840.357126798;842.1872892658614;858.1932982995963;8945.418547794372;9388.577514011886;9775.560099795526;9816.509174541245;9957.900420995842;353867;360545;364249;365517;367233;72735.63632181911;79482;79882;80972.59513702511;82267;899.383922013436;914.2366124286295;930.0978051290238;930.4603330068679;933.1368484152132;15117.244137793286;15129.924350378178;15843.683126337639;16069.91965040214;16142;37518.24963500769;37851.81074094722;38532.42201367038;38961.220775584894;39839.60160398359;51679;52247.73876130595;52303.476965231384;52907.206391904925;52963;7348.595827229554;7620.80947976305;7644.961775191311;7678.731244406419;8018.596745569619;8217.05503867039;10326.72811981363;11768.705782355168;11932.642020739391;12107.33409662477;9654.951725241328;9891.455969921722;10042.799144016933;10194.694159175237;10242.692719218436;9758.414495130315;10178.898211017795;10233.948830256099;10314.329568578018;10499.790004200026;9753.707388778348;10060.144887684628;10123.898761012295;10486.790264194826;10524.736881577715;7566.772996810105;7673.501222420525;7680.577568233801;7680.884786728092;8018.3184429324165;7636.465447418627;8953.238974687225;9553.283503737386;9999.550020249251;10088 04:12:01 INFO - PROCESS | 2919 | |3;mootools.html;1352.7226918481708;1378.3590630356885;1460.2333774768244;1666.6833301672673;1696.4232161065236;6864.107666003425;7104.005439238511;7459.1049074111115;8149.511029338244;8158.8368232635385;7465.813354666123;7743.148253692089;7757.844843103135;7992.680292788296;7992.760217193479;2149.2477632828504;2175.923842665507;2176.880271585062;2177.8148857347064;2182.1053368119074;1815.2285278756542;1884.5665496935712;2107.1782005018053;2162.156758864044;2165.6859755335468;2100.380387785607;2200.7469141048823;2206.8455208135438;2213.435573928654;2226.142934970032;2090;2119.629064913634;2120.4698825293676;2152.332776839185;2166.350094971502;2141.0900367343816;2175.4887601413657;2185.158713895146;2187.8359123065834;2191.4630915425664;7589.506682065703;7595.050618672666;7595.620218989028;7616.923830761677;7810.921890781133;4557.931631025537;4679.555442232982;4679.60223381013;4710.105080034782;4723.4568024677255;2726.1003868723474;2905.418916216765;2907.8400687962153;2917.7519910807605;2921.9123426297033;920.8250432417819;922.1884741427568;934.1732566678538;942.9674506415464;945.8108378324363;10434.68695939123;10675.839862401992;10731.195160363004;10733.40966246856;10761.569537218502;4008.6392224699916;4118.5263694674895;4182.414461975326;4239.537359610929;4275.850345237902;9237.90762092384;9264.351495395258;9285.30360222986;9350.111739384747;9358.391704539183;4638.095571363552;4639.164950308977;4666.230072038144;4723.905521889543;4757.785899634525;4222.831086756495;4231.555686652857;4283.378910058024;4289.978550107229;4290.978545107317;3372.3086767212662;3419.247765491588;3494.9126271843406;3512.613612502623;3513.9297214056087;1050.0811789684026;1104.1608377633056;1110.3726394587034;1152.7982603044434;1156.5894107591853;621.623917529897;734.9595772232472;762.367235194787;765.946383753132;779.3064172886136;4900.411950565955;4951.504849515091;4964.131277026506;4986.750662466935;5066.341375621219;783.2598194706062;823.6869989404108;830.3066939105794;840.1724301562956;868.1702368594915;3285.408626447215;3296.522004309362;3320.335932813399;3328.334333133383;3380.645032271576;9399.483028433502;9726.465044422483;9845.901540984642;9849.064338887794;9860.408375497493;9598.568064437117;9788.55951482185;9809.754756131153;9847.507624618884;9863.112319891245;7366.410687145016;7425.257474252639;7438.256174382626;7441.8139546511775;7450.254974502506;4882.023595281029;4885.5358740919555;4911.926321105258;4978.726170060681;5124.308218390619;8777.874399032444;8969.416987896027;9034.593443295198;9040.186383225542;9202.953985230031;8997.730068097968;9211.355205135844;9293.907060929305;9410.623575056921;9432.575534101119;6775.728970841111;6816.250212476524;6849;6898.8620227594165;6921.8961715575315;7391.150017747813;7446.292602202782;7449.217832127549;7456.030716007102;7460.813479663052;6471.935280647133;6521.706523206372;6539.869202615826;6595.604263744191;6705.765298214734;6553.541252112497;6591.967040164768;6648.268690443951;6668.066470694203;6832.453403727591;5780.913286300793;5829.446202610745;5830.358660547421;5831.1544826000845;5834.27071616048;4108.2810508160355;4120.670346372223;4284.186004659229;4297.462817147856;4351.477822661301;4381.824727010884;4429.490608580055;4434.711743736647;4549.522300158435;4557.931631025471 04:12:01 INFO - PROCESS | 2919 | |4;prototype.html;283.6751919052685;289.6234894636971;297.23313850266317;297.55069844534745;301.6787121715374;4696.647751418649;4893.070316639836;4915.164422048251;4922.114019476498;4947.28264401662;4690.296455531672;4778.689385189969;4809.855704328876;4818.301346304793;4836.2987366831785;3579.6957258632915;3724.5530536335677;3822.847086116566;3828.827702753369;3839.577646458887;3748.418995055757;3837.7910958048265;3838;3858.7684738915755;3885.261800257942;3821.40768180931;3821.5796262411113;3824.368979118442;3830.3296923038497;3831.9845241011153;3820.5797362290264;3844.9615503844884;3847.383779030258;3864.14988702485;3868.32304346741;3118.892793058454;3287.1124796305103;3287.967120328814;3293.6870997255223;3426.1777173478436;4731.4795372509;4743.810247590092;4744.810207591692;4750.64370172235;4754.453237877654;4143.316352801784;4295.398644189816;4304.741715497049;4317.179735850178;4325.8485952991805;5071.746412679351;5081.551757749061;5085.872853178663;5087.186050231945;5097.235414687826;3555.9466608000903;3586.677199052072;3586.8385922633543;3592.2276710507103;3596.604373518911;5287.154055351125;5308.840734777963;5312.389075256395;5321.962217367615;5323.760430780624;3417.043227896193;3561.1631266652835;3584.4586827664357;3605.675489205984;3614.7288953328607;3644.562652481719;3648.4527320902075;3650.1604630935035;3651.8174091295327;3655.177585043344;4217.915641687149;4221.219074471172;4222.852200173041;4225.260579398593;4227.788610569459;4912.214045752662;5070.366204224472;5103.8468845934685;5133.024725302181;5138.768755406016;1332.3538084029237;1352.5933029649054;1352.9729405411836;1356.3353956561411;1359.8912087033013;804.5534728225891;834.6937043526862;836.4897412578322;839.8908141941508;857.7869097727847;3982.4623675803973;3995.0623947385493;4017.4174744662446;4027.617376349242;4055.2497787909333;4613.223908795138;4613.739267758774;4615;4629.1577990268;4645.605123564467;4021;4034.1039711335625;4059.330210515291;4062.988820533855;4064.5935406459116;2485.7141428735567;2592.8980183422345;2758.2966343582257;2785.3175971887017;2799.4820958122814;4913.656044076952;4932.90134197314;4934.383202099738;4935.951580094221;4954.5788607968725;5346.07541285137;5353.81261655848;5359.169328754049;5361.973190134025;5368.248445217672;4694.460137084211;4962.064794730059;5016.448190699093;5021.49785021495;5023.598112151019;5284.973575132253;5302.310699609026;5308.389535203347;5315.574754019746;5326.7336633167415;5021.698698078127;5176.482351764794;5224.869378265574;5299.708516031501;5306.52241298285;5003.224500202475;5008.4974507648285;5020.648554601143;5022.773975171053;5028.547430731252;3436.9656303436645;3544.6278140794843;3560.359135355661;3659.8353074111196;3664.7356661951585;5254.13306804373;5258.6581872178185;5267.552258058108;5267.683938963674;5274.498922602346;5083.923741143812;5085.338905942352;5091.287219789158;5112.207607820794;5122.820701275437;4949.851504454872;5042.773075211551;5064.670796398223;5070.264811602371;5075.213341932006;5001.749912504433;5003.224500202475;5007.549320561167;5009.423916249678;5010.69935803853;4989.451160372429;5122;5150.742462876916;5154.484551544965;5156.819511317236;5002.2496625506465;5023.874403139951;5024.321716568216;5025.145725226647;5026.4973502649445 04:12:01 INFO - PROCESS | 2919 | |5;yui.html;559.5020431815685;566.4958187213379;588.3028611095849;614.8401415631937;627.9026750853619;12006.639800805979;12688;13115.737685246302;13131.868681313184;13200.73598528029;12389.380530973438;12639.304838233893;12647.430865611046;12680.4927802888;12692;574.0900672434196;745.4185735126583;761.9276168763959;762.881753328235;764.1136281912982;623.7910300049489;749.7338444852081;770.6532060572737;773.6789232468519;778.4200770426012;809.2676128104046;811.6144831205189;813.7233340664174;815.4658698552457;815.7144999250245;618.2673531864755;805.315481840431;806.749907528668;807.0436532708759;807.1968391450486;471.09314569453664;570.3982298674896;585.8857522783059;586.0974099886171;588.4556784973882;12288.078394120388;12327.321997290146;12357.505699771998;12365.505379784798;12366.258024518558;11435.256708314017;11927;12081.879181208164;12163.452644631012;12209.877901220963;10718.946405267923;10781.838272425919;10948.562057517689;10961;10980.835287470694;3604.2250916052913;3629.1290090378384;3634.6365363463706;3664.1022949377307;3671.8714844980564;52148.21777673338;64916;64977.675111624136;65198.3480165197;65897;9284.303677224236;9747.463889486145;9887.604495820231;10186.286959912735;10188.388696678076;17636.1181940905;17668.469945901645;17687.911560442117;17688.380906668204;17707.38024169148;6574.474042076622;6640.369164929324;6661.666916654148;6740.966295168493;7019.649017549102;3593.8984298454156;3603.873864414733;3619.9276014479565;3629.129009037812;3632.582253040934;2877.7697784177635;2971.985140074329;2987.2979849735293;2992.521196608532;2995.0715278263806;482.642844295219;483.23889873449457;484.78911673422175;493.0681012885649;496.2655270200124;466.9229577119737;491.3170692737098;510.6348960493265;512.5156726893125;520.7084032941565;7086.39765619928;7290.635468226567;7314.817129571697;7466.626668666545;7519.366706214334;468.2227502346157;494.8540180646718;530.6444682063027;530.9946900531022;534.3934634190222;3505.94741078884;3521.0845180252804;3557.839897204632;3630.1650620357464;3674.982308023493;99279;100899;102157.48921255347;106501;106533.46733266284;81201.59399203084;85962.57018714992;86824.56587717147;87010.56494717486;91813.08186918178;74259;74669.05295152857;76142.61928690321;76816.23183768202;77397;6555.246146693191;6814;6899.586024838325;6981.0924579804305;6995.020697102512;47006.294905577124;48561.271580925604;48923.51076489332;48960.510394895595;50907.745461272454;59297.407025930916;65757.67121164555;65895.34709380702;66230.66884665546;69212.30787692059;8768.561571921251;8853.822923541622;8856.11438856135;9083.273338132802;9335.486548239913;10122;10168.084872361524;10276.640317588848;10344.586216551254;10556.419396933243;8900.910990890008;9125.406848555089;9219.262459003397;9269.814603707753;9487.241020718464;9436.764080898032;9470.479123648265;9637.71086867395;9897.50512474359;9901.950490247502;9499.525023748924;9721.076497732704;9780.951095244478;9786.755331116494;9792.755181120529;7811.960940195263;8047.315978141924;8296;8359.58202089881;8392.874106888523;7863.882041769492;8156.574238819591;8415.621297041771;8620.91379086201;8689.17452841979 04:12:01 INFO - PROCESS | 2919 | __end_tp_report 04:12:01 INFO - PROCESS | 2919 | __start_cc_report 04:12:01 INFO - PROCESS | 2919 | _x_x_mozilla_cycle_collect,856 04:12:01 INFO - PROCESS | 2919 | __end_cc_report 04:12:01 INFO - PROCESS | 2919 | __startTimestamp1474456321799__endTimestamp 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | ------- Summary: start ------- 04:12:01 INFO - PROCESS | 2919 | Number of tests: 6 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#0] dojo.html Cycles:165 Average:4645.51 Median:4753.69 stddev:489.86 (10.3%) stddev-sans-first:462.43 04:12:01 INFO - PROCESS | 2919 | Values: 2531.4 2542.5 2590.5 2594.7 2606.2 4546.8 4577.1 4666.0 4685.7 4688.3 4623.9 4632.8 4640.2 4647.5 4661.0 4256.4 4290.1 4321.7 4335.2 4345.0 4298.7 4325.7 4337.2 4341.5 4374.3 4291.3 4354.7 4392.1 4421.4 4424.2 4211.2 4253.1 4343.6 4370.8 4396.0 4236.3 4253.6 4283.8 4285.2 4286.6 4600.2 4613.8 4672.1 4683.5 4714.0 5025.2 5036.6 5039.2 5042.2 5057.2 4916.4 4962.9 5025.8 5086.0 5087.2 4721.5 4730.9 4736.3 4743.0 4778.7 5191.9 5199.2 5201.4 5205.8 5262.7 4820.9 4864.1 4887.3 4925.6 4940.1 5033.3 5036.9 5037.9 5039.5 5084.8 4998.9 5002.5 5002.7 5050.2 5061.5 4898.1 4953.0 4955.9 4991.1 5013.6 4254.7 4283.5 4322.5 4323.5 4327.0 3740.7 3754.0 3778.7 3805.6 3826.8 4306.5 4347.8 4348.8 4390.5 4401.7 4881.3 5017.9 5020.0 5023.1 5037.4 4279.0 4298.1 4319.7 4334.0 4361.0 4527.0 4530.9 4532.8 4535.1 4593.0 4709.5 4718.7 4719.2 4721.2 4778.8 4932.4 4983.3 5000.3 5024.3 5039.0 4741.6 4743.4 4745.1 4753.7 4789.4 4958.3 4959.0 4962.0 4963.2 5025.3 4917.2 4970.9 4978.3 5010.7 5023.7 4908.9 4910.4 4912.8 4955.5 4976.1 4896.3 4909.0 4911.7 4912.3 4972.3 4734.4 4787.4 4798.1 4831.2 4914.8 4976.4 4986.9 5000.6 5059.0 5063.2 4831.3 4835.1 4838.5 4848.0 4873.1 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#1] ext.html Cycles:180 Average:46621.86 Median:35543.97 stddev:83089.41 (233.8%) stddev-sans-first:83250.55 04:12:01 INFO - PROCESS | 2919 | Values: 568.4 577.7 577.7 584.5 587.7 28553.9 37754.6 39229.0 39364.4 42856.4 40647.4 41102.0 41160.8 41174.4 41223.0 7670.5 7825.9 7826.5 7827.1 7830.6 7698.5 7794.3 7796.5 7798.8 7799.8 7804.2 7846.7 7847.0 7857.2 7858.4 7747.1 7769.4 7769.9 7785.6 7793.3 7654.2 7687.2 7712.5 7760.6 7764.5 38325.8 38652.4 38675.6 38709.2 38784.8 2824.3 2875.9 2884.4 2926.1 2963.3 27705.6 28202.2 28646.4 28664.7 28960.9 3444.1 3452.3 3457.2 3564.2 3582.9 247538.8 247613.0 248540.0 249903.0 249926.0 53660.7 53706.7 53745.5 54044.5 54216.0 32104.5 32972.8 33034.0 35128.5 55650.7 13363.8 13453.9 13472.6 13534.0 13535.2 10509.4 10535.6 10554.8 10560.7 10560.8 17863.6 17881.2 18016.2 18059.3 18130.5 6481.6 6666.2 6740.8 6789.8 6875.4 747.9 823.6 859.3 864.1 956.2 25759.4 27789.9 28179.6 28402.3 28702.1 769.6 926.2 956.4 988.8 999.3 13594.7 14449.3 14529.2 14758.6 14875.9 346949.0 349871.0 349875.0 351659.0 351738.0 49291.0 49578.5 49599.8 49650.0 49859.0 1024.6 1072.5 1138.8 1156.2 1171.5 25157.9 29238.3 29422.6 29710.9 29812.9 41773.2 51184.2 52880.7 53067.7 53090.0 336797.0 339378.0 340012.0 340777.0 341110.0 24353.0 25087.7 25288.8 38245.0 38660.8 40784.0 41266.6 41381.4 41429.0 41647.2 34907.3 35171.5 35293.6 35353.0 35436.3 35203.5 35613.0 35676.6 35838.5 36103.3 34247.3 35008.7 35333.6 35483.3 35604.6 29817.9 32152.0 32451.5 32500.2 32661.0 24130.0 24715.9 24848.3 24919.4 25182.6 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#2] jquery.html Cycles:180 Average:23612.01 Median:4858.89 stddev:60070.86 (1236.3%) stddev-sans-first:60213.98 04:12:01 INFO - PROCESS | 2919 | Values: 347.3 356.3 366.7 369.5 392.8 12672.2 13010.5 14070.9 16180.5 16920.9 16261.1 16402.7 16550.8 16691.1 16696.0 4685.8 5032.0 5079.0 5088.5 5090.6 3606.0 3804.0 5109.1 5193.4 5194.3 5667.5 5940.4 5963.6 6056.4 6062.4 5801.9 5942.9 5976.7 5982.8 6048.6 5261.9 5277.2 5285.4 5290.8 5290.9 19456.8 20101.4 20264.6 20287.1 20330.7 1729.4 1867.0 2090.4 2218.0 2331.2 12465.8 13241.4 13609.8 13667.2 13729.1 1695.3 1753.7 1754.4 1795.9 1807.3 65130.3 67999.0 68220.0 68592.7 69013.7 9529.8 9577.9 9856.1 10011.8 10151.6 13901.5 13911.3 13912.2 13912.3 13913.4 7092.6 7182.7 7266.1 7313.9 7378.6 6750.4 6887.4 7179.0 7350.2 7366.7 3968.2 4060.5 4181.7 4257.1 4379.2 1722.3 1791.7 1798.9 1829.6 1829.8 643.5 776.9 783.4 803.6 912.1 15905.6 15959.1 16820.7 17533.4 17555.3 829.2 836.7 840.4 842.2 858.2 8945.4 9388.6 9775.6 9816.5 9957.9 353867.0 360545.0 364249.0 365517.0 367233.0 72735.6 79482.0 79882.0 80972.6 82267.0 899.4 914.2 930.1 930.5 933.1 15117.2 15129.9 15843.7 16069.9 16142.0 37518.2 37851.8 38532.4 38961.2 39839.6 51679.0 52247.7 52303.5 52907.2 52963.0 7348.6 7620.8 7645.0 7678.7 8018.6 8217.1 10326.7 11768.7 11932.6 12107.3 9655.0 9891.5 10042.8 10194.7 10242.7 9758.4 10178.9 10233.9 10314.3 10499.8 9753.7 10060.1 10123.9 10486.8 10524.7 7566.8 7673.5 7680.6 7680.9 8018.3 7636.5 8953.2 9553.3 9999.6 10088.0 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#3] mootools.html Cycles:180 Average:5094.33 Median:4971.43 stddev:2919.60 (58.7%) stddev-sans-first:2914.25 04:12:01 INFO - PROCESS | 2919 | Values: 1352.7 1378.4 1460.2 1666.7 1696.4 6864.1 7104.0 7459.1 8149.5 8158.8 7465.8 7743.1 7757.8 7992.7 7992.8 2149.2 2175.9 2176.9 2177.8 2182.1 1815.2 1884.6 2107.2 2162.2 2165.7 2100.4 2200.7 2206.8 2213.4 2226.1 2090.0 2119.6 2120.5 2152.3 2166.4 2141.1 2175.5 2185.2 2187.8 2191.5 7589.5 7595.1 7595.6 7616.9 7810.9 4557.9 4679.6 4679.6 4710.1 4723.5 2726.1 2905.4 2907.8 2917.8 2921.9 920.8 922.2 934.2 943.0 945.8 10434.7 10675.8 10731.2 10733.4 10761.6 4008.6 4118.5 4182.4 4239.5 4275.9 9237.9 9264.4 9285.3 9350.1 9358.4 4638.1 4639.2 4666.2 4723.9 4757.8 4222.8 4231.6 4283.4 4290.0 4291.0 3372.3 3419.2 3494.9 3512.6 3513.9 1050.1 1104.2 1110.4 1152.8 1156.6 621.6 735.0 762.4 765.9 779.3 4900.4 4951.5 4964.1 4986.8 5066.3 783.3 823.7 830.3 840.2 868.2 3285.4 3296.5 3320.3 3328.3 3380.6 9399.5 9726.5 9845.9 9849.1 9860.4 9598.6 9788.6 9809.8 9847.5 9863.1 7366.4 7425.3 7438.3 7441.8 7450.3 4882.0 4885.5 4911.9 4978.7 5124.3 8777.9 8969.4 9034.6 9040.2 9203.0 8997.7 9211.4 9293.9 9410.6 9432.6 6775.7 6816.3 6849.0 6898.9 6921.9 7391.2 7446.3 7449.2 7456.0 7460.8 6471.9 6521.7 6539.9 6595.6 6705.8 6553.5 6592.0 6648.3 6668.1 6832.5 5780.9 5829.4 5830.4 5831.2 5834.3 4108.3 4120.7 4284.2 4297.5 4351.5 4381.8 4429.5 4434.7 4549.5 4557.9 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#4] prototype.html Cycles:180 Average:4179.20 Median:4747.73 stddev:1231.28 (25.9%) stddev-sans-first:1199.51 04:12:01 INFO - PROCESS | 2919 | Values: 283.7 289.6 297.2 297.6 301.7 4696.6 4893.1 4915.2 4922.1 4947.3 4690.3 4778.7 4809.9 4818.3 4836.3 3579.7 3724.6 3822.8 3828.8 3839.6 3748.4 3837.8 3838.0 3858.8 3885.3 3821.4 3821.6 3824.4 3830.3 3832.0 3820.6 3845.0 3847.4 3864.1 3868.3 3118.9 3287.1 3288.0 3293.7 3426.2 4731.5 4743.8 4744.8 4750.6 4754.5 4143.3 4295.4 4304.7 4317.2 4325.8 5071.7 5081.6 5085.9 5087.2 5097.2 3555.9 3586.7 3586.8 3592.2 3596.6 5287.2 5308.8 5312.4 5322.0 5323.8 3417.0 3561.2 3584.5 3605.7 3614.7 3644.6 3648.5 3650.2 3651.8 3655.2 4217.9 4221.2 4222.9 4225.3 4227.8 4912.2 5070.4 5103.8 5133.0 5138.8 1332.4 1352.6 1353.0 1356.3 1359.9 804.6 834.7 836.5 839.9 857.8 3982.5 3995.1 4017.4 4027.6 4055.2 4613.2 4613.7 4615.0 4629.2 4645.6 4021.0 4034.1 4059.3 4063.0 4064.6 2485.7 2592.9 2758.3 2785.3 2799.5 4913.7 4932.9 4934.4 4936.0 4954.6 5346.1 5353.8 5359.2 5362.0 5368.2 4694.5 4962.1 5016.4 5021.5 5023.6 5285.0 5302.3 5308.4 5315.6 5326.7 5021.7 5176.5 5224.9 5299.7 5306.5 5003.2 5008.5 5020.6 5022.8 5028.5 3437.0 3544.6 3560.4 3659.8 3664.7 5254.1 5258.7 5267.6 5267.7 5274.5 5083.9 5085.3 5091.3 5112.2 5122.8 4949.9 5042.8 5064.7 5070.3 5075.2 5001.7 5003.2 5007.5 5009.4 5010.7 4989.5 5122.0 5150.7 5154.5 5156.8 5002.2 5023.9 5024.3 5025.1 5026.5 04:12:01 INFO - PROCESS | 2919 | 04:12:01 INFO - PROCESS | 2919 | [#5] yui.html Cycles:180 Average:17645.13 Median:29942.93 stddev:26551.01 (88.7%) stddev-sans-first:26594.50 04:12:01 INFO - PROCESS | 2919 | Values: 559.5 566.5 588.3 614.8 627.9 12006.6 12688.0 13115.7 13131.9 13200.7 12389.4 12639.3 12647.4 12680.5 12692.0 574.1 745.4 761.9 762.9 764.1 623.8 749.7 770.7 773.7 778.4 809.3 811.6 813.7 815.5 815.7 618.3 805.3 806.7 807.0 807.2 471.1 570.4 585.9 586.1 588.5 12288.1 12327.3 12357.5 12365.5 12366.3 11435.3 11927.0 12081.9 12163.5 12209.9 10718.9 10781.8 10948.6 10961.0 10980.8 3604.2 3629.1 3634.6 3664.1 3671.9 52148.2 64916.0 64977.7 65198.3 65897.0 9284.3 9747.5 9887.6 10186.3 10188.4 17636.1 17668.5 17687.9 17688.4 17707.4 6574.5 6640.4 6661.7 6741.0 7019.6 3593.9 3603.9 3619.9 3629.1 3632.6 2877.8 2972.0 2987.3 2992.5 2995.1 482.6 483.2 484.8 493.1 496.3 466.9 491.3 510.6 512.5 520.7 7086.4 7290.6 7314.8 7466.6 7519.4 468.2 494.9 530.6 531.0 534.4 3505.9 3521.1 3557.8 3630.2 3675.0 99279.0 100899.0 102157.5 106501.0 106533.5 81201.6 85962.6 86824.6 87010.6 91813.1 74259.0 74669.1 76142.6 76816.2 77397.0 6555.2 6814.0 6899.6 6981.1 6995.0 47006.3 48561.3 48923.5 48960.5 50907.7 59297.4 65757.7 65895.3 66230.7 69212.3 8768.6 8853.8 8856.1 9083.3 9335.5 10122.0 10168.1 10276.6 10344.6 10556.4 8900.9 9125.4 9219.3 9269.8 9487.2 9436.8 9470.5 9637.7 9897.5 9902.0 9499.5 9721.1 9781.0 9786.8 9792.8 7812.0 8047.3 8296.0 8359.6 8392.9 7863.9 8156.6 8415.6 8620.9 8689.2 04:12:01 INFO - PROCESS | 2919 | -------- Summary: end -------- 04:12:01 INFO - PROCESS | 2919 | 04:12:02 INFO - TEST-INFO | 2919: exit 0 04:12:02 INFO - TEST-OK | dromaeo_css | took 1120967ms 04:12:02 INFO - TEST-START | kraken 04:12:02 INFO - operating with platform_type : linux_ 04:12:02 INFO - Initialising browser for kraken test... 04:12:02 INFO - TEST-INFO | started process 3230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvfpWij/profile http://localhost:39051/getInfo.html) 04:12:07 INFO - PROCESS | 3230 | __metrics Screen width/height:1600/1200 04:12:07 INFO - PROCESS | 3230 | colorDepth:24 04:12:07 INFO - PROCESS | 3230 | Browser inner width/height: 1024/697 04:12:07 INFO - PROCESS | 3230 | __metrics 04:12:07 INFO - PROCESS | 3230 | 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" 04:12:07 INFO - PROCESS | 3230 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:09 INFO - TEST-INFO | 3230: exit 0 04:12:09 INFO - Browser initialized. 04:12:09 INFO - Running cycle 1/1 for kraken test... 04:12:09 INFO - TEST-INFO | started process 3312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvfpWij/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:21 INFO - PROCESS | 3312 | RSS: Main: 175132672 04:12:21 INFO - PROCESS | 3312 | 04:12:24 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39051/tests/kraken/driver.html?testName=audio-beat-detection) 04:12:25 INFO - PROCESS | 3312 | RSS: Main: 234360832 04:12:25 INFO - PROCESS | 3312 | 04:12:28 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39051/tests/kraken/driver.html?testName=audio-dft) 04:12:28 INFO - PROCESS | 3312 | RSS: Main: 218894336 04:12:28 INFO - PROCESS | 3312 | 04:12:31 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39051/tests/kraken/driver.html?testName=audio-fft) 04:12:32 INFO - PROCESS | 3312 | RSS: Main: 300572672 04:12:32 INFO - PROCESS | 3312 | 04:12:34 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39051/tests/kraken/driver.html?testName=audio-oscillator) 04:12:35 INFO - PROCESS | 3312 | RSS: Main: 210362368 04:12:35 INFO - PROCESS | 3312 | 04:12:37 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39051/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:38 INFO - PROCESS | 3312 | RSS: Main: 213921792 04:12:38 INFO - PROCESS | 3312 | 04:12:41 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39051/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:42 INFO - PROCESS | 3312 | RSS: Main: 250269696 04:12:42 INFO - PROCESS | 3312 | 04:12:45 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39051/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:46 INFO - PROCESS | 3312 | RSS: Main: 249647104 04:12:46 INFO - PROCESS | 3312 | 04:12:49 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39051/tests/kraken/driver.html?testName=json-parse-financial) 04:12:49 INFO - PROCESS | 3312 | RSS: Main: 248926208 04:12:49 INFO - PROCESS | 3312 | 04:12:51 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39051/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:51 INFO - PROCESS | 3312 | RSS: Main: 211509248 04:12:51 INFO - PROCESS | 3312 | 04:12:53 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:54 INFO - PROCESS | 3312 | RSS: Main: 218427392 04:12:54 INFO - PROCESS | 3312 | 04:12:56 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:57 INFO - PROCESS | 3312 | RSS: Main: 222908416 04:12:57 INFO - PROCESS | 3312 | 04:13:00 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:13:00 INFO - PROCESS | 3312 | RSS: Main: 224407552 04:13:00 INFO - PROCESS | 3312 | 04:13:03 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:13:04 INFO - PROCESS | 3312 | RSS: Main: 222781440 04:13:04 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | Cycle 1(1): loaded http://localhost:39051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39051/tests/kraken/driver.html?testName=ai-astar) 04:13:06 INFO - PROCESS | 3312 | RSS: Main: 273915904 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | __start_tp_report 04:13:06 INFO - PROCESS | 3312 | _x_x_mozilla_page_load 04:13:06 INFO - PROCESS | 3312 | _x_x_mozilla_page_load_details 04:13:06 INFO - PROCESS | 3312 | |i|pagename|runs| 04:13:06 INFO - PROCESS | 3312 | |0;ai-astar;113;114;115;115;113;115;116;114;113;114 04:13:06 INFO - PROCESS | 3312 | |1;audio-beat-detection;160;127;123;176;126;124;123;123;177;124 04:13:06 INFO - PROCESS | 3312 | |2;audio-dft;161;161;163;162;160;161;161;161;163;161 04:13:06 INFO - PROCESS | 3312 | |3;audio-fft;76;76;77;116;77;77;77;76;120;78 04:13:06 INFO - PROCESS | 3312 | |4;audio-oscillator;84;83;112;85;85;84;106;85;84;113 04:13:06 INFO - PROCESS | 3312 | |5;imaging-gaussian-blur;119;118;117;116;116;117;117;116;118;117 04:13:06 INFO - PROCESS | 3312 | |6;imaging-darkroom;147;147;145;146;146;147;146;147;146;146 04:13:06 INFO - PROCESS | 3312 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 04:13:06 INFO - PROCESS | 3312 | |8;json-parse-financial;64;63;63;65;65;65;64;65;64;65 04:13:06 INFO - PROCESS | 3312 | |9;json-stringify-tinderbox;67;66;67;67;68;67;67;66;67;66 04:13:06 INFO - PROCESS | 3312 | |10;stanford-crypto-aes;83;82;83;83;82;82;84;82;82;82 04:13:06 INFO - PROCESS | 3312 | |11;stanford-crypto-ccm;134;143;135;132;133;136;132;136;173;132 04:13:06 INFO - PROCESS | 3312 | |12;stanford-crypto-pbkdf2;174;174;174;177;173;171;171;173;172;172 04:13:06 INFO - PROCESS | 3312 | |13;stanford-crypto-sha256-iterative;70;71;69;71;69;72;69;69;67;68 04:13:06 INFO - PROCESS | 3312 | __end_tp_report 04:13:06 INFO - PROCESS | 3312 | __start_cc_report 04:13:06 INFO - PROCESS | 3312 | _x_x_mozilla_cycle_collect,1947 04:13:06 INFO - PROCESS | 3312 | __end_cc_report 04:13:06 INFO - PROCESS | 3312 | __startTimestamp1474456386353__endTimestamp 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | ------- Summary: start ------- 04:13:06 INFO - PROCESS | 3312 | Number of tests: 14 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:1.03 (0.9%) stddev-sans-first:1.00 04:13:06 INFO - PROCESS | 3312 | Values: 113.0 114.0 115.0 115.0 113.0 115.0 116.0 114.0 113.0 114.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:126.50 stddev:23.05 (18.2%) stddev-sans-first:23.07 04:13:06 INFO - PROCESS | 3312 | Values: 160.0 127.0 123.0 176.0 126.0 124.0 123.0 123.0 177.0 124.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:13:06 INFO - PROCESS | 3312 | Values: 161.0 161.0 163.0 162.0 160.0 161.0 161.0 161.0 163.0 161.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#3] audio-fft Cycles:10 Average:85.00 Median:77.00 stddev:17.43 (22.6%) stddev-sans-first:18.18 04:13:06 INFO - PROCESS | 3312 | Values: 76.0 76.0 77.0 116.0 77.0 77.0 77.0 76.0 120.0 78.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:84.00 stddev:12.72 (15.1%) stddev-sans-first:13.15 04:13:06 INFO - PROCESS | 3312 | Values: 84.0 83.0 112.0 85.0 85.0 84.0 106.0 85.0 84.0 113.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.99 (0.8%) stddev-sans-first:0.78 04:13:06 INFO - PROCESS | 3312 | Values: 119.0 118.0 117.0 116.0 116.0 117.0 117.0 116.0 118.0 117.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 04:13:06 INFO - PROCESS | 3312 | Values: 147.0 147.0 145.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:13:06 INFO - PROCESS | 3312 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 04:13:06 INFO - PROCESS | 3312 | Values: 64.0 63.0 63.0 65.0 65.0 65.0 64.0 65.0 64.0 65.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 04:13:06 INFO - PROCESS | 3312 | Values: 67.0 66.0 67.0 67.0 68.0 67.0 67.0 66.0 67.0 66.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.50 stddev:0.71 (0.9%) stddev-sans-first:0.73 04:13:06 INFO - PROCESS | 3312 | Values: 83.0 82.0 83.0 83.0 82.0 82.0 84.0 82.0 82.0 82.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:135.50 stddev:12.53 (9.2%) stddev-sans-first:13.18 04:13:06 INFO - PROCESS | 3312 | Values: 134.0 143.0 135.0 132.0 133.0 136.0 132.0 136.0 173.0 132.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.50 stddev:1.79 (1.0%) stddev-sans-first:1.87 04:13:06 INFO - PROCESS | 3312 | Values: 174.0 174.0 174.0 177.0 173.0 171.0 171.0 173.0 172.0 172.0 04:13:06 INFO - PROCESS | 3312 | 04:13:06 INFO - PROCESS | 3312 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:1.51 (2.2%) stddev-sans-first:1.59 04:13:06 INFO - PROCESS | 3312 | Values: 70.0 71.0 69.0 71.0 69.0 72.0 69.0 69.0 67.0 68.0 04:13:06 INFO - PROCESS | 3312 | -------- Summary: end -------- 04:13:06 INFO - PROCESS | 3312 | 04:13:07 INFO - TEST-INFO | 3312: exit 0 04:13:07 INFO - TEST-OK | kraken | took 64854ms 04:13:07 INFO - SUITE-END | took 1185s 04:13:08 INFO - Completed test suite (00:19:46) 04:13:08 INFO - javascript benchmark 04:13:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2531.379811946073, 2542.4787918476713, 2590.456004239109, 2594.675665541807, 2606.2181345596327, 4546.8408605698805, 4577.0617023510185, 4666.043461090477, 4685.672002959795, 4688.296755486675, 4623.907521849561, 4632.768361581924, 4640.211164102102, 4647.5352464753505, 4661.044485880395, 4256.361545768136, 4290.099079193373, 4321.719088259253, 4335.219660461115, 4344.978275108636, 4298.720583162084, 4325.697201195918, 4337.219300525904, 4341.522432532419, 4374.300111982084, 4291.291936830419, 4354.695171338008, 4392.12157568486, 4421.403110580062, 4424.247877860756, 4211.220924129046, 4253.085586598865, 4343.609075183217, 4370.759608221547, 4395.978020109911, 4236.258654735439, 4253.638440732541, 4283.828646854122, 4285.164392943346, 4286.592773686495, 4600.194965880957, 4613.76931153441, 4672.0889426561835, 4683.531646835323, 4713.95286047142, 5025.246213068032, 5036.597072234249, 5039.244113382985, 5042.168042273021, 5057.165567681328, 4916.410030796292, 4962.9007419851405, 5025.798968041274, 5086.0, 5087.211482220262, 4721.504242054603, 4730.905381892412, 4736.313234580966, 4743.02767932573, 4778.713277203309, 5191.948080519146, 5199.168133098686, 5201.375834899761, 5205.843824685266, 5262.736863156827, 4820.903581928342, 4864.075825593126, 4887.291342755351, 4925.605951523869, 4940.135476291706, 5033.295338652591, 5036.924446133311, 5037.899242015139, 5039.546440820343, 5084.77118529667, 4998.925016124761, 5002.474740152305, 5002.699838009731, 5050.242463630411, 5061.519155680205, 4898.118338699069, 4953.0, 4955.925661115086, 4991.051700176955, 5013.623978201649, 4254.659627229814, 4283.464566929134, 4322.459692538433, 4323.459567554056, 4327.0, 3740.738148329591, 3753.962460375416, 3778.6599206071587, 3805.6004119567046, 3826.789526576065, 4306.5262821089655, 4347.804348804311, 4348.804303806336, 4390.495093064338, 4401.713888597231, 4881.29221262922, 5017.874553136201, 5020.046191223656, 5023.070731914608, 5037.395512538519, 4278.957210427856, 4298.097399546068, 4319.676024298127, 4333.956660433355, 4360.978195109004, 4527.049319642846, 4530.886727831765, 4532.79602417892, 4535.070310586322, 4592.977035114803, 4709.529047095331, 4718.693284936468, 4719.197736384892, 4721.150192965162, 4778.784954677048, 4932.4081110267, 4983.327250821093, 5000.274960130833, 5024.346834911438, 5038.974805125951, 4741.57325840676, 4743.430788305287, 4745.098431298043, 4753.691010084472, 4789.353437286061, 4958.305837182726, 4958.950410495849, 4961.975190124026, 4963.181075122564, 5025.321581586439, 4917.237828136645, 4970.8757281068265, 4978.253262010728, 5010.69935803853, 5023.6985780853265, 4908.8772780680765, 4910.410750709938, 4912.778924948315, 4955.5044495550155, 4976.054549635558, 4896.290037944407, 4909.0, 4911.656184067081, 4912.287718280901, 4972.303877457087, 4734.384530011076, 4787.40157480315, 4798.054682403163, 4831.178699621002, 4914.754262286943, 4976.402831660224, 4986.925196121989, 5000.624953128603, 5059.038782631435, 5063.215201643604, 4831.299461578122, 4835.105505481357, 4838.491958344323, 4848.006158737521, 4873.098476781806], "value": 4613.5816566268295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.4088547910171, 577.6505214345322, 577.6707276852196, 584.5411352088611, 587.7178954102029, 28553.857230713842, 37754.62245377545, 39229.0, 39364.40953385702, 42856.357154642705, 40647.39028914569, 41102.0, 41160.794196029055, 41174.38238426426, 41223.0, 7670.539767613933, 7825.9217407826045, 7826.491278066907, 7827.099883513412, 7830.6084695764985, 7698.538087714755, 7794.298513133816, 7796.532208067506, 7798.8440231195345, 7799.8050048748955, 7804.219578042207, 7846.72536461224, 7846.960765196166, 7857.214278572125, 7858.410619203554, 7747.147813740484, 7769.3784497240085, 7769.922300776976, 7785.649645765954, 7793.298603125718, 7654.19630938749, 7687.231276872324, 7712.537247765152, 7760.573168475732, 7764.456488045839, 38325.80837095824, 38652.420213696816, 38675.61324386748, 38709.22581548381, 38784.80607596944, 2824.308044529083, 2875.870585823643, 2884.423115376933, 2926.0636596289196, 2963.303623648441, 27705.584416233774, 28202.15393538197, 28646.427071458664, 28664.713352866413, 28960.855195724096, 3444.1045328214595, 3452.3267962747273, 3457.2357981593154, 3564.1802385451233, 3582.8566857325677, 247538.7623061891, 247613.0, 248540.0, 249903.0, 249926.0, 53660.73169634205, 53706.7314663432, 53745.46254537483, 54044.45955540394, 54216.0, 32104.518432223536, 32972.83513582417, 33034.0, 35128.47307290393, 55650.72174639182, 13363.799543006862, 13453.865461345262, 13472.595822125351, 13534.0, 13535.187888726708, 10509.369437833755, 10535.578576856993, 10554.841677374845, 10560.683179504627, 10560.788784224425, 17863.553411164823, 17881.195346209453, 18016.189271482814, 18059.27762889496, 18130.45608631743, 6481.611103333815, 6666.233383160901, 6740.764073257413, 6789.830254243585, 6875.381215690511, 747.9401647868158, 823.642207440607, 859.3297228162044, 864.0840708848641, 956.168133723651, 25759.356016099373, 27789.86105069502, 28179.57592393694, 28402.289942751595, 28702.138935831957, 769.5959621198915, 926.1757036237753, 956.3926906414338, 988.7874107067015, 999.2705325112709, 13594.728105437836, 14449.31542456688, 14529.200893950723, 14758.55724328272, 14875.851241487662, 346949.0, 349871.0, 349875.0, 351659.0, 351738.0, 49291.0, 49578.50421495811, 49599.752001240486, 49650.0, 49859.0, 1024.5594394410475, 1072.4905669806778, 1138.8405623212923, 1156.1848896527927, 1171.5255321594768, 25157.87421062883, 29238.269043273238, 29422.558661619663, 29710.85144574263, 29812.850935745184, 41773.164536708486, 51184.23223651574, 52880.73559632177, 53067.734661326445, 53090.0, 336797.0, 339378.0, 340012.0, 340777.0, 341110.0, 24353.0, 25087.74912250854, 25288.81579276207, 38245.0, 38660.80669596634, 40784.0, 41266.58733412628, 41381.37927931143, 41429.0, 41647.1670566593, 34907.30185396328, 35171.472427914116, 35293.64706352904, 35353.0, 35436.29127417489, 35203.471947921316, 35613.0, 35676.64323356837, 35838.46242306315, 36103.27793444064, 34247.31505369829, 35008.67582172466, 35333.646663533036, 35483.29033419369, 35604.64395356013, 29817.850910746176, 32152.0, 32451.513227301137, 32500.187495312806, 32661.0, 24130.0, 24715.87642061778, 24848.254552363458, 24919.377015574755, 25182.62226066647], "value": 17235.214212943694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3053892215569, 356.25364860617015, 366.7322854316348, 369.4845690262084, 392.7682667226336, 12672.176308539938, 13010.544630937908, 14070.859291407083, 16180.514584562452, 16920.915395423006, 16261.10563918984, 16402.671946561062, 16550.751738723928, 16691.081990490547, 16696.0, 4685.789139488723, 5032.0, 5079.034983353151, 5088.4911508849, 5090.643654944155, 3606.0, 3804.029972357045, 5109.054824857395, 5193.350831146106, 5194.324737784102, 5667.4615911488545, 5940.37626049263, 5963.582549221556, 6056.394360563931, 6062.363451837559, 5801.9129713054335, 5942.910856337158, 5976.701164941757, 5982.760689572412, 6048.637081775108, 5261.921071183935, 5277.23480095384, 5285.365756109291, 5290.761915713802, 5290.92063619046, 19456.805431945784, 20101.39695809128, 20264.594708105902, 20287.087081081387, 20330.695039574417, 1729.44657709533, 1867.0, 2090.4251330883976, 2217.9889100554556, 2331.2423462374795, 12465.813012804816, 13241.404136813866, 13609.795853062213, 13667.248301343423, 13729.107608005448, 1695.2710334556136, 1753.7194048952106, 1754.4473490850469, 1795.8742887997846, 1807.2951548895942, 65130.348696512905, 67998.9800152998, 68220.0, 68592.657036715, 69013.65493172553, 9529.809403811885, 9577.856332155023, 9856.112949834405, 10011.798584169946, 10151.593936242467, 13901.513447029507, 13911.30443477822, 13912.165270083828, 13912.30438478072, 13913.373898174606, 7092.5744455331815, 7182.748603798842, 7266.091738532684, 7313.853722925512, 7378.631068446556, 6750.3924646782025, 6887.414569761526, 7178.964105179545, 7350.191478937312, 7366.742164024341, 3968.166684996183, 4060.5127384713433, 4181.749095054306, 4257.106007738348, 4379.167958087953, 1722.3196837249216, 1791.7312403139372, 1798.856091512676, 1829.6066345735464, 1829.7987221405635, 643.5045015338163, 776.9494982826211, 783.3705891745157, 803.5901690138072, 912.1061359867234, 15905.602359941095, 15959.122248276457, 16820.74768878468, 17533.386331478338, 17555.29778808859, 829.2163905109734, 836.7364280251701, 840.357126798, 842.1872892658614, 858.1932982995963, 8945.418547794372, 9388.577514011886, 9775.560099795526, 9816.509174541245, 9957.900420995842, 353867.0, 360545.0, 364249.0, 365517.0, 367233.0, 72735.63632181911, 79482.0, 79882.0, 80972.59513702511, 82267.0, 899.383922013436, 914.2366124286295, 930.0978051290238, 930.4603330068679, 933.1368484152132, 15117.244137793286, 15129.924350378178, 15843.683126337639, 16069.91965040214, 16142.0, 37518.24963500769, 37851.81074094722, 38532.42201367038, 38961.220775584894, 39839.60160398359, 51679.0, 52247.73876130595, 52303.476965231384, 52907.206391904925, 52963.0, 7348.595827229554, 7620.80947976305, 7644.961775191311, 7678.731244406419, 8018.596745569619, 8217.05503867039, 10326.72811981363, 11768.705782355168, 11932.642020739391, 12107.33409662477, 9654.951725241328, 9891.455969921722, 10042.799144016933, 10194.694159175237, 10242.692719218436, 9758.414495130315, 10178.898211017795, 10233.948830256099, 10314.329568578018, 10499.790004200026, 9753.707388778348, 10060.144887684628, 10123.898761012295, 10486.790264194826, 10524.736881577715, 7566.772996810105, 7673.501222420525, 7680.577568233801, 7680.884786728092, 8018.3184429324165, 7636.465447418627, 8953.238974687225, 9553.283503737386, 9999.550020249251, 10088.0], "value": 8177.509695495407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.7226918481708, 1378.3590630356885, 1460.2333774768244, 1666.6833301672673, 1696.4232161065236, 6864.107666003425, 7104.005439238511, 7459.1049074111115, 8149.511029338244, 8158.8368232635385, 7465.813354666123, 7743.148253692089, 7757.844843103135, 7992.680292788296, 7992.760217193479, 2149.2477632828504, 2175.923842665507, 2176.880271585062, 2177.8148857347064, 2182.1053368119074, 1815.2285278756542, 1884.5665496935712, 2107.1782005018053, 2162.156758864044, 2165.6859755335468, 2100.380387785607, 2200.7469141048823, 2206.8455208135438, 2213.435573928654, 2226.142934970032, 2090.0, 2119.629064913634, 2120.4698825293676, 2152.332776839185, 2166.350094971502, 2141.0900367343816, 2175.4887601413657, 2185.158713895146, 2187.8359123065834, 2191.4630915425664, 7589.506682065703, 7595.050618672666, 7595.620218989028, 7616.923830761677, 7810.921890781133, 4557.931631025537, 4679.555442232982, 4679.60223381013, 4710.105080034782, 4723.4568024677255, 2726.1003868723474, 2905.418916216765, 2907.8400687962153, 2917.7519910807605, 2921.9123426297033, 920.8250432417819, 922.1884741427568, 934.1732566678538, 942.9674506415464, 945.8108378324363, 10434.68695939123, 10675.839862401992, 10731.195160363004, 10733.40966246856, 10761.569537218502, 4008.6392224699916, 4118.5263694674895, 4182.414461975326, 4239.537359610929, 4275.850345237902, 9237.90762092384, 9264.351495395258, 9285.30360222986, 9350.111739384747, 9358.391704539183, 4638.095571363552, 4639.164950308977, 4666.230072038144, 4723.905521889543, 4757.785899634525, 4222.831086756495, 4231.555686652857, 4283.378910058024, 4289.978550107229, 4290.978545107317, 3372.3086767212662, 3419.247765491588, 3494.9126271843406, 3512.613612502623, 3513.9297214056087, 1050.0811789684026, 1104.1608377633056, 1110.3726394587034, 1152.7982603044434, 1156.5894107591853, 621.623917529897, 734.9595772232472, 762.367235194787, 765.946383753132, 779.3064172886136, 4900.411950565955, 4951.504849515091, 4964.131277026506, 4986.750662466935, 5066.341375621219, 783.2598194706062, 823.6869989404108, 830.3066939105794, 840.1724301562956, 868.1702368594915, 3285.408626447215, 3296.522004309362, 3320.335932813399, 3328.334333133383, 3380.645032271576, 9399.483028433502, 9726.465044422483, 9845.901540984642, 9849.064338887794, 9860.408375497493, 9598.568064437117, 9788.55951482185, 9809.754756131153, 9847.507624618884, 9863.112319891245, 7366.410687145016, 7425.257474252639, 7438.256174382626, 7441.8139546511775, 7450.254974502506, 4882.023595281029, 4885.5358740919555, 4911.926321105258, 4978.726170060681, 5124.308218390619, 8777.874399032444, 8969.416987896027, 9034.593443295198, 9040.186383225542, 9202.953985230031, 8997.730068097968, 9211.355205135844, 9293.907060929305, 9410.623575056921, 9432.575534101119, 6775.728970841111, 6816.250212476524, 6849.0, 6898.8620227594165, 6921.8961715575315, 7391.150017747813, 7446.292602202782, 7449.217832127549, 7456.030716007102, 7460.813479663052, 6471.935280647133, 6521.706523206372, 6539.869202615826, 6595.604263744191, 6705.765298214734, 6553.541252112497, 6591.967040164768, 6648.268690443951, 6668.066470694203, 6832.453403727591, 5780.913286300793, 5829.446202610745, 5830.358660547421, 5831.1544826000845, 5834.27071616048, 4108.2810508160355, 4120.670346372223, 4284.186004659229, 4297.462817147856, 4351.477822661301, 4381.824727010884, 4429.490608580055, 4434.711743736647, 4549.522300158435, 4557.931631025471], "value": 4069.3087632390384, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.6751919052685, 289.6234894636971, 297.23313850266317, 297.55069844534745, 301.6787121715374, 4696.647751418649, 4893.070316639836, 4915.164422048251, 4922.114019476498, 4947.28264401662, 4690.296455531672, 4778.689385189969, 4809.855704328876, 4818.301346304793, 4836.2987366831785, 3579.6957258632915, 3724.5530536335677, 3822.847086116566, 3828.827702753369, 3839.577646458887, 3748.418995055757, 3837.7910958048265, 3838.0, 3858.7684738915755, 3885.261800257942, 3821.40768180931, 3821.5796262411113, 3824.368979118442, 3830.3296923038497, 3831.9845241011153, 3820.5797362290264, 3844.9615503844884, 3847.383779030258, 3864.14988702485, 3868.32304346741, 3118.892793058454, 3287.1124796305103, 3287.967120328814, 3293.6870997255223, 3426.1777173478436, 4731.4795372509, 4743.810247590092, 4744.810207591692, 4750.64370172235, 4754.453237877654, 4143.316352801784, 4295.398644189816, 4304.741715497049, 4317.179735850178, 4325.8485952991805, 5071.746412679351, 5081.551757749061, 5085.872853178663, 5087.186050231945, 5097.235414687826, 3555.9466608000903, 3586.677199052072, 3586.8385922633543, 3592.2276710507103, 3596.604373518911, 5287.154055351125, 5308.840734777963, 5312.389075256395, 5321.962217367615, 5323.760430780624, 3417.043227896193, 3561.1631266652835, 3584.4586827664357, 3605.675489205984, 3614.7288953328607, 3644.562652481719, 3648.4527320902075, 3650.1604630935035, 3651.8174091295327, 3655.177585043344, 4217.915641687149, 4221.219074471172, 4222.852200173041, 4225.260579398593, 4227.788610569459, 4912.214045752662, 5070.366204224472, 5103.8468845934685, 5133.024725302181, 5138.768755406016, 1332.3538084029237, 1352.5933029649054, 1352.9729405411836, 1356.3353956561411, 1359.8912087033013, 804.5534728225891, 834.6937043526862, 836.4897412578322, 839.8908141941508, 857.7869097727847, 3982.4623675803973, 3995.0623947385493, 4017.4174744662446, 4027.617376349242, 4055.2497787909333, 4613.223908795138, 4613.739267758774, 4615.0, 4629.1577990268, 4645.605123564467, 4021.0, 4034.1039711335625, 4059.330210515291, 4062.988820533855, 4064.5935406459116, 2485.7141428735567, 2592.8980183422345, 2758.2966343582257, 2785.3175971887017, 2799.4820958122814, 4913.656044076952, 4932.90134197314, 4934.383202099738, 4935.951580094221, 4954.5788607968725, 5346.07541285137, 5353.81261655848, 5359.169328754049, 5361.973190134025, 5368.248445217672, 4694.460137084211, 4962.064794730059, 5016.448190699093, 5021.49785021495, 5023.598112151019, 5284.973575132253, 5302.310699609026, 5308.389535203347, 5315.574754019746, 5326.7336633167415, 5021.698698078127, 5176.482351764794, 5224.869378265574, 5299.708516031501, 5306.52241298285, 5003.224500202475, 5008.4974507648285, 5020.648554601143, 5022.773975171053, 5028.547430731252, 3436.9656303436645, 3544.6278140794843, 3560.359135355661, 3659.8353074111196, 3664.7356661951585, 5254.13306804373, 5258.6581872178185, 5267.552258058108, 5267.683938963674, 5274.498922602346, 5083.923741143812, 5085.338905942352, 5091.287219789158, 5112.207607820794, 5122.820701275437, 4949.851504454872, 5042.773075211551, 5064.670796398223, 5070.264811602371, 5075.213341932006, 5001.749912504433, 5003.224500202475, 5007.549320561167, 5009.423916249678, 5010.69935803853, 4989.451160372429, 5122.0, 5150.742462876916, 5154.484551544965, 5156.819511317236, 5002.2496625506465, 5023.874403139951, 5024.321716568216, 5025.145725226647, 5026.4973502649445], "value": 3788.923656329332, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.5020431815685, 566.4958187213379, 588.3028611095849, 614.8401415631937, 627.9026750853619, 12006.639800805979, 12688.0, 13115.737685246302, 13131.868681313184, 13200.73598528029, 12389.380530973438, 12639.304838233893, 12647.430865611046, 12680.4927802888, 12692.0, 574.0900672434196, 745.4185735126583, 761.9276168763959, 762.881753328235, 764.1136281912982, 623.7910300049489, 749.7338444852081, 770.6532060572737, 773.6789232468519, 778.4200770426012, 809.2676128104046, 811.6144831205189, 813.7233340664174, 815.4658698552457, 815.7144999250245, 618.2673531864755, 805.315481840431, 806.749907528668, 807.0436532708759, 807.1968391450486, 471.09314569453664, 570.3982298674896, 585.8857522783059, 586.0974099886171, 588.4556784973882, 12288.078394120388, 12327.321997290146, 12357.505699771998, 12365.505379784798, 12366.258024518558, 11435.256708314017, 11927.0, 12081.879181208164, 12163.452644631012, 12209.877901220963, 10718.946405267923, 10781.838272425919, 10948.562057517689, 10961.0, 10980.835287470694, 3604.2250916052913, 3629.1290090378384, 3634.6365363463706, 3664.1022949377307, 3671.8714844980564, 52148.21777673338, 64916.0, 64977.675111624136, 65198.3480165197, 65897.0, 9284.303677224236, 9747.463889486145, 9887.604495820231, 10186.286959912735, 10188.388696678076, 17636.1181940905, 17668.469945901645, 17687.911560442117, 17688.380906668204, 17707.38024169148, 6574.474042076622, 6640.369164929324, 6661.666916654148, 6740.966295168493, 7019.649017549102, 3593.8984298454156, 3603.873864414733, 3619.9276014479565, 3629.129009037812, 3632.582253040934, 2877.7697784177635, 2971.985140074329, 2987.2979849735293, 2992.521196608532, 2995.0715278263806, 482.642844295219, 483.23889873449457, 484.78911673422175, 493.0681012885649, 496.2655270200124, 466.9229577119737, 491.3170692737098, 510.6348960493265, 512.5156726893125, 520.7084032941565, 7086.39765619928, 7290.635468226567, 7314.817129571697, 7466.626668666545, 7519.366706214334, 468.2227502346157, 494.8540180646718, 530.6444682063027, 530.9946900531022, 534.3934634190222, 3505.94741078884, 3521.0845180252804, 3557.839897204632, 3630.1650620357464, 3674.982308023493, 99279.0, 100899.0, 102157.48921255347, 106501.0, 106533.46733266284, 81201.59399203084, 85962.57018714992, 86824.56587717147, 87010.56494717486, 91813.08186918178, 74259.0, 74669.05295152857, 76142.61928690321, 76816.23183768202, 77397.0, 6555.246146693191, 6814.0, 6899.586024838325, 6981.0924579804305, 6995.020697102512, 47006.294905577124, 48561.271580925604, 48923.51076489332, 48960.510394895595, 50907.745461272454, 59297.407025930916, 65757.67121164555, 65895.34709380702, 66230.66884665546, 69212.30787692059, 8768.561571921251, 8853.822923541622, 8856.11438856135, 9083.273338132802, 9335.486548239913, 10122.0, 10168.084872361524, 10276.640317588848, 10344.586216551254, 10556.419396933243, 8900.910990890008, 9125.406848555089, 9219.262459003397, 9269.814603707753, 9487.241020718464, 9436.764080898032, 9470.479123648265, 9637.71086867395, 9897.50512474359, 9901.950490247502, 9499.525023748924, 9721.076497732704, 9780.951095244478, 9786.755331116494, 9792.755181120529, 7811.960940195263, 8047.315978141924, 8296.0, 8359.58202089881, 8392.874106888523, 7863.882041769492, 8156.574238819591, 8415.621297041771, 8620.91379086201, 8689.17452841979], "value": 6128.020733194104, "unit": "score"}], "name": "dromaeo_css", "value": 6281.746685366532, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 115.0, 113.0, 115.0, 116.0, 114.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 127.0, 123.0, 176.0, 126.0, 124.0, 123.0, 123.0, 177.0, 124.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 162.0, 160.0, 161.0, 161.0, 161.0, 163.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 116.0, 77.0, 77.0, 77.0, 76.0, 120.0, 78.0], "value": 85.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 112.0, 85.0, 85.0, 84.0, 106.0, 85.0, 84.0, 113.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 82.0, 82.0, 84.0, 82.0, 82.0, 82.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 143.0, 135.0, 132.0, 133.0, 136.0, 132.0, 136.0, 173.0, 132.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 174.0, 177.0, 173.0, 171.0, 171.0, 173.0, 172.0, 172.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 69.0, 71.0, 69.0, 72.0, 69.0, 69.0, 67.0, 68.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1544.1, "alertThreshold": 2.0}]} 04:13:08 INFO - Return code: 0 04:13:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:13:08 INFO - # TBPL SUCCESS # 04:13:08 INFO - Running post-action listener: _resource_record_post_action 04:13:08 INFO - [mozharness: 2016-09-21 11:13:08.177225Z] Finished run-tests step (success) 04:13:08 INFO - Running post-run listener: _resource_record_post_run 04:13:08 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 12288; Write bytes: 389509120; Read time: 172; Write time: 281164 04:13:08 INFO - TinderboxPrint: CPU usage
13.1% 04:13:08 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 172 04:13:08 INFO - TinderboxPrint: I/O write bytes / time
389,509,120 / 281,164 04:13:08 INFO - TinderboxPrint: CPU idle
8,336.5 (86.9%) 04:13:08 INFO - TinderboxPrint: CPU user
1,202.5 (12.5%) 04:13:08 INFO - TinderboxPrint: Swap in / out
0 / 0 04:13:08 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99696640; Read time: 0; Write time: 96672 04:13:08 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 289808384; Read time: 172; Write time: 184492 04:13:08 INFO - Running post-run listener: _upload_blobber_files 04:13:08 INFO - Blob upload gear active. 04:13:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:13:08 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 04:13:08 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:13:08 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:13:09 INFO - (blobuploader) - INFO - Open directory for files ... 04:13:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:13:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:13:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:09 INFO - (blobuploader) - INFO - Done attempting. 04:13:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:13:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:13:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:14 INFO - (blobuploader) - INFO - Done attempting. 04:13:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:13:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:13:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:15 INFO - (blobuploader) - INFO - Done attempting. 04:13:15 INFO - (blobuploader) - INFO - Iteration through files over. 04:13:15 INFO - Return code: 0 04:13:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:13:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:13:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07e7160a408582dc4ad4c7f8ed5348a1c0c61f5e0ca76b3ae8dec00ef419bb631f4f191e48b98b2aa946672bbb4fe9e5b48d6c0ae1ed0d9a9d5878ebc65a7813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/342db55cb5226f7d10ac6c075ca11d94f0ec858d2a2f9a0f6eebce9ae443a6b962a810a0ba8791a1bf47751d8747d88e30ba84757708ec22c95615676dffccdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bd63bd3c7ef675f39e97e649ea30413b553a5d292870d04346e003dc10c47b19f7724e69138378d943ca4ca40fdeddcc4f2bffa939273c1c4bb306f29ecc8ca"} 04:13:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:13:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:13:15 INFO - Contents: 04:13:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07e7160a408582dc4ad4c7f8ed5348a1c0c61f5e0ca76b3ae8dec00ef419bb631f4f191e48b98b2aa946672bbb4fe9e5b48d6c0ae1ed0d9a9d5878ebc65a7813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/342db55cb5226f7d10ac6c075ca11d94f0ec858d2a2f9a0f6eebce9ae443a6b962a810a0ba8791a1bf47751d8747d88e30ba84757708ec22c95615676dffccdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bd63bd3c7ef675f39e97e649ea30413b553a5d292870d04346e003dc10c47b19f7724e69138378d943ca4ca40fdeddcc4f2bffa939273c1c4bb306f29ecc8ca"} 04:13:15 INFO - Running post-run listener: copy_logs_to_upload_dir 04:13:15 INFO - Copying logs to upload dir... 04:13:15 INFO - mkdir: /builds/slave/test/build/upload/logs 04:13:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.004593 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-21 04:13:15.665522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 04:13:15.669118) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 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-Non-PGO/sha512/07e7160a408582dc4ad4c7f8ed5348a1c0c61f5e0ca76b3ae8dec00ef419bb631f4f191e48b98b2aa946672bbb4fe9e5b48d6c0ae1ed0d9a9d5878ebc65a7813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/342db55cb5226f7d10ac6c075ca11d94f0ec858d2a2f9a0f6eebce9ae443a6b962a810a0ba8791a1bf47751d8747d88e30ba84757708ec22c95615676dffccdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bd63bd3c7ef675f39e97e649ea30413b553a5d292870d04346e003dc10c47b19f7724e69138378d943ca4ca40fdeddcc4f2bffa939273c1c4bb306f29ecc8ca"} build_url:https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011462 build_url: 'https://queue.taskcluster.net/v1/task/I6EfeyZGTZCfjaj3s0LA8g/artifacts/public/build/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-Non-PGO/sha512/07e7160a408582dc4ad4c7f8ed5348a1c0c61f5e0ca76b3ae8dec00ef419bb631f4f191e48b98b2aa946672bbb4fe9e5b48d6c0ae1ed0d9a9d5878ebc65a7813", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/342db55cb5226f7d10ac6c075ca11d94f0ec858d2a2f9a0f6eebce9ae443a6b962a810a0ba8791a1bf47751d8747d88e30ba84757708ec22c95615676dffccdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bd63bd3c7ef675f39e97e649ea30413b553a5d292870d04346e003dc10c47b19f7724e69138378d943ca4ca40fdeddcc4f2bffa939273c1c4bb306f29ecc8ca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 04:13:15.698501) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 04:13:15.698909) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474455146.112963-993136072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 04:13:15.779759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 04:13:15.780096) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 04:13:15.780423) ========= ========= Total master_lag: 0.18 =========