builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1490887922.47 results: success (0) revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.470224) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.470883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.471219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.486776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.487191) ========= 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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-30 08:32:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 58.4K=0.2s 2017-03-30 08:32:02 (58.4 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.306391 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.808104) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.808562) ========= 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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018689 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.859839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:02.860596) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-30 08:32:02,922 truncating revision to first 12 chars 2017-03-30 08:32:02,922 Setting DEBUG logging. 2017-03-30 08:32:02,922 attempt 1/10 2017-03-30 08:32:02,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3364cc17988c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 08:32:03,502 unpacking tar archive at: mozilla-central-3364cc17988c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.888165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:03.767486) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:03.767830) ========= script_repo_revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:03.768260) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:03.768563) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 08:32:03.787138) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-30 08:32:03.787606) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:32:03 INFO - MultiFileLogger online at 20170330 08:32:03 in /builds/slave/test 08:32:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:32:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:32:03 INFO - {'append_to_log': False, 08:32:03 INFO - 'base_work_dir': '/builds/slave/test', 08:32:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:32:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:32:03 INFO - 'branch': 'Firefox-Non-PGO', 08:32:03 INFO - 'buildbot_json_path': 'buildprops.json', 08:32:03 INFO - 'config_files': ('talos/linux_config.py',), 08:32:03 INFO - 'default_actions': ('clobber', 08:32:03 INFO - 'read-buildbot-config', 08:32:03 INFO - 'download-and-extract', 08:32:03 INFO - 'populate-webroot', 08:32:03 INFO - 'create-virtualenv', 08:32:03 INFO - 'install', 08:32:03 INFO - 'run-tests'), 08:32:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:32:03 INFO - 'download_minidump_stackwalk': True, 08:32:03 INFO - 'download_symbols': 'ondemand', 08:32:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:32:03 INFO - 'tooltool.py': '/tools/tooltool.py', 08:32:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:32:03 INFO - '/tools/misc-python/virtualenv.py')}, 08:32:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:32:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:32:03 INFO - 'gecko_profile': False, 08:32:03 INFO - 'gecko_profile_interval': 0, 08:32:03 INFO - 'installer_path': 'installer.exe', 08:32:03 INFO - 'log_level': 'info', 08:32:03 INFO - 'log_name': 'talos', 08:32:03 INFO - 'log_to_console': True, 08:32:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:32:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:32:03 INFO - 'opt_config_files': (), 08:32:03 INFO - 'pip_index': False, 08:32:03 INFO - 'suite': 'dromaeojs-e10s', 08:32:03 INFO - 'system_bits': '32', 08:32:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:32:03 INFO - 'title': 'talos-linux64-ix-011', 08:32:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:32:03 INFO - 'use_talos_json': True, 08:32:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:32:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:32:03 INFO - 'work_dir': 'build'} 08:32:03 INFO - [mozharness: 2017-03-30 15:32:03.937812Z] Running clobber step. 08:32:03 INFO - Running pre-action listener: _resource_record_pre_action 08:32:03 INFO - Running main action method: clobber 08:32:03 INFO - rmtree: /builds/slave/test/build 08:32:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:32:04 INFO - Running post-action listener: _resource_record_post_action 08:32:04 INFO - [mozharness: 2017-03-30 15:32:04.867808Z] Finished clobber step (success) 08:32:04 INFO - [mozharness: 2017-03-30 15:32:04.867906Z] Running read-buildbot-config step. 08:32:04 INFO - Running pre-action listener: _resource_record_pre_action 08:32:04 INFO - Running main action method: read_buildbot_config 08:32:04 INFO - Using buildbot properties: 08:32:04 INFO - { 08:32:04 INFO - "project": "", 08:32:04 INFO - "product": "firefox", 08:32:04 INFO - "who": "ffxbld@noreply.mozilla.org", 08:32:04 INFO - "installer_path": "public/build/target.tar.bz2", 08:32:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:32:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:32:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:32:04 INFO - "stage_platform": "linux64", 08:32:04 INFO - "basedir": "/builds/slave/test", 08:32:04 INFO - "buildnumber": 744, 08:32:04 INFO - "platform": "ubuntu64_hw", 08:32:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:32:04 INFO - "slavebuilddir": "test", 08:32:04 INFO - "taskId": "Dr52SoktTB2XobK8TF6CWA", 08:32:04 INFO - "branch": "mozilla-central", 08:32:04 INFO - "script_repo_revision": "production", 08:32:04 INFO - "revision": "3364cc17988c013c36f2a8123315db2855393011", 08:32:04 INFO - "slavename": "talos-linux64-ix-011", 08:32:04 INFO - "repo_path": "mozilla-central" 08:32:04 INFO - } 08:32:04 INFO - Finding installer, test and symbols from parent task. 08:32:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dr52SoktTB2XobK8TF6CWA'}, attempt #1 08:32:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dr52SoktTB2XobK8TF6CWA'}, attempt #1 08:32:05 INFO - Task dependencies: WPrw1PBMSS-vUjG5HGKOMw 08:32:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw'}, attempt #1 08:32:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 08:32:06 INFO - Set test_url: https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/test_packages.json 08:32:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.crashreporter-symbols.zip 08:32:06 INFO - Running post-action listener: _resource_record_post_action 08:32:06 INFO - [mozharness: 2017-03-30 15:32:06.257321Z] Finished read-buildbot-config step (success) 08:32:06 INFO - [mozharness: 2017-03-30 15:32:06.257539Z] Running download-and-extract step. 08:32:06 INFO - Running pre-action listener: _resource_record_pre_action 08:32:06 INFO - Running main action method: download_and_extract 08:32:06 INFO - mkdir: /builds/slave/test/build/tests 08:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:06 INFO - trying https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.test_packages.json 08:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:32:06 INFO - Downloaded 986 bytes. 08:32:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:32:06 INFO - Using the following test package requirements: 08:32:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:32:06 INFO - u'common': [u'target.common.tests.zip'], 08:32:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:32:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:32:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:32:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:32:06 INFO - u'mozbase': [u'target.common.tests.zip'], 08:32:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:32:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:32:06 INFO - u'web-platform': [u'target.common.tests.zip', 08:32:06 INFO - u'target.web-platform.tests.zip'], 08:32:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:32:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:32:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip 08:32:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:32:06 INFO - Fetch https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip into memory 08:32:08 INFO - Expected file size: 58234116 08:32:08 INFO - Obtained file size: 58234116 08:32:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:32:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip 08:32:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:32:18 INFO - Fetch https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.common.tests.zip into memory 08:32:20 INFO - Expected file size: 58234116 08:32:20 INFO - Obtained file size: 58234116 08:32:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.talos.tests.zip 08:32:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:32:30 INFO - Fetch https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.talos.tests.zip into memory 08:32:32 INFO - Expected file size: 14404152 08:32:32 INFO - Obtained file size: 14404152 08:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:32 INFO - trying https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 08:32:32 INFO - Downloading https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:32:35 INFO - Downloaded 62548797 bytes. 08:32:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 08:32:35 INFO - mkdir: /builds/slave/test/properties 08:32:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:35 INFO - Contents: 08:32:35 INFO - build_url:https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 08:32:35 INFO - Running post-action listener: _resource_record_post_action 08:32:35 INFO - Running post-action listener: set_extra_try_arguments 08:32:35 INFO - [mozharness: 2017-03-30 15:32:35.116224Z] Finished download-and-extract step (success) 08:32:35 INFO - [mozharness: 2017-03-30 15:32:35.116398Z] Running populate-webroot step. 08:32:35 INFO - Running pre-action listener: _resource_record_pre_action 08:32:35 INFO - Running main action method: populate_webroot 08:32:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:32:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:32:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:32:35 INFO - '--tppagecycles', 08:32:35 INFO - '7'], 08:32:35 INFO - 'tests': ['tsvgm']}}, 08:32:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['damp', 'tps']}, 08:32:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'tests': ['damp', 'tps']}, 08:32:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['dromaeo_dom']}, 08:32:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['basic_compositor_video']}, 08:32:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:32:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['a11yr', 08:32:35 INFO - 'ts_paint', 08:32:35 INFO - 'tpaint', 08:32:35 INFO - 'sessionrestore', 08:32:35 INFO - 'sessionrestore_no_auto_restore', 08:32:35 INFO - 'tabpaint']}, 08:32:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['tsvgx', 08:32:35 INFO - 'tsvgr_opacity', 08:32:35 INFO - 'tart', 08:32:35 INFO - 'tscrollx', 08:32:35 INFO - 'cart', 08:32:35 INFO - 'tsvg_static']}, 08:32:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:35 INFO - 'tsvgr_opacity', 08:32:35 INFO - 'tart', 08:32:35 INFO - 'tscrollx', 08:32:35 INFO - 'cart', 08:32:35 INFO - 'tsvg_static']}, 08:32:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'talos_options': ['--disable-e10s'], 08:32:35 INFO - 'tests': ['tp5o']}, 08:32:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:32:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'talos_options': ['--disable-e10s', 08:32:35 INFO - '--xperf_path', 08:32:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:35 INFO - 'tests': ['tp5n']}, 08:32:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:32:35 INFO - 'talos_options': ['--xperf_path', 08:32:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:35 INFO - 'tests': ['tp5n']}}, 08:32:35 INFO - 'talos.zip': {'path': '', 08:32:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:32:35 INFO - Running post-action listener: _resource_record_post_action 08:32:35 INFO - [mozharness: 2017-03-30 15:32:35.131983Z] Finished populate-webroot step (success) 08:32:35 INFO - [mozharness: 2017-03-30 15:32:35.132072Z] Running create-virtualenv step. 08:32:35 INFO - Running pre-action listener: _resource_record_pre_action 08:32:35 INFO - Running main action method: create_virtualenv 08:32:35 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:35 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 08:32:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:32:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:35 INFO - Using real prefix '/usr' 08:32:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:35 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:37 INFO - Installing pip.................done. 08:32:37 INFO - Return code: 0 08:32:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:32:37 INFO - Reading from file tmpfile_stdout 08:32:37 INFO - Output received: 08:32:37 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:32:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32:37 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')]} 08:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 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')]} 08:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:37 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 08:32:37 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 08:32:37 INFO - Using env: {'DISPLAY': ':0', 08:32:37 INFO - 'HOME': '/home/cltbld', 08:32:37 INFO - 'LANG': 'en_US.UTF-8', 08:32:37 INFO - 'LANGUAGE': 'en_US:en', 08:32:37 INFO - 'LOGNAME': 'cltbld', 08:32:37 INFO - 'MAIL': '/var/mail/cltbld', 08:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:37 INFO - 'MOZ_NO_REMOTE': '1', 08:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:37 INFO - 'NO_EM_RESTART': '1', 08:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:37 INFO - 'PWD': '/builds/slave/test', 08:32:37 INFO - 'SHELL': '/bin/bash', 08:32:37 INFO - 'SHLVL': '1', 08:32:37 INFO - 'TERM': 'linux', 08:32:37 INFO - 'TMOUT': '86400', 08:32:37 INFO - 'USER': 'cltbld', 08:32:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:37 INFO - '_': '/tools/buildbot/bin/python'} 08:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:38 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 08:32:38 INFO - Cleaning up... 08:32:38 INFO - Return code: 0 08:32:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:32:38 INFO - Using env: {'DISPLAY': ':0', 08:32:38 INFO - 'HOME': '/home/cltbld', 08:32:38 INFO - 'LANG': 'en_US.UTF-8', 08:32:38 INFO - 'LANGUAGE': 'en_US:en', 08:32:38 INFO - 'LOGNAME': 'cltbld', 08:32:38 INFO - 'MAIL': '/var/mail/cltbld', 08:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:38 INFO - 'MOZ_NO_REMOTE': '1', 08:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:38 INFO - 'NO_EM_RESTART': '1', 08:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:38 INFO - 'PWD': '/builds/slave/test', 08:32:38 INFO - 'SHELL': '/bin/bash', 08:32:38 INFO - 'SHLVL': '1', 08:32:38 INFO - 'TERM': 'linux', 08:32:38 INFO - 'TMOUT': '86400', 08:32:38 INFO - 'USER': 'cltbld', 08:32:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:38 INFO - '_': '/tools/buildbot/bin/python'} 08:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:38 INFO - Downloading/unpacking psutil>=3.1.1 08:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:40 INFO - Installing collected packages: psutil 08:32:40 INFO - Running setup.py install for psutil 08:32:40 INFO - building 'psutil._psutil_linux' extension 08:32:40 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 08:32:40 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 08:32:40 INFO - building 'psutil._psutil_posix' extension 08:32:40 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 08:32:40 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 08:32:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:41 INFO - Successfully installed psutil 08:32:41 INFO - Cleaning up... 08:32:41 INFO - Return code: 0 08:32:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:41 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:41 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 08:32:41 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 08:32:41 INFO - Using env: {'DISPLAY': ':0', 08:32:41 INFO - 'HOME': '/home/cltbld', 08:32:41 INFO - 'LANG': 'en_US.UTF-8', 08:32:41 INFO - 'LANGUAGE': 'en_US:en', 08:32:41 INFO - 'LOGNAME': 'cltbld', 08:32:41 INFO - 'MAIL': '/var/mail/cltbld', 08:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:41 INFO - 'MOZ_NO_REMOTE': '1', 08:32:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:41 INFO - 'NO_EM_RESTART': '1', 08:32:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:41 INFO - 'PWD': '/builds/slave/test', 08:32:41 INFO - 'SHELL': '/bin/bash', 08:32:41 INFO - 'SHLVL': '1', 08:32:41 INFO - 'TERM': 'linux', 08:32:41 INFO - 'TMOUT': '86400', 08:32:41 INFO - 'USER': 'cltbld', 08:32:41 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:41 INFO - '_': '/tools/buildbot/bin/python'} 08:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:41 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 08:32:41 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 08:32:41 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 08:32:41 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 08:32:41 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 08:32:41 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 08:32:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:43 INFO - Installing collected packages: mozsystemmonitor 08:32:43 INFO - Running setup.py install for mozsystemmonitor 08:32:43 INFO - Successfully installed mozsystemmonitor 08:32:43 INFO - Cleaning up... 08:32:43 INFO - Return code: 0 08:32:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:32:43 INFO - Using env: {'DISPLAY': ':0', 08:32:43 INFO - 'HOME': '/home/cltbld', 08:32:43 INFO - 'LANG': 'en_US.UTF-8', 08:32:43 INFO - 'LANGUAGE': 'en_US:en', 08:32:43 INFO - 'LOGNAME': 'cltbld', 08:32:43 INFO - 'MAIL': '/var/mail/cltbld', 08:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:43 INFO - 'MOZ_NO_REMOTE': '1', 08:32:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:43 INFO - 'NO_EM_RESTART': '1', 08:32:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:43 INFO - 'PWD': '/builds/slave/test', 08:32:43 INFO - 'SHELL': '/bin/bash', 08:32:43 INFO - 'SHLVL': '1', 08:32:43 INFO - 'TERM': 'linux', 08:32:43 INFO - 'TMOUT': '86400', 08:32:43 INFO - 'USER': 'cltbld', 08:32:43 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:43 INFO - '_': '/tools/buildbot/bin/python'} 08:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:45 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:46 INFO - warning: no files found matching '*.txt' 08:32:46 INFO - no previously-included directories found matching 'build' 08:32:46 INFO - no previously-included directories found matching 'dist' 08:32:46 INFO - no previously-included directories found matching '.git*' 08:32:46 INFO - Installing collected packages: jsonschema, functools32 08:32:46 INFO - Running setup.py install for functools32 08:32:46 INFO - warning: no files found matching '*.txt' 08:32:46 INFO - no previously-included directories found matching 'build' 08:32:46 INFO - no previously-included directories found matching 'dist' 08:32:46 INFO - no previously-included directories found matching '.git*' 08:32:46 INFO - Successfully installed jsonschema functools32 08:32:46 INFO - Cleaning up... 08:32:46 INFO - Return code: 0 08:32:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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', '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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:32: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 functools32==3.2.3-2 08:32:46 INFO - Using env: {'DISPLAY': ':0', 08:32:46 INFO - 'HOME': '/home/cltbld', 08:32:46 INFO - 'LANG': 'en_US.UTF-8', 08:32:46 INFO - 'LANGUAGE': 'en_US:en', 08:32:46 INFO - 'LOGNAME': 'cltbld', 08:32:46 INFO - 'MAIL': '/var/mail/cltbld', 08:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:46 INFO - 'MOZ_NO_REMOTE': '1', 08:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:46 INFO - 'NO_EM_RESTART': '1', 08:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:46 INFO - 'PWD': '/builds/slave/test', 08:32:46 INFO - 'SHELL': '/bin/bash', 08:32:46 INFO - 'SHLVL': '1', 08:32:46 INFO - 'TERM': 'linux', 08:32:46 INFO - 'TMOUT': '86400', 08:32:46 INFO - 'USER': 'cltbld', 08:32:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:46 INFO - '_': '/tools/buildbot/bin/python'} 08:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:32:46 INFO - Cleaning up... 08:32:46 INFO - Return code: 0 08:32:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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', '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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:32: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 blobuploader==1.2.4 08:32:46 INFO - Using env: {'DISPLAY': ':0', 08:32:46 INFO - 'HOME': '/home/cltbld', 08:32:46 INFO - 'LANG': 'en_US.UTF-8', 08:32:46 INFO - 'LANGUAGE': 'en_US:en', 08:32:46 INFO - 'LOGNAME': 'cltbld', 08:32:46 INFO - 'MAIL': '/var/mail/cltbld', 08:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:46 INFO - 'MOZ_NO_REMOTE': '1', 08:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:46 INFO - 'NO_EM_RESTART': '1', 08:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:46 INFO - 'PWD': '/builds/slave/test', 08:32:46 INFO - 'SHELL': '/bin/bash', 08:32:46 INFO - 'SHLVL': '1', 08:32:46 INFO - 'TERM': 'linux', 08:32:46 INFO - 'TMOUT': '86400', 08:32:46 INFO - 'USER': 'cltbld', 08:32:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:46 INFO - '_': '/tools/buildbot/bin/python'} 08:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:49 INFO - Downloading docopt-0.6.1.tar.gz 08:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:49 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:49 INFO - Running setup.py install for blobuploader 08:32:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:49 INFO - Running setup.py install for requests 08:32:49 INFO - Running setup.py install for docopt 08:32:50 INFO - Successfully installed blobuploader requests docopt 08:32:50 INFO - Cleaning up... 08:32:50 INFO - Return code: 0 08:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:50 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:50 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 08:32:50 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 08:32:50 INFO - Using env: {'DISPLAY': ':0', 08:32:50 INFO - 'HOME': '/home/cltbld', 08:32:50 INFO - 'LANG': 'en_US.UTF-8', 08:32:50 INFO - 'LANGUAGE': 'en_US:en', 08:32:50 INFO - 'LOGNAME': 'cltbld', 08:32:50 INFO - 'MAIL': '/var/mail/cltbld', 08:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:50 INFO - 'MOZ_NO_REMOTE': '1', 08:32:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:50 INFO - 'NO_EM_RESTART': '1', 08:32:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:50 INFO - 'PWD': '/builds/slave/test', 08:32:50 INFO - 'SHELL': '/bin/bash', 08:32:50 INFO - 'SHLVL': '1', 08:32:50 INFO - 'TERM': 'linux', 08:32:50 INFO - 'TMOUT': '86400', 08:32:50 INFO - 'USER': 'cltbld', 08:32:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:50 INFO - '_': '/tools/buildbot/bin/python'} 08:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:50 INFO - Running setup.py (path:/tmp/pip-SuoaL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:50 INFO - Running setup.py (path:/tmp/pip-or6Tai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:50 INFO - Running setup.py (path:/tmp/pip-OHqADg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:50 INFO - Running setup.py (path:/tmp/pip-6Yt8JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:50 INFO - Running setup.py (path:/tmp/pip-re26_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:50 INFO - Running setup.py (path:/tmp/pip-jJiEGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:50 INFO - Running setup.py (path:/tmp/pip-GUdbix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:50 INFO - Running setup.py (path:/tmp/pip-hzNTED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:50 INFO - Running setup.py (path:/tmp/pip-KqPjou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:50 INFO - Running setup.py (path:/tmp/pip-481jGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:51 INFO - Running setup.py (path:/tmp/pip-X2lho0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:51 INFO - Running setup.py (path:/tmp/pip-beO2ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:51 INFO - Running setup.py (path:/tmp/pip-Y2bqoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:51 INFO - Running setup.py (path:/tmp/pip-Ka8_5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:51 INFO - Running setup.py (path:/tmp/pip-RBC9ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:51 INFO - Running setup.py (path:/tmp/pip-v2ltoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:51 INFO - Running setup.py (path:/tmp/pip-gRcds3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:51 INFO - Running setup.py install for manifestparser 08:32:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:51 INFO - Running setup.py install for mozcrash 08:32:51 INFO - Running setup.py install for mozdebug 08:32:51 INFO - Running setup.py install for mozdevice 08:32:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:51 INFO - Running setup.py install for mozfile 08:32:52 INFO - Running setup.py install for mozhttpd 08:32:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozinfo 08:32:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozInstall 08:32:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozleak 08:32:52 INFO - Running setup.py install for mozlog 08:32:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for moznetwork 08:32:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozprocess 08:32:52 INFO - Running setup.py install for mozprofile 08:32:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozrunner 08:32:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:52 INFO - Running setup.py install for mozscreenshot 08:32:53 INFO - Running setup.py install for moztest 08:32:53 INFO - Running setup.py install for mozversion 08:32:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:53 INFO - Cleaning up... 08:32:53 INFO - Return code: 0 08:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:53 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:53 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 08:32:53 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 08:32:53 INFO - Using env: {'DISPLAY': ':0', 08:32:53 INFO - 'HOME': '/home/cltbld', 08:32:53 INFO - 'LANG': 'en_US.UTF-8', 08:32:53 INFO - 'LANGUAGE': 'en_US:en', 08:32:53 INFO - 'LOGNAME': 'cltbld', 08:32:53 INFO - 'MAIL': '/var/mail/cltbld', 08:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:53 INFO - 'MOZ_NO_REMOTE': '1', 08:32:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:53 INFO - 'NO_EM_RESTART': '1', 08:32:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:53 INFO - 'PWD': '/builds/slave/test', 08:32:53 INFO - 'SHELL': '/bin/bash', 08:32:53 INFO - 'SHLVL': '1', 08:32:53 INFO - 'TERM': 'linux', 08:32:53 INFO - 'TMOUT': '86400', 08:32:53 INFO - 'USER': 'cltbld', 08:32:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:53 INFO - '_': '/tools/buildbot/bin/python'} 08:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:53 INFO - Running setup.py (path:/tmp/pip-XPfftx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:53 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:53 INFO - Running setup.py (path:/tmp/pip-bLgkZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:53 INFO - Running setup.py (path:/tmp/pip-eMoPYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:53 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:53 INFO - Running setup.py (path:/tmp/pip-x3Gvwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:53 INFO - Running setup.py (path:/tmp/pip-Ui2Q65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:53 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:53 INFO - Running setup.py (path:/tmp/pip-1ga4rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:53 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)) 08:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:53 INFO - Running setup.py (path:/tmp/pip-gKysdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:54 INFO - Running setup.py (path:/tmp/pip-tz1OMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:54 INFO - Running setup.py (path:/tmp/pip-RqPOEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:54 INFO - Running setup.py (path:/tmp/pip-u6AqWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:54 INFO - Running setup.py (path:/tmp/pip-8QaQPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:54 INFO - Running setup.py (path:/tmp/pip-5o2mMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:54 INFO - Running setup.py (path:/tmp/pip-Kc160F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:54 INFO - Running setup.py (path:/tmp/pip-MgY8R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:54 INFO - Running setup.py (path:/tmp/pip-A2JEUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:54 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:54 INFO - Running setup.py (path:/tmp/pip-Z9LIeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:54 INFO - Running setup.py (path:/tmp/pip-TSZjSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:54 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)) 08:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:32:54 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)) 08:32:54 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:32:54 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:32:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:56 INFO - Downloading blessings-1.6.tar.gz 08:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:56 INFO - Installing collected packages: blessings 08:32:56 INFO - Running setup.py install for blessings 08:32:56 INFO - Successfully installed blessings 08:32:56 INFO - Cleaning up... 08:32:57 INFO - Return code: 0 08:32:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:57 INFO - Reading from file tmpfile_stdout 08:32:57 INFO - Current package versions: 08:32:57 INFO - argparse == 1.2.1 08:32:57 INFO - blessings == 1.6 08:32:57 INFO - blobuploader == 1.2.4 08:32:57 INFO - docopt == 0.6.1 08:32:57 INFO - functools32 == 3.2.3-2 08:32:57 INFO - jsonschema == 2.5.1 08:32:57 INFO - manifestparser == 1.1 08:32:57 INFO - mozInstall == 1.12 08:32:57 INFO - mozcrash == 1.0 08:32:57 INFO - mozdebug == 0.1 08:32:57 INFO - mozdevice == 0.49 08:32:57 INFO - mozfile == 1.2 08:32:57 INFO - mozhttpd == 0.7 08:32:57 INFO - mozinfo == 0.9 08:32:57 INFO - mozleak == 0.1 08:32:57 INFO - mozlog == 3.4 08:32:57 INFO - moznetwork == 0.27 08:32:57 INFO - mozprocess == 0.25 08:32:57 INFO - mozprofile == 0.28 08:32:57 INFO - mozrunner == 6.13 08:32:57 INFO - mozscreenshot == 0.1 08:32:57 INFO - mozsystemmonitor == 0.3 08:32:57 INFO - moztest == 0.8 08:32:57 INFO - mozversion == 1.4 08:32:57 INFO - psutil == 3.1.1 08:32:57 INFO - requests == 1.2.3 08:32:57 INFO - wsgiref == 0.1.2 08:32:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:57 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:57 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 08:32:57 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 08:32:57 INFO - Using env: {'DISPLAY': ':0', 08:32:57 INFO - 'HOME': '/home/cltbld', 08:32:57 INFO - 'LANG': 'en_US.UTF-8', 08:32:57 INFO - 'LANGUAGE': 'en_US:en', 08:32:57 INFO - 'LOGNAME': 'cltbld', 08:32:57 INFO - 'MAIL': '/var/mail/cltbld', 08:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:57 INFO - 'MOZ_NO_REMOTE': '1', 08:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:57 INFO - 'NO_EM_RESTART': '1', 08:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:57 INFO - 'PWD': '/builds/slave/test', 08:32:57 INFO - 'SHELL': '/bin/bash', 08:32:57 INFO - 'SHLVL': '1', 08:32:57 INFO - 'TERM': 'linux', 08:32:57 INFO - 'TMOUT': '86400', 08:32:57 INFO - 'USER': 'cltbld', 08:32:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:57 INFO - '_': '/tools/buildbot/bin/python'} 08:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 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)) 08:32:57 INFO - Cleaning up... 08:32:57 INFO - Return code: 0 08:32:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:57 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 0x26b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2953b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, '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': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', '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 08:32:57 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 08:32:57 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 08:32:57 INFO - Using env: {'DISPLAY': ':0', 08:32:57 INFO - 'HOME': '/home/cltbld', 08:32:57 INFO - 'LANG': 'en_US.UTF-8', 08:32:57 INFO - 'LANGUAGE': 'en_US:en', 08:32:57 INFO - 'LOGNAME': 'cltbld', 08:32:57 INFO - 'MAIL': '/var/mail/cltbld', 08:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:57 INFO - 'MOZ_NO_REMOTE': '1', 08:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:57 INFO - 'NO_EM_RESTART': '1', 08:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:57 INFO - 'PWD': '/builds/slave/test', 08:32:57 INFO - 'SHELL': '/bin/bash', 08:32:57 INFO - 'SHLVL': '1', 08:32:57 INFO - 'TERM': 'linux', 08:32:57 INFO - 'TMOUT': '86400', 08:32:57 INFO - 'USER': 'cltbld', 08:32:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:57 INFO - '_': '/tools/buildbot/bin/python'} 08:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:57 INFO - Cleaning up... 08:32:57 INFO - Return code: 0 08:32:57 INFO - Running post-action listener: _resource_record_post_action 08:32:57 INFO - Running post-action listener: _start_resource_monitoring 08:32:57 INFO - Starting resource monitoring. 08:32:57 INFO - [mozharness: 2017-03-30 15:32:57.754570Z] Finished create-virtualenv step (success) 08:32:57 INFO - [mozharness: 2017-03-30 15:32:57.755090Z] Running install step. 08:32:57 INFO - Running pre-action listener: _resource_record_pre_action 08:32:57 INFO - Running main action method: install 08:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:57 INFO - Reading from file tmpfile_stdout 08:32:57 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:58 INFO - Reading from file tmpfile_stdout 08:32:58 INFO - Output received: 08:32:58 INFO - Usage: mozinstall [options] installer 08:32:58 INFO - Options: 08:32:58 INFO - -h, --help show this help message and exit 08:32:58 INFO - -d DEST, --destination=DEST 08:32:58 INFO - Directory to install application into. [default: 08:32:58 INFO - "/builds/slave/test"] 08:32:58 INFO - --app=APP Application being installed. [default: firefox] 08:32:58 INFO - mkdir: /builds/slave/test/build/application 08:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:33:13 INFO - Reading from file tmpfile_stdout 08:33:13 INFO - Output received: 08:33:13 INFO - /builds/slave/test/build/application/firefox/firefox 08:33:13 INFO - Running post-action listener: _resource_record_post_action 08:33:13 INFO - [mozharness: 2017-03-30 15:33:13.727969Z] Finished install step (success) 08:33:13 INFO - [mozharness: 2017-03-30 15:33:13.728306Z] Running run-tests step. 08:33:13 INFO - Running pre-action listener: _resource_record_pre_action 08:33:13 INFO - Running main action method: run_tests 08:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:33:13 INFO - Python 2.7.3 08:33:13 INFO - Return code: 0 08:33:13 INFO - grabbing minidump binary from tooltool 08:33:13 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')]} 08:33:13 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 0x2951290>, '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 0x2951b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2952040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:33:13 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 08:33:13 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 08:33:13 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 08:33:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:33:13 INFO - Return code: 0 08:33:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:33:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:33:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:33:13 INFO - ENV: RUST_BACKTRACE is now 1 08:33:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:33:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:33:13 INFO - Using env: {'DISPLAY': ':0', 08:33:13 INFO - 'HOME': '/home/cltbld', 08:33:13 INFO - 'LANG': 'en_US.UTF-8', 08:33:13 INFO - 'LANGUAGE': 'en_US:en', 08:33:13 INFO - 'LOGNAME': 'cltbld', 08:33:13 INFO - 'MAIL': '/var/mail/cltbld', 08:33:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:33:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:13 INFO - 'MOZ_NO_REMOTE': '1', 08:33:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:33:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:13 INFO - 'NO_EM_RESTART': '1', 08:33:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:13 INFO - 'PWD': '/builds/slave/test', 08:33:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:33:13 INFO - 'RUST_BACKTRACE': '1', 08:33:13 INFO - 'SHELL': '/bin/bash', 08:33:13 INFO - 'SHLVL': '1', 08:33:13 INFO - 'TERM': 'linux', 08:33:13 INFO - 'TMOUT': '86400', 08:33:13 INFO - 'USER': 'cltbld', 08:33:13 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102', 08:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:13 INFO - '_': '/tools/buildbot/bin/python'} 08:33:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:33:14 INFO - mozversion application_buildid: 20170330145930 08:33:14 INFO - mozversion application_changeset: 3364cc17988c013c36f2a8123315db2855393011 08:33:14 INFO - mozversion application_display_name: Nightly 08:33:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:33:14 INFO - mozversion application_name: Firefox 08:33:14 INFO - mozversion application_remotingname: firefox 08:33:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:33:14 INFO - mozversion application_vendor: Mozilla 08:33:14 INFO - mozversion application_version: 55.0a1 08:33:14 INFO - mozversion platform_buildid: 20170330145930 08:33:14 INFO - mozversion platform_changeset: 3364cc17988c013c36f2a8123315db2855393011 08:33:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:33:14 INFO - mozversion platform_version: 55.0a1 08:33:14 INFO - using testdate: 1490887994 08:33:14 INFO - actual date: 1490887994 08:33:14 INFO - starting webserver on 'localhost:53387' 08:33:14 INFO - SUITE-START | Running 2 tests 08:33:14 INFO - TEST-START | dromaeo_css 08:33:14 INFO - operating with platform_type : linux_ 08:33:14 INFO - Initialising browser for dromaeo_css test... 08:33:14 INFO - TEST-INFO | started process 9671 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp39Vroz/profile http://localhost:53387/getInfo.html) 08:33:18 INFO - PID 9671 | __metrics Screen width/height:1600/1200 08:33:18 INFO - PID 9671 | colorDepth:24 08:33:18 INFO - PID 9671 | Browser inner width/height: 1024/697 08:33:18 INFO - PID 9671 | __metrics 08:33:19 ERROR - PID 9671 | 1490887999809 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmp39Vroz/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmp39Vroz/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmp39Vroz/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmp39Vroz/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:33:19 ERROR - PID 9671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 08:33:20 INFO - TEST-INFO | 9671: exit 0 08:33:20 INFO - Browser initialized. 08:33:20 INFO - Running cycle 1/1 for dromaeo_css test... 08:33:20 INFO - TEST-INFO | started process 9803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp39Vroz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:32 INFO - PID 9803 | RSS: Main: 155869184 08:33:32 INFO - PID 9803 | 08:36:37 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53387/tests/dromaeo/cssquery-ext.html) 08:36:39 INFO - PID 9803 | RSS: Main: 165302272 08:36:39 INFO - PID 9803 | 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | A coding exception was thrown and uncaught in a Task. 08:38:21 INFO - PID 9803 | 08:38:21 ERROR - PID 9803 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:38:21 INFO - PID 9803 | Full stack: 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | A coding exception was thrown and uncaught in a Task. 08:38:21 INFO - PID 9803 | 08:38:21 ERROR - PID 9803 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:38:21 INFO - PID 9803 | Full stack: 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | A coding exception was thrown and uncaught in a Task. 08:38:21 INFO - PID 9803 | 08:38:21 ERROR - PID 9803 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:38:21 INFO - PID 9803 | Full stack: 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | ************************* 08:38:21 INFO - PID 9803 | A coding exception was thrown and uncaught in a Task. 08:38:21 INFO - PID 9803 | 08:38:21 ERROR - PID 9803 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:38:21 INFO - PID 9803 | Full stack: 08:38:21 INFO - PID 9803 | ************************* 08:39:41 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-ext.html (next: http://localhost:53387/tests/dromaeo/cssquery-jquery.html) 08:39:42 INFO - PID 9803 | RSS: Main: 161857536 08:39:42 INFO - PID 9803 | 08:42:44 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53387/tests/dromaeo/cssquery-mootools.html) 08:42:45 INFO - PID 9803 | RSS: Main: 169385984 08:42:45 INFO - PID 9803 | 08:45:46 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53387/tests/dromaeo/cssquery-prototype.html) 08:45:47 INFO - PID 9803 | RSS: Main: 161333248 08:45:47 INFO - PID 9803 | 08:48:50 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53387/tests/dromaeo/cssquery-yui.html) 08:48:51 INFO - PID 9803 | RSS: Main: 169197568 08:48:51 INFO - PID 9803 | 08:51:53 INFO - PID 9803 | Cycle 1(1): loaded http://localhost:53387/tests/dromaeo/cssquery-yui.html (next: http://localhost:53387/tests/dromaeo/cssquery-dojo.html) 08:51:54 INFO - PID 9803 | RSS: Main: 162193408 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | __start_tp_report 08:51:54 INFO - PID 9803 | _x_x_mozilla_page_load 08:51:54 INFO - PID 9803 | _x_x_mozilla_page_load_details 08:51:54 INFO - PID 9803 | |i|pagename|runs| 08:51:54 INFO - PID 9803 | |0;dojo.html;2693.973060269397;2696.662917135358;2732.904348347808;2753.3942532642814;2756.421151558173;5153.922691159635;5221.817236396727;5256.999999999995;5289.86775330616;5293.761780719868;5208.322918020662;5232.529072383485;5250.684958902459;5333.333333333333;5370.248165256867;4797.328374027639;4864.464908860023;4866.756662166895;4874.390701162355;4883.853484395474;4871.12319782439;4902.994886048352;4903.852884413455;4922.138625740499;4959.60323174147;4740.597049250818;4773.1169733599045;4797.18447863864;4829.251466022772;4835.709857408531;4772.904541909143;4788.521147885183;4814.614830813526;4833.178359678863;4841.394825646794;4675.13510000651;4715.269133284312;4741.8103275868925;4751.0497900419705;4769.35613692154;5112.565431938253;5162.432132465426;5164.870878228036;5199.896002079975;5253.50091741284;5627.324721033502;5667.914981275284;5689.9146512802345;5721.284839395075;5724.627899186539;5709.200711900337;5716.79991200306;5729.312482502127;5738.569607279471;5740.540756739492;5087.364079489989;5296.814611488579;5308.230306605597;5313.388960269541;5376.4085950545405;5633.380328163898;5727.541796656257;5765.077587585966;5791.276090488689;5821.330546987066;5366.651167674088;5418.674879507243;5421.322334708161;5429.538489228381;5503.449655034545;5659.886802263932;5688.11834165705;5698.259226300637;5701.458361455655;5709.514691251207;5475.4250803665045;5639.520640745583;5649.378568357477;5651.971740141355;5657.462541058593;5560.471755183251;5565.30433695788;5627.774889004476;5681.801136960186;5692.203091567184;4802.27965805132;4808.06242782654;4808.855734327975;4831.879203019953;4856.39295088114;4082.2039702257775;4082.2856000199845;4103.958960410418;4106.938395924063;4147.751134931914;4730.337752714623;4796.25658023007;4812.085703716282;4859.3682821233015;4861.0277944411255;5716.971415142898;5726;5730.169125476783;5766.826995190151;5807.8548036299435;4824.903501929942;4840.903181936342;4851.466338702739;4863.635227357962;4898.53463920927;4961.8511444656715;5010.724410157476;5031.974840125849;5069.163588008011;5092.6435149539175;5242.554382877498;5279.815206467755;5286.682799032071;5329.067413202752;5348.572114230775;5623.606347555632;5651.095824668033;5658.377578466448;5671.6597004179885;5686.516646084963;5144.408393034849;5224.399194092576;5269.077911365573;5294.8941021180135;5337.839864804062;5450.073487507054;5540.916886246629;5586.441355864381;5588;5598.86002849932;5674.631148975303;5677.3471050829685;5704.971475142598;5711.029125048669;5720.542356611378;5365.785368585369;5463.836084917459;5470.671759694431;5526.143447765603;5578.023845827045;5469.644473109235;5496.972515137399;5501.504864562048;5506.366767821592;5534.944650553604;5348.411674715856;5438.184272359178;5439.891202175855;5444.673319600836;5492.038893193755;5386.057439947915;5474.7810087597645;5515.22786809855;5520.66875987442;5566.24855644499;5405.972970135124;5409.972950135224;5431.321084864392;5451.127819548853;5484.396716361117 08:51:54 INFO - PID 9803 | |1;ext.html;619.2290598205235;683.4259222253306;696.8362434827815;1199.3883119608997;1203.8976686981607;36720.08199795003;37308.44037339442;37471.25057498852;37579.06052348693;37593.81203093987;35350.64649353506;35441.46837797435;35630.2873942521;35789.64210357895;35823.820880895626;6891.724331026766;7294.197638259787;7515.887261691079;7516.0980682318195;7531.397488200931;7541.09506859175;7650.846983060335;7654.732084377048;7660.157382687928;7664.118626357985;7663.271989161049;7722.922770772277;7726.111497177841;7737.381009519225;7739.303462688357;7615.200403957588;7696.114946781136;7701.152873183945;7701.268379503938;7715.575643339614;7461.253874612549;7498.962505187494;7511.436642251799;7530.058742657168;7535.359494442979;34485.82757086224;34746;34792.65207347919;34811.303773924636;34867.476987845206;2630.6974697909795;2672.9866350668317;2676.1168814291236;2679.973200267992;2685.731426857318;24077.277681669577;24343.878280608482;24477.436805154965;24519.632205516933;24520.754792452026;2548.311955771931;2565.576679847823;2566.7304932981956;2586.366340246652;2589.9482010359875;153580;154356;154922;161931;164173.358266417;41530.58469415327;41639.583604164174;41679.374809377885;41936.16127677429;42025.78987105045;42804.78597606992;43001.56998430038;43073.13853722908;43176.568234317885;43402.34896476555;12884.355782210852;12986.090973631945;12992.415341309475;13030.609081727564;13097;10578;10605.363678179334;10634.521446534769;10637.255392122632;10704.411257380763;13097.803532947013;13103.672408189681;13103.755143261375;13220.140690855063;13283.20300781937;5452.236686863842;5466.426025267369;5487.259220005249;5524.613277070566;5646.689432081275;822.457178262344;836.7908720976627;846.4582667093065;860.3246451535515;864.8918885139358;20752.896235519027;21382.78617213808;21432.464188395414;21626;22019;931.4830269200659;934.5420743835472;936.2884208001967;962.4369743699874;964.4840010594298;10861.402622855661;11242.7189320266;11292.854357672017;11339.92270734279;11354.311206600705;240582;240946;241045;241435;241632;41998.58001420008;42267.7886610565;42657;42698.57301427008;42761;1214.1015648420282;1253.6427118271067;1265.487477571585;1266.5313833881523;1267.3979859566632;19495.805041949967;19872.60254794925;21041.57916841624;21046.15815367368;21244.57510849805;30861.691383085883;30893.536596950613;31001.534976974912;31029;31038.689613104485;219962;220217;221200;221743;221911;29177.85411073016;30882.69117308798;31162;31196.220094497818;31446.05661830149;36301.86605380851;36962;37517.624823751416;37617.62382376141;38201.42697859479;27795;28152;28314.43371132607;28331;29148.7085129146;28229.57655635126;28235.71764282331;28318.292042699097;29245.268868277613;29537.556936646397;31284.37431251408;31305.37389252248;31338.21654458657;31823.204419889687;31927.807219277885;26495.50552859775;27292.59061114045;27674.308142296602;27976.86011569929;28592.42815143727;21365.78634213638;22626.624204458953;23084.76915230826;23167.6524852124;23172.3048308551 08:51:54 INFO - PID 9803 | |2;jquery.html;411.95262544807355;416.68540252109636;424.68573255790704;429.8474041715191;435.4317615511756;17808;18095.72856407155;18762.906185469055;18823;18955.62088758224;16653.25060372283;16925.153742312898;16936.491905242863;17223.569410764736;17290.654186916254;5691.544676425897;5693.743781529841;5780.0751879699255;5820.825375238728;5865.824025279248;5702.543796496291;5771.82684519463;5777.59556831022;5787.942120578803;5801.883962320751;5581.358143813452;5662.8301150965335;5689;5691.089425691869;5780.306363236417;5737.192784272962;5742.368339482654;5776.797812076599;5780.277465316835;5807.767689292445;5079.161938280179;5226.660267082628;5254.4220135785035;5259.76331065103;5270.39390470097;22062;22625.886870565708;22832.200872969534;22928;23073.884630576908;1534.2252162657899;1549.682315125408;1552.7826104345402;1565.6555557777385;1572.543962250946;13575.253361065137;14481.637959051002;14509.782353264709;14760.717846460619;14990.62523436912;1673.0630846725871;1703.9829601703948;1734.358287433645;1742.3901634427975;1774.1484087638007;87747.56126219392;87962;88419.55790221007;89715;91109;10738.18794328018;10885.346879187116;11147.777044459066;11181.888181118085;11256.268342557707;14918.328675209641;14930;15104.471343503144;15105.773413398807;15125.168115753519;7608.087029556488;7823.217678232131;7862.960685196537;8007.159248278964;8091.352691784643;7337.26627337262;7430.554166750012;7528.5859277739155;7600.923990760132;7624.390048796083;6884.105066341343;6896.93103068973;6911.9308806912295;6983.336583024605;6993.685284162225;2036.582500587376;2037.5619241863071;2065.9070341834654;2068.6586713192155;2242.394553470554;826.7230477789888;842.5744998775579;853.108501615813;866.2117473099449;872.9388942774071;16009.59976000586;16594.170291485378;17087.658246834995;17206;17379;866.8136350684633;868.2229404682773;872.1235158665501;877.4121338703085;880.445319448756;9292.395994260487;9305.302102342352;9504;9568.425894446356;11145.219834611662;446994;447399;452232;458519;458647.2555656076;95189;95787.52106239564;95895;96747.51626241824;96965;1073.978520429587;1110.4114819145866;1130.9999999999836;1133.6032388664066;1136.2841409911869;17524.47426577205;17557.736633950244;18013.459596212135;18099.457016289532;18442.262309508005;44381.39891395768;44976.1004779896;45386;46032.769836150605;46252.306215406126;58248;58795.70602146962;58806.411935880096;59024.40975590189;59676.10485842803;8018.679252830055;8222.095569487472;8245.093039765501;8270.710525131592;8376.581170941306;12658.746825063263;12709.93645031769;12789.168704034208;12866.228026318451;13020.414081366172;9539;9718.396464583264;9832.508374581384;9886.258530610377;10165.614511746595;10153.187744980532;10196.643117490852;10518.421486818299;10531.157507399543;10902.236843421202;10937.343759374464;11126.221164518405;11507.712307192387;11587.130965177477;11632.592859250224;8046.235607617267;8148;8218;8297.336213103057;8451.281641060334;10874.78250434971;10890.564377424815;11060.115190784558;11075;11406.82889756671 08:51:54 INFO - PID 9803 | |3;mootools.html;1228.9262644241346;1259.9496020159188;1268.885800277975;1274.6239859241523;1281.2952875918243;9533;9852.458114803683;9918.900810991889;9938.751531211723;10203.234757393187;9823.607055717763;9984;10023.39859608424;10047.346922450053;10057.899421005788;2189.036823797528;2221.6445368741006;2226.7216597925258;2229.15292188968;2233.0732745910427;2091.592139532792;2182.9126834926665;2200.1309482754305;2202.7246594175726;2209.5359974405396;2140.529083601605;2188.485705859122;2206.8124209442217;2211.215018668374;2226.1763147635397;2178.5316157026236;2182.9126834926583;2185.366243789299;2186.529896072352;2193.7367515898036;2094.8847813370257;2114.481951921789;2170.435686721448;2173.0221400369737;2178.433607262123;9759.804803903882;9849.409035457895;9868.013198680146;9890.258230632731;9893.752656183653;5314.521693047605;5539.446055394468;5540.556755459554;5555.944440555583;5557.41647127054;3198.952015719766;3366.0575038989123;3389.67798059184;3463.0995941055253;3465.8440370183403;974.35692442988;987.8567607696915;1013.0173731480526;1067.0396643021265;1071.464267866067;14095.929520352434;14362.210078445682;14384;14451.205183714892;14497.855021449755;4743.075100355467;4818.542238487338;4945.530174633404;5006.799728010911;5037.571806396424;9819.509024548744;10007.249456290807;10018.048285412875;10099.14157296638;10203.081722644998;5475.698836564027;5496.367917689437;5500.724963751796;5515.420880807537;5527.585431092684;4852.272159176152;4937.308776771255;5009.398872135367;5056.241563765465;5064.518870707277;3778.0932576181517;3871.090293780959;3897.8440862365187;3898.5126859142606;3932.9803350983634;1124.8931351521592;1128.3173679923527;1131.8868113188614;1137.4312843578211;1137.8748337682848;664.5016237821634;680.4862328941617;681.8261343357412;684.9691763870637;688.5524409134002;5322.361316642027;5461.863453413696;5527.889442211133;6027.758889644453;6075.544334174954;811.3893175369628;811.8213992921548;813.7274013205524;833.8624127018974;855.8074433252468;3476.808775517371;3524.1718196223865;3614.0603443104983;3642.1440961374055;3674.669279764834;11655.59205427806;12178.208416452902;12305.753884922431;12310.446029928675;12348.567800126952;12063.457144428521;12594.937025314815;12698.873011269769;12725.61823145307;12740.872591273968;9077.954610226907;9150.267978561698;9261.30540209487;9282.48946307946;9320.580573874191;5347.117725575236;5376.865578360415;5462.945370546244;5571.637843540157;5662.207290979349;11326.603568875049;11372.943135284271;11422.771544568895;11482.655520334403;11640.719520852868;11626.128040397174;11655.88344116548;11708.180427370002;11711.76576468483;11948.880511194777;7713.92286077132;7914.485558438683;7991.440599158003;8009.439339246197;8017.478863873829;8983.550822458721;9004.459732416077;9005.63977440895;9098.317626178195;9138.817223655356;7062.1878483975;7115.395191408788;7127.073480447509;7146.785596432115;7228.132624084933;6839.555428897106;6964.965175174294;7005.929940700527;7014.473914456335;7039.753608623673;6619.933800661932;6736.7642132525125;6742.9662851685425;6744.224414192235;6754.425873800782;4945.653804233669;4966.205407134841;5043.5460808527405;5046.1673823818655;5246.108161612458;5080.0799559548195;5104.923426148536;5137.023965446553;5175.068487672255;5254.632175747814 08:51:54 INFO - PID 9803 | |4;prototype.html;376.9038895081754;397.1877510491046;397.4257198348388;397.50709120690345;397.60636969400264;5296.655717378368;5488.807891723791;5609.439056094394;5634.887302253957;5675.404082571333;5360.892782144365;5464.398916119224;5473.31583552056;5502.80740174094;5554.944450555493;4426.136903303857;4431.512533621315;4436.201483732927;4444.888877778058;4453.265211240157;4351.804168812395;4414.050979039506;4420.292753159495;4433.534478879719;4434.312681534368;4385.013371991308;4390.056137930344;4400.405945197389;4420.1380730757355;4420.58004489573;4400.09797991411;4406.294992801152;4413.646908247332;4420.1159768046355;4421;3495.5106285120105;3996.160806230695;4001.099752555681;4007.83968641254;4012.578679238696;5348.331458567679;5375.43557926416;5381.112116500773;5382.58206039164;5410.972945135289;4833.1058754130245;4848.248521479177;4853.320535125085;4853.805847766085;4858.17411040124;5605.243292155588;5776.768929242825;5803.303603567599;5833.300003999547;5834.5624078194305;4008.178323443716;4010.7192496525254;4010.859619913318;4011.8194681239415;4012;5975.163477113208;6070.2412198475195;6118.908216376803;6159.599626024294;6189.597676151081;4155;4161.10536234705;4169.3120635094865;4179.310413781692;4182.477190351206;4230.725002874803;4247.4265974093105;4256.127493863751;4257.127288905767;4271.337942618899;4793.32893394925;4854.611631069506;4886.828960986349;4890.168671325883;4892.535209155125;5828.125781132864;5829.941700583025;5830;5835.183074369592;5837.445442682938;1460.4085345435117;1462.5228519195477;1463.5463006468028;1463.9048461849945;1468.4273133477952;939.5255396024963;992.3301771304341;993.8409854423257;1000.1198944928561;1010.711947095089;5028.949710502921;5056.6713163644245;5061.670991385548;5062.240663900371;5070.239464080418;6010.819675409744;6039.48664363534;6060.939390606038;6072.726727297282;6083.300420451705;4873.390826146731;5028.622853286018;5057.570106540912;5070.239464080418;5086.338775959176;3210.502372132323;3224.613046434443;3237.1583388318736;3243.1567792374153;3247.6427592964756;5528.668279903219;5636.943630563724;5670.177824215466;5672.234248376416;5687.829365119053;6144.324124346318;6171.876562468726;6197.411245931629;6198.3120043574;6257.812265631947;5632.239647647597;5637.43625637433;5643.726638549631;5660.150977353348;5661.745221465127;5870.121561100407;5933.380637810846;5999.52003839683;6001.639901605918;6077.756889724362;5885.058390657646;5910.940890591039;5949.910751338647;5957.165996760544;5978.4021597839865;5420.457954204706;5597.860053498695;5639.407862174577;5645.633033852787;5657.632253903483;4124.546299906948;4128.422020917134;4134.462519872397;4135.56576559469;4142.5857414258335;5956.910646340221;6027.484851360956;6071.666058366833;6081.6351018939;6083.726232319645;5801.912971305518;5847.649141051551;5860.882782344414;5864.120381942743;5880.206172166874;5775.7400916957995;5802.187693722798;5837.795677151279;5851.736671849862;5864.765409383747;5601.159826026129;5746.069504552792;5764.279465066867;5772.826815195551;5792.94207057924;5906.911396329145;5916.763329466773;5925.348211696796;5926.044405346139;5927.8221653350465;5605.635633683797;5695.515881150149;5720.256366672306;5725.914111288251;5792.5655575832825 08:51:54 INFO - PID 9803 | |5;yui.html;560.5431573267787;588.4086493074459;597.2444857255575;599.8710277290381;613.5551724999378;12200.328981905992;12627;12838.486460541579;12914.225146491217;13261.204327740343;13074.280914549696;13213.339333033358;13377.197368157893;13484.865151348484;13584.660383490418;656.1142457682139;874.2525141004445;905.0587389115312;918.9035151309116;923.8706581078654;917.7338571814166;917.9357444978854;918.0498184379169;919.7700574856286;930.1442672741094;971.208465100944;971.4899677669209;974.3861367338567;976.599594166392;977.3988996766973;942.6700654770843;955.8805149356331;957.0572985609205;960.0735290444754;963.9228861691083;702.1258533126239;702.5538782872861;707.409313223459;711.1430725975239;711.3277952335045;13564.59306220805;13695.589132326046;13870.722585548334;14296.642583935381;14394.640133996629;12281.201721888048;12316.692082697915;12646.141272388424;12781.146733723557;12876.80684789729;8435.114312997108;8451.36614753896;8506.829863402698;8527.530985795778;8558.101399353041;2287.4853158039473;2287.5081857400573;2326.430024643956;2360.7403185649564;2362.055177928825;58974.705126474095;59575.40424595742;59623.70188149075;59858.102128467675;60487;7960.601969901481;7975.641096150571;8061.919380806235;8062.153473885275;8324.458910170819;19454;20508.384748457273;20519.17923283082;20554.28060017892;20570;5824.854378640568;5845.152452894306;5873.2658417697785;5880.617759845682;5943.524518038546;2910.4324656692165;3004.7596192304563;3009.7291243788163;3038;3068.365243735903;3609.5668519777796;3652.579953305351;3671.2840996006057;3690.7785532868115;3703.037210325335;650.9967450162719;652.5921299188008;658.3909883357875;667.956582822115;673.020754801772;466.3052052441837;466.8179410030091;475.9524047595282;498.29988865643105;502.3394236578887;7531.58576278299;7637.465377423527;7690.769276921701;7699.11460182075;8003;450.4751963962013;478.5071376482229;482.9975850120727;501.49599652349156;508.9007941635458;3271.8036917785007;3305.471124620049;3337.5661164049;3378.2567835076243;3412.9658703412647;60546;62742.68628656828;62749;65925;66078.33921660818;53331.46668533343;53469;53507.46492535103;53838;56753;49188.7540562302;49283.26075108876;49776.75111624419;49791.50208497941;50350.24474632883;6765.560238584476;6954.860902782018;6955;7109.466789990668;7117.501774875916;25537.106201282866;25702.871485642452;26161.215163545123;26661.866690666422;26686.466270674868;31723;32133.1966700825;32203.35593288168;32914.17714557155;32919.34161316808;8848.380613357003;8915.699707803884;8969.192772650493;8992.875890513686;9325.207357374698;9314.6274149036;9463.148316651534;9558.56986435626;9766.267529935425;9822.21422286201;8494.32045436376;8605.784855378666;8689.913100868911;8827.646894124164;9048.728538143867;8031.196880311922;8157.510549367057;8316.501009939422;8551.828963420821;8561.058283588925;8128.634211460617;8279.586020699062;8496.660133594834;8612;8612.956935215283;6678.265390807104;6905.585664860125;6974.337437943387;7143.82140446493;7219.205887352492;6719.664016799238;6894.24163342042;6934.653267336714;7036.964815175891;7247.202807691256 08:51:54 INFO - PID 9803 | __end_tp_report 08:51:54 INFO - PID 9803 | __start_cc_report 08:51:54 INFO - PID 9803 | _x_x_mozilla_cycle_collect,390 08:51:54 INFO - PID 9803 | __end_cc_report 08:51:54 INFO - PID 9803 | __startTimestamp1490889114031__endTimestamp 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | ------- Summary: start ------- 08:51:54 INFO - PID 9803 | Number of tests: 6 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#0] dojo.html Cycles:165 Average:5197.31 Median:5348.41 stddev:586.95 (11.0%) stddev-sans-first:554.92 08:51:54 INFO - PID 9803 | Values: 2694.0 2696.7 2732.9 2753.4 2756.4 5153.9 5221.8 5257.0 5289.9 5293.8 5208.3 5232.5 5250.7 5333.3 5370.2 4797.3 4864.5 4866.8 4874.4 4883.9 4871.1 4903.0 4903.9 4922.1 4959.6 4740.6 4773.1 4797.2 4829.3 4835.7 4772.9 4788.5 4814.6 4833.2 4841.4 4675.1 4715.3 4741.8 4751.0 4769.4 5112.6 5162.4 5164.9 5199.9 5253.5 5627.3 5667.9 5689.9 5721.3 5724.6 5709.2 5716.8 5729.3 5738.6 5740.5 5087.4 5296.8 5308.2 5313.4 5376.4 5633.4 5727.5 5765.1 5791.3 5821.3 5366.7 5418.7 5421.3 5429.5 5503.4 5659.9 5688.1 5698.3 5701.5 5709.5 5475.4 5639.5 5649.4 5652.0 5657.5 5560.5 5565.3 5627.8 5681.8 5692.2 4802.3 4808.1 4808.9 4831.9 4856.4 4082.2 4082.3 4104.0 4106.9 4147.8 4730.3 4796.3 4812.1 4859.4 4861.0 5717.0 5726.0 5730.2 5766.8 5807.9 4824.9 4840.9 4851.5 4863.6 4898.5 4961.9 5010.7 5032.0 5069.2 5092.6 5242.6 5279.8 5286.7 5329.1 5348.6 5623.6 5651.1 5658.4 5671.7 5686.5 5144.4 5224.4 5269.1 5294.9 5337.8 5450.1 5540.9 5586.4 5588.0 5598.9 5674.6 5677.3 5705.0 5711.0 5720.5 5365.8 5463.8 5470.7 5526.1 5578.0 5469.6 5497.0 5501.5 5506.4 5534.9 5348.4 5438.2 5439.9 5444.7 5492.0 5386.1 5474.8 5515.2 5520.7 5566.2 5406.0 5410.0 5431.3 5451.1 5484.4 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#1] ext.html Cycles:180 Average:34995.63 Median:30947.54 stddev:54589.50 (176.4%) stddev-sans-first:54681.62 08:51:54 INFO - PID 9803 | Values: 619.2 683.4 696.8 1199.4 1203.9 36720.1 37308.4 37471.3 37579.1 37593.8 35350.6 35441.5 35630.3 35789.6 35823.8 6891.7 7294.2 7515.9 7516.1 7531.4 7541.1 7650.8 7654.7 7660.2 7664.1 7663.3 7722.9 7726.1 7737.4 7739.3 7615.2 7696.1 7701.2 7701.3 7715.6 7461.3 7499.0 7511.4 7530.1 7535.4 34485.8 34746.0 34792.7 34811.3 34867.5 2630.7 2673.0 2676.1 2680.0 2685.7 24077.3 24343.9 24477.4 24519.6 24520.8 2548.3 2565.6 2566.7 2586.4 2589.9 153580.0 154356.0 154922.0 161931.0 164173.4 41530.6 41639.6 41679.4 41936.2 42025.8 42804.8 43001.6 43073.1 43176.6 43402.3 12884.4 12986.1 12992.4 13030.6 13097.0 10578.0 10605.4 10634.5 10637.3 10704.4 13097.8 13103.7 13103.8 13220.1 13283.2 5452.2 5466.4 5487.3 5524.6 5646.7 822.5 836.8 846.5 860.3 864.9 20752.9 21382.8 21432.5 21626.0 22019.0 931.5 934.5 936.3 962.4 964.5 10861.4 11242.7 11292.9 11339.9 11354.3 240582.0 240946.0 241045.0 241435.0 241632.0 41998.6 42267.8 42657.0 42698.6 42761.0 1214.1 1253.6 1265.5 1266.5 1267.4 19495.8 19872.6 21041.6 21046.2 21244.6 30861.7 30893.5 31001.5 31029.0 31038.7 219962.0 220217.0 221200.0 221743.0 221911.0 29177.9 30882.7 31162.0 31196.2 31446.1 36301.9 36962.0 37517.6 37617.6 38201.4 27795.0 28152.0 28314.4 28331.0 29148.7 28229.6 28235.7 28318.3 29245.3 29537.6 31284.4 31305.4 31338.2 31823.2 31927.8 26495.5 27292.6 27674.3 27976.9 28592.4 21365.8 22626.6 23084.8 23167.7 23172.3 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#2] jquery.html Cycles:180 Average:28239.04 Median:248604.05 stddev:75209.66 (30.3%) stddev-sans-first:75391.62 08:51:54 INFO - PID 9803 | Values: 412.0 416.7 424.7 429.8 435.4 17808.0 18095.7 18762.9 18823.0 18955.6 16653.3 16925.2 16936.5 17223.6 17290.7 5691.5 5693.7 5780.1 5820.8 5865.8 5702.5 5771.8 5777.6 5787.9 5801.9 5581.4 5662.8 5689.0 5691.1 5780.3 5737.2 5742.4 5776.8 5780.3 5807.8 5079.2 5226.7 5254.4 5259.8 5270.4 22062.0 22625.9 22832.2 22928.0 23073.9 1534.2 1549.7 1552.8 1565.7 1572.5 13575.3 14481.6 14509.8 14760.7 14990.6 1673.1 1704.0 1734.4 1742.4 1774.1 87747.6 87962.0 88419.6 89715.0 91109.0 10738.2 10885.3 11147.8 11181.9 11256.3 14918.3 14930.0 15104.5 15105.8 15125.2 7608.1 7823.2 7863.0 8007.2 8091.4 7337.3 7430.6 7528.6 7600.9 7624.4 6884.1 6896.9 6911.9 6983.3 6993.7 2036.6 2037.6 2065.9 2068.7 2242.4 826.7 842.6 853.1 866.2 872.9 16009.6 16594.2 17087.7 17206.0 17379.0 866.8 868.2 872.1 877.4 880.4 9292.4 9305.3 9504.0 9568.4 11145.2 446994.0 447399.0 452232.0 458519.0 458647.3 95189.0 95787.5 95895.0 96747.5 96965.0 1074.0 1110.4 1131.0 1133.6 1136.3 17524.5 17557.7 18013.5 18099.5 18442.3 44381.4 44976.1 45386.0 46032.8 46252.3 58248.0 58795.7 58806.4 59024.4 59676.1 8018.7 8222.1 8245.1 8270.7 8376.6 12658.7 12709.9 12789.2 12866.2 13020.4 9539.0 9718.4 9832.5 9886.3 10165.6 10153.2 10196.6 10518.4 10531.2 10902.2 10937.3 11126.2 11507.7 11587.1 11632.6 8046.2 8148.0 8218.0 8297.3 8451.3 10874.8 10890.6 11060.1 11075.0 11406.8 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#3] mootools.html Cycles:180 Average:6055.39 Median:4945.59 stddev:3821.04 (77.3%) stddev-sans-first:3814.55 08:51:54 INFO - PID 9803 | Values: 1228.9 1259.9 1268.9 1274.6 1281.3 9533.0 9852.5 9918.9 9938.8 10203.2 9823.6 9984.0 10023.4 10047.3 10057.9 2189.0 2221.6 2226.7 2229.2 2233.1 2091.6 2182.9 2200.1 2202.7 2209.5 2140.5 2188.5 2206.8 2211.2 2226.2 2178.5 2182.9 2185.4 2186.5 2193.7 2094.9 2114.5 2170.4 2173.0 2178.4 9759.8 9849.4 9868.0 9890.3 9893.8 5314.5 5539.4 5540.6 5555.9 5557.4 3199.0 3366.1 3389.7 3463.1 3465.8 974.4 987.9 1013.0 1067.0 1071.5 14095.9 14362.2 14384.0 14451.2 14497.9 4743.1 4818.5 4945.5 5006.8 5037.6 9819.5 10007.2 10018.0 10099.1 10203.1 5475.7 5496.4 5500.7 5515.4 5527.6 4852.3 4937.3 5009.4 5056.2 5064.5 3778.1 3871.1 3897.8 3898.5 3933.0 1124.9 1128.3 1131.9 1137.4 1137.9 664.5 680.5 681.8 685.0 688.6 5322.4 5461.9 5527.9 6027.8 6075.5 811.4 811.8 813.7 833.9 855.8 3476.8 3524.2 3614.1 3642.1 3674.7 11655.6 12178.2 12305.8 12310.4 12348.6 12063.5 12594.9 12698.9 12725.6 12740.9 9078.0 9150.3 9261.3 9282.5 9320.6 5347.1 5376.9 5462.9 5571.6 5662.2 11326.6 11372.9 11422.8 11482.7 11640.7 11626.1 11655.9 11708.2 11711.8 11948.9 7713.9 7914.5 7991.4 8009.4 8017.5 8983.6 9004.5 9005.6 9098.3 9138.8 7062.2 7115.4 7127.1 7146.8 7228.1 6839.6 6965.0 7005.9 7014.5 7039.8 6619.9 6736.8 6743.0 6744.2 6754.4 4945.7 4966.2 5043.5 5046.2 5246.1 5080.1 5104.9 5137.0 5175.1 5254.6 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#4] prototype.html Cycles:180 Average:4839.55 Median:5468.86 stddev:1406.58 (25.7%) stddev-sans-first:1370.06 08:51:54 INFO - PID 9803 | Values: 376.9 397.2 397.4 397.5 397.6 5296.7 5488.8 5609.4 5634.9 5675.4 5360.9 5464.4 5473.3 5502.8 5554.9 4426.1 4431.5 4436.2 4444.9 4453.3 4351.8 4414.1 4420.3 4433.5 4434.3 4385.0 4390.1 4400.4 4420.1 4420.6 4400.1 4406.3 4413.6 4420.1 4421.0 3495.5 3996.2 4001.1 4007.8 4012.6 5348.3 5375.4 5381.1 5382.6 5411.0 4833.1 4848.2 4853.3 4853.8 4858.2 5605.2 5776.8 5803.3 5833.3 5834.6 4008.2 4010.7 4010.9 4011.8 4012.0 5975.2 6070.2 6118.9 6159.6 6189.6 4155.0 4161.1 4169.3 4179.3 4182.5 4230.7 4247.4 4256.1 4257.1 4271.3 4793.3 4854.6 4886.8 4890.2 4892.5 5828.1 5829.9 5830.0 5835.2 5837.4 1460.4 1462.5 1463.5 1463.9 1468.4 939.5 992.3 993.8 1000.1 1010.7 5028.9 5056.7 5061.7 5062.2 5070.2 6010.8 6039.5 6060.9 6072.7 6083.3 4873.4 5028.6 5057.6 5070.2 5086.3 3210.5 3224.6 3237.2 3243.2 3247.6 5528.7 5636.9 5670.2 5672.2 5687.8 6144.3 6171.9 6197.4 6198.3 6257.8 5632.2 5637.4 5643.7 5660.2 5661.7 5870.1 5933.4 5999.5 6001.6 6077.8 5885.1 5910.9 5949.9 5957.2 5978.4 5420.5 5597.9 5639.4 5645.6 5657.6 4124.5 4128.4 4134.5 4135.6 4142.6 5956.9 6027.5 6071.7 6081.6 6083.7 5801.9 5847.6 5860.9 5864.1 5880.2 5775.7 5802.2 5837.8 5851.7 5864.8 5601.2 5746.1 5764.3 5772.8 5792.9 5906.9 5916.8 5925.3 5926.0 5927.8 5605.6 5695.5 5720.3 5725.9 5792.6 08:51:54 INFO - PID 9803 | 08:51:54 INFO - PID 9803 | [#5] yui.html Cycles:180 Average:13133.82 Median:31678.12 stddev:17095.45 (54.0%) stddev-sans-first:17117.34 08:51:54 INFO - PID 9803 | Values: 560.5 588.4 597.2 599.9 613.6 12200.3 12627.0 12838.5 12914.2 13261.2 13074.3 13213.3 13377.2 13484.9 13584.7 656.1 874.3 905.1 918.9 923.9 917.7 917.9 918.0 919.8 930.1 971.2 971.5 974.4 976.6 977.4 942.7 955.9 957.1 960.1 963.9 702.1 702.6 707.4 711.1 711.3 13564.6 13695.6 13870.7 14296.6 14394.6 12281.2 12316.7 12646.1 12781.1 12876.8 8435.1 8451.4 8506.8 8527.5 8558.1 2287.5 2287.5 2326.4 2360.7 2362.1 58974.7 59575.4 59623.7 59858.1 60487.0 7960.6 7975.6 8061.9 8062.2 8324.5 19454.0 20508.4 20519.2 20554.3 20570.0 5824.9 5845.2 5873.3 5880.6 5943.5 2910.4 3004.8 3009.7 3038.0 3068.4 3609.6 3652.6 3671.3 3690.8 3703.0 651.0 652.6 658.4 668.0 673.0 466.3 466.8 476.0 498.3 502.3 7531.6 7637.5 7690.8 7699.1 8003.0 450.5 478.5 483.0 501.5 508.9 3271.8 3305.5 3337.6 3378.3 3413.0 60546.0 62742.7 62749.0 65925.0 66078.3 53331.5 53469.0 53507.5 53838.0 56753.0 49188.8 49283.3 49776.8 49791.5 50350.2 6765.6 6954.9 6955.0 7109.5 7117.5 25537.1 25702.9 26161.2 26661.9 26686.5 31723.0 32133.2 32203.4 32914.2 32919.3 8848.4 8915.7 8969.2 8992.9 9325.2 9314.6 9463.1 9558.6 9766.3 9822.2 8494.3 8605.8 8689.9 8827.6 9048.7 8031.2 8157.5 8316.5 8551.8 8561.1 8128.6 8279.6 8496.7 8612.0 8613.0 6678.3 6905.6 6974.3 7143.8 7219.2 6719.7 6894.2 6934.7 7037.0 7247.2 08:51:54 INFO - PID 9803 | -------- Summary: end -------- 08:51:54 INFO - PID 9803 | 08:51:54 INFO - TEST-INFO | 9803: exit 0 08:51:54 INFO - TEST-OK | dromaeo_css | took 1120904ms 08:51:54 INFO - TEST-START | kraken 08:51:54 INFO - operating with platform_type : linux_ 08:51:54 INFO - Initialising browser for kraken test... 08:51:54 INFO - TEST-INFO | started process 10181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPLl4ZI/profile http://localhost:53387/getInfo.html) 08:51:59 INFO - PID 10181 | __metrics Screen width/height:1600/1200 08:51:59 INFO - PID 10181 | colorDepth:24 08:51:59 INFO - PID 10181 | Browser inner width/height: 1024/697 08:51:59 INFO - PID 10181 | __metrics 08:52:00 ERROR - PID 10181 | 1490889120323 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmpPLl4ZI/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpPLl4ZI/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpPLl4ZI/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpPLl4ZI/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:52:00 ERROR - PID 10181 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 08:52:01 INFO - TEST-INFO | 10181: exit 0 08:52:01 INFO - Browser initialized. 08:52:01 INFO - Running cycle 1/1 for kraken test... 08:52:01 INFO - TEST-INFO | started process 10316 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPLl4ZI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:13 INFO - PID 10316 | RSS: Main: 156250112 08:52:13 INFO - PID 10316 | 08:52:16 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53387/tests/kraken/driver.html?testName=audio-beat-detection) 08:52:17 INFO - PID 10316 | RSS: Main: 162201600 08:52:17 INFO - PID 10316 | 08:52:19 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53387/tests/kraken/driver.html?testName=audio-dft) 08:52:20 INFO - PID 10316 | RSS: Main: 161677312 08:52:20 INFO - PID 10316 | 08:52:23 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53387/tests/kraken/driver.html?testName=audio-fft) 08:52:24 INFO - PID 10316 | RSS: Main: 158269440 08:52:24 INFO - PID 10316 | 08:52:26 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53387/tests/kraken/driver.html?testName=audio-oscillator) 08:52:26 INFO - PID 10316 | RSS: Main: 158871552 08:52:26 INFO - PID 10316 | 08:52:29 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53387/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:29 INFO - PID 10316 | RSS: Main: 159252480 08:52:29 INFO - PID 10316 | 08:52:32 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53387/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:33 INFO - PID 10316 | RSS: Main: 164757504 08:52:33 INFO - PID 10316 | 08:52:37 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53387/tests/kraken/driver.html?testName=imaging-desaturate) 08:52:37 INFO - PID 10316 | RSS: Main: 162410496 08:52:37 INFO - PID 10316 | 08:52:40 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53387/tests/kraken/driver.html?testName=json-parse-financial) 08:52:41 INFO - PID 10316 | RSS: Main: 162856960 08:52:41 INFO - PID 10316 | 08:52:43 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53387/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:43 INFO - PID 10316 | RSS: Main: 163565568 08:52:43 INFO - PID 10316 | 08:52:45 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:46 INFO - PID 10316 | RSS: Main: 163323904 08:52:46 INFO - PID 10316 | 08:52:48 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:49 INFO - PID 10316 | RSS: Main: 163708928 08:52:49 INFO - PID 10316 | 08:52:51 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:52 INFO - PID 10316 | RSS: Main: 163958784 08:52:52 INFO - PID 10316 | 08:52:55 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:56 INFO - PID 10316 | RSS: Main: 164093952 08:52:56 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | Cycle 1(1): loaded http://localhost:53387/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53387/tests/kraken/driver.html?testName=ai-astar) 08:52:58 INFO - PID 10316 | RSS: Main: 165699584 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | __start_tp_report 08:52:58 INFO - PID 10316 | _x_x_mozilla_page_load 08:52:58 INFO - PID 10316 | _x_x_mozilla_page_load_details 08:52:58 INFO - PID 10316 | |i|pagename|runs| 08:52:58 INFO - PID 10316 | |0;ai-astar;103;111;112;112;111;114;112;111;111;112 08:52:58 INFO - PID 10316 | |1;audio-beat-detection;154;126;132;166;124;128;125;125;148;131 08:52:58 INFO - PID 10316 | |2;audio-dft;167;175;168;168;171;166;171;164;165;165 08:52:58 INFO - PID 10316 | |3;audio-fft;88;75;75;112;76;76;77;76;93;80 08:52:58 INFO - PID 10316 | |4;audio-oscillator;85;84;107;85;84;84;107;86;84;116 08:52:58 INFO - PID 10316 | |5;imaging-gaussian-blur;115;114;116;115;114;120;115;116;115;114 08:52:58 INFO - PID 10316 | |6;imaging-darkroom;152;152;151;151;151;151;150;162;152;150 08:52:58 INFO - PID 10316 | |7;imaging-desaturate;95;95;94;95;104;104;94;95;95;95 08:52:58 INFO - PID 10316 | |8;json-parse-financial;67;68;67;67;69;69;69;67;71;68 08:52:58 INFO - PID 10316 | |9;json-stringify-tinderbox;55;56;57;56;55;56;56;56;56;56 08:52:58 INFO - PID 10316 | |10;stanford-crypto-aes;83;91;83;84;85;83;83;83;86;84 08:52:58 INFO - PID 10316 | |11;stanford-crypto-ccm;135;146;140;154;135;134;150;145;194;141 08:52:58 INFO - PID 10316 | |12;stanford-crypto-pbkdf2;192;196;195;198;189;206;199;192;195;198 08:52:58 INFO - PID 10316 | |13;stanford-crypto-sha256-iterative;71;73;70;70;74;71;76;79;69;74 08:52:58 INFO - PID 10316 | __end_tp_report 08:52:58 INFO - PID 10316 | __start_cc_report 08:52:58 INFO - PID 10316 | _x_x_mozilla_cycle_collect,883 08:52:58 INFO - PID 10316 | __end_cc_report 08:52:58 INFO - PID 10316 | __startTimestamp1490889178716__endTimestamp 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | ------- Summary: start ------- 08:52:58 INFO - PID 10316 | Number of tests: 14 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.92 (2.6%) stddev-sans-first:0.97 08:52:58 INFO - PID 10316 | Values: 103.0 111.0 112.0 112.0 111.0 114.0 112.0 111.0 111.0 112.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:131.50 stddev:14.75 (11.2%) stddev-sans-first:14.12 08:52:58 INFO - PID 10316 | Values: 154.0 126.0 132.0 166.0 124.0 128.0 125.0 125.0 148.0 131.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#2] audio-dft Cycles:10 Average:168.00 Median:168.00 stddev:3.43 (2.0%) stddev-sans-first:3.62 08:52:58 INFO - PID 10316 | Values: 167.0 175.0 168.0 168.0 171.0 166.0 171.0 164.0 165.0 165.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.50 stddev:11.95 (15.6%) stddev-sans-first:12.53 08:52:58 INFO - PID 10316 | Values: 88.0 75.0 75.0 112.0 76.0 76.0 77.0 76.0 93.0 80.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:84.00 stddev:12.54 (14.9%) stddev-sans-first:13.03 08:52:58 INFO - PID 10316 | Values: 85.0 84.0 107.0 85.0 84.0 84.0 107.0 86.0 84.0 116.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.00 stddev:1.78 (1.5%) stddev-sans-first:1.88 08:52:58 INFO - PID 10316 | Values: 115.0 114.0 116.0 115.0 114.0 120.0 115.0 116.0 115.0 114.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#6] imaging-darkroom Cycles:10 Average:152.20 Median:151.50 stddev:3.52 (2.3%) stddev-sans-first:3.73 08:52:58 INFO - PID 10316 | Values: 152.0 152.0 151.0 151.0 151.0 151.0 150.0 162.0 152.0 150.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:95.00 stddev:3.92 (4.1%) stddev-sans-first:4.12 08:52:58 INFO - PID 10316 | Values: 95.0 95.0 94.0 95.0 104.0 104.0 94.0 95.0 95.0 95.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:68.50 stddev:1.32 (1.9%) stddev-sans-first:1.32 08:52:58 INFO - PID 10316 | Values: 67.0 68.0 67.0 67.0 69.0 69.0 69.0 67.0 71.0 68.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:56.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 08:52:58 INFO - PID 10316 | Values: 55.0 56.0 57.0 56.0 55.0 56.0 56.0 56.0 56.0 56.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.00 stddev:2.51 (3.0%) stddev-sans-first:2.60 08:52:58 INFO - PID 10316 | Values: 83.0 91.0 83.0 84.0 85.0 83.0 83.0 83.0 86.0 84.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:145.50 stddev:17.68 (12.1%) stddev-sans-first:18.17 08:52:58 INFO - PID 10316 | Values: 135.0 146.0 140.0 154.0 135.0 134.0 150.0 145.0 194.0 141.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.00 Median:197.00 stddev:4.71 (2.4%) stddev-sans-first:4.77 08:52:58 INFO - PID 10316 | Values: 192.0 196.0 195.0 198.0 189.0 206.0 199.0 192.0 195.0 198.0 08:52:58 INFO - PID 10316 | 08:52:58 INFO - PID 10316 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:73.50 stddev:3.13 (4.3%) stddev-sans-first:3.26 08:52:58 INFO - PID 10316 | Values: 71.0 73.0 70.0 70.0 74.0 71.0 76.0 79.0 69.0 74.0 08:52:58 INFO - PID 10316 | -------- Summary: end -------- 08:52:58 INFO - PID 10316 | 08:52:59 INFO - TEST-INFO | 10316: exit 0 08:52:59 INFO - TEST-OK | kraken | took 65012ms 08:52:59 INFO - SUITE-END | took 1185s 08:53:00 INFO - Completed test suite (00:19:46) 08:53:00 INFO - javascript benchmark 08:53:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6577.569622755558, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2693.973060269397, 2696.662917135358, 2732.904348347808, 2753.3942532642814, 2756.421151558173, 5153.922691159635, 5221.817236396727, 5256.999999999995, 5289.86775330616, 5293.761780719868, 5208.322918020662, 5232.529072383485, 5250.684958902459, 5333.333333333333, 5370.248165256867, 4797.328374027639, 4864.464908860023, 4866.756662166895, 4874.390701162355, 4883.853484395474, 4871.12319782439, 4902.994886048352, 4903.852884413455, 4922.138625740499, 4959.60323174147, 4740.597049250818, 4773.1169733599045, 4797.18447863864, 4829.251466022772, 4835.709857408531, 4772.904541909143, 4788.521147885183, 4814.614830813526, 4833.178359678863, 4841.394825646794, 4675.13510000651, 4715.269133284312, 4741.8103275868925, 4751.0497900419705, 4769.35613692154, 5112.565431938253, 5162.432132465426, 5164.870878228036, 5199.896002079975, 5253.50091741284, 5627.324721033502, 5667.914981275284, 5689.9146512802345, 5721.284839395075, 5724.627899186539, 5709.200711900337, 5716.79991200306, 5729.312482502127, 5738.569607279471, 5740.540756739492, 5087.364079489989, 5296.814611488579, 5308.230306605597, 5313.388960269541, 5376.4085950545405, 5633.380328163898, 5727.541796656257, 5765.077587585966, 5791.276090488689, 5821.330546987066, 5366.651167674088, 5418.674879507243, 5421.322334708161, 5429.538489228381, 5503.449655034545, 5659.886802263932, 5688.11834165705, 5698.259226300637, 5701.458361455655, 5709.514691251207, 5475.4250803665045, 5639.520640745583, 5649.378568357477, 5651.971740141355, 5657.462541058593, 5560.471755183251, 5565.30433695788, 5627.774889004476, 5681.801136960186, 5692.203091567184, 4802.27965805132, 4808.06242782654, 4808.855734327975, 4831.879203019953, 4856.39295088114, 4082.2039702257775, 4082.2856000199845, 4103.958960410418, 4106.938395924063, 4147.751134931914, 4730.337752714623, 4796.25658023007, 4812.085703716282, 4859.3682821233015, 4861.0277944411255, 5716.971415142898, 5726.0, 5730.169125476783, 5766.826995190151, 5807.8548036299435, 4824.903501929942, 4840.903181936342, 4851.466338702739, 4863.635227357962, 4898.53463920927, 4961.8511444656715, 5010.724410157476, 5031.974840125849, 5069.163588008011, 5092.6435149539175, 5242.554382877498, 5279.815206467755, 5286.682799032071, 5329.067413202752, 5348.572114230775, 5623.606347555632, 5651.095824668033, 5658.377578466448, 5671.6597004179885, 5686.516646084963, 5144.408393034849, 5224.399194092576, 5269.077911365573, 5294.8941021180135, 5337.839864804062, 5450.073487507054, 5540.916886246629, 5586.441355864381, 5588.0, 5598.86002849932, 5674.631148975303, 5677.3471050829685, 5704.971475142598, 5711.029125048669, 5720.542356611378, 5365.785368585369, 5463.836084917459, 5470.671759694431, 5526.143447765603, 5578.023845827045, 5469.644473109235, 5496.972515137399, 5501.504864562048, 5506.366767821592, 5534.944650553604, 5348.411674715856, 5438.184272359178, 5439.891202175855, 5444.673319600836, 5492.038893193755, 5386.057439947915, 5474.7810087597645, 5515.22786809855, 5520.66875987442, 5566.24855644499, 5405.972970135124, 5409.972950135224, 5431.321084864392, 5451.127819548853, 5484.396716361117], "value": 5155.5004060388455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.2290598205235, 683.4259222253306, 696.8362434827815, 1199.3883119608997, 1203.8976686981607, 36720.08199795003, 37308.44037339442, 37471.25057498852, 37579.06052348693, 37593.81203093987, 35350.64649353506, 35441.46837797435, 35630.2873942521, 35789.64210357895, 35823.820880895626, 6891.724331026766, 7294.197638259787, 7515.887261691079, 7516.0980682318195, 7531.397488200931, 7541.09506859175, 7650.846983060335, 7654.732084377048, 7660.157382687928, 7664.118626357985, 7663.271989161049, 7722.922770772277, 7726.111497177841, 7737.381009519225, 7739.303462688357, 7615.200403957588, 7696.114946781136, 7701.152873183945, 7701.268379503938, 7715.575643339614, 7461.253874612549, 7498.962505187494, 7511.436642251799, 7530.058742657168, 7535.359494442979, 34485.82757086224, 34746.0, 34792.65207347919, 34811.303773924636, 34867.476987845206, 2630.6974697909795, 2672.9866350668317, 2676.1168814291236, 2679.973200267992, 2685.731426857318, 24077.277681669577, 24343.878280608482, 24477.436805154965, 24519.632205516933, 24520.754792452026, 2548.311955771931, 2565.576679847823, 2566.7304932981956, 2586.366340246652, 2589.9482010359875, 153580.0, 154356.0, 154922.0, 161931.0, 164173.358266417, 41530.58469415327, 41639.583604164174, 41679.374809377885, 41936.16127677429, 42025.78987105045, 42804.78597606992, 43001.56998430038, 43073.13853722908, 43176.568234317885, 43402.34896476555, 12884.355782210852, 12986.090973631945, 12992.415341309475, 13030.609081727564, 13097.0, 10578.0, 10605.363678179334, 10634.521446534769, 10637.255392122632, 10704.411257380763, 13097.803532947013, 13103.672408189681, 13103.755143261375, 13220.140690855063, 13283.20300781937, 5452.236686863842, 5466.426025267369, 5487.259220005249, 5524.613277070566, 5646.689432081275, 822.457178262344, 836.7908720976627, 846.4582667093065, 860.3246451535515, 864.8918885139358, 20752.896235519027, 21382.78617213808, 21432.464188395414, 21626.0, 22019.0, 931.4830269200659, 934.5420743835472, 936.2884208001967, 962.4369743699874, 964.4840010594298, 10861.402622855661, 11242.7189320266, 11292.854357672017, 11339.92270734279, 11354.311206600705, 240582.0, 240946.0, 241045.0, 241435.0, 241632.0, 41998.58001420008, 42267.7886610565, 42657.0, 42698.57301427008, 42761.0, 1214.1015648420282, 1253.6427118271067, 1265.487477571585, 1266.5313833881523, 1267.3979859566632, 19495.805041949967, 19872.60254794925, 21041.57916841624, 21046.15815367368, 21244.57510849805, 30861.691383085883, 30893.536596950613, 31001.534976974912, 31029.0, 31038.689613104485, 219962.0, 220217.0, 221200.0, 221743.0, 221911.0, 29177.85411073016, 30882.69117308798, 31162.0, 31196.220094497818, 31446.05661830149, 36301.86605380851, 36962.0, 37517.624823751416, 37617.62382376141, 38201.42697859479, 27795.0, 28152.0, 28314.43371132607, 28331.0, 29148.7085129146, 28229.57655635126, 28235.71764282331, 28318.292042699097, 29245.268868277613, 29537.556936646397, 31284.37431251408, 31305.37389252248, 31338.21654458657, 31823.204419889687, 31927.807219277885, 26495.50552859775, 27292.59061114045, 27674.308142296602, 27976.86011569929, 28592.42815143727, 21365.78634213638, 22626.624204458953, 23084.76915230826, 23167.6524852124, 23172.3048308551], "value": 15149.458067930416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.95262544807355, 416.68540252109636, 424.68573255790704, 429.8474041715191, 435.4317615511756, 17808.0, 18095.72856407155, 18762.906185469055, 18823.0, 18955.62088758224, 16653.25060372283, 16925.153742312898, 16936.491905242863, 17223.569410764736, 17290.654186916254, 5691.544676425897, 5693.743781529841, 5780.0751879699255, 5820.825375238728, 5865.824025279248, 5702.543796496291, 5771.82684519463, 5777.59556831022, 5787.942120578803, 5801.883962320751, 5581.358143813452, 5662.8301150965335, 5689.0, 5691.089425691869, 5780.306363236417, 5737.192784272962, 5742.368339482654, 5776.797812076599, 5780.277465316835, 5807.767689292445, 5079.161938280179, 5226.660267082628, 5254.4220135785035, 5259.76331065103, 5270.39390470097, 22062.0, 22625.886870565708, 22832.200872969534, 22928.0, 23073.884630576908, 1534.2252162657899, 1549.682315125408, 1552.7826104345402, 1565.6555557777385, 1572.543962250946, 13575.253361065137, 14481.637959051002, 14509.782353264709, 14760.717846460619, 14990.62523436912, 1673.0630846725871, 1703.9829601703948, 1734.358287433645, 1742.3901634427975, 1774.1484087638007, 87747.56126219392, 87962.0, 88419.55790221007, 89715.0, 91109.0, 10738.18794328018, 10885.346879187116, 11147.777044459066, 11181.888181118085, 11256.268342557707, 14918.328675209641, 14930.0, 15104.471343503144, 15105.773413398807, 15125.168115753519, 7608.087029556488, 7823.217678232131, 7862.960685196537, 8007.159248278964, 8091.352691784643, 7337.26627337262, 7430.554166750012, 7528.5859277739155, 7600.923990760132, 7624.390048796083, 6884.105066341343, 6896.93103068973, 6911.9308806912295, 6983.336583024605, 6993.685284162225, 2036.582500587376, 2037.5619241863071, 2065.9070341834654, 2068.6586713192155, 2242.394553470554, 826.7230477789888, 842.5744998775579, 853.108501615813, 866.2117473099449, 872.9388942774071, 16009.59976000586, 16594.170291485378, 17087.658246834995, 17206.0, 17379.0, 866.8136350684633, 868.2229404682773, 872.1235158665501, 877.4121338703085, 880.445319448756, 9292.395994260487, 9305.302102342352, 9504.0, 9568.425894446356, 11145.219834611662, 446994.0, 447399.0, 452232.0, 458519.0, 458647.2555656076, 95189.0, 95787.52106239564, 95895.0, 96747.51626241824, 96965.0, 1073.978520429587, 1110.4114819145866, 1130.9999999999836, 1133.6032388664066, 1136.2841409911869, 17524.47426577205, 17557.736633950244, 18013.459596212135, 18099.457016289532, 18442.262309508005, 44381.39891395768, 44976.1004779896, 45386.0, 46032.769836150605, 46252.306215406126, 58248.0, 58795.70602146962, 58806.411935880096, 59024.40975590189, 59676.10485842803, 8018.679252830055, 8222.095569487472, 8245.093039765501, 8270.710525131592, 8376.581170941306, 12658.746825063263, 12709.93645031769, 12789.168704034208, 12866.228026318451, 13020.414081366172, 9539.0, 9718.396464583264, 9832.508374581384, 9886.258530610377, 10165.614511746595, 10153.187744980532, 10196.643117490852, 10518.421486818299, 10531.157507399543, 10902.236843421202, 10937.343759374464, 11126.221164518405, 11507.712307192387, 11587.130965177477, 11632.592859250224, 8046.235607617267, 8148.0, 8218.0, 8297.336213103057, 8451.281641060334, 10874.78250434971, 10890.564377424815, 11060.115190784558, 11075.0, 11406.82889756671], "value": 9047.407984488083, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.9262644241346, 1259.9496020159188, 1268.885800277975, 1274.6239859241523, 1281.2952875918243, 9533.0, 9852.458114803683, 9918.900810991889, 9938.751531211723, 10203.234757393187, 9823.607055717763, 9984.0, 10023.39859608424, 10047.346922450053, 10057.899421005788, 2189.036823797528, 2221.6445368741006, 2226.7216597925258, 2229.15292188968, 2233.0732745910427, 2091.592139532792, 2182.9126834926665, 2200.1309482754305, 2202.7246594175726, 2209.5359974405396, 2140.529083601605, 2188.485705859122, 2206.8124209442217, 2211.215018668374, 2226.1763147635397, 2178.5316157026236, 2182.9126834926583, 2185.366243789299, 2186.529896072352, 2193.7367515898036, 2094.8847813370257, 2114.481951921789, 2170.435686721448, 2173.0221400369737, 2178.433607262123, 9759.804803903882, 9849.409035457895, 9868.013198680146, 9890.258230632731, 9893.752656183653, 5314.521693047605, 5539.446055394468, 5540.556755459554, 5555.944440555583, 5557.41647127054, 3198.952015719766, 3366.0575038989123, 3389.67798059184, 3463.0995941055253, 3465.8440370183403, 974.35692442988, 987.8567607696915, 1013.0173731480526, 1067.0396643021265, 1071.464267866067, 14095.929520352434, 14362.210078445682, 14384.0, 14451.205183714892, 14497.855021449755, 4743.075100355467, 4818.542238487338, 4945.530174633404, 5006.799728010911, 5037.571806396424, 9819.509024548744, 10007.249456290807, 10018.048285412875, 10099.14157296638, 10203.081722644998, 5475.698836564027, 5496.367917689437, 5500.724963751796, 5515.420880807537, 5527.585431092684, 4852.272159176152, 4937.308776771255, 5009.398872135367, 5056.241563765465, 5064.518870707277, 3778.0932576181517, 3871.090293780959, 3897.8440862365187, 3898.5126859142606, 3932.9803350983634, 1124.8931351521592, 1128.3173679923527, 1131.8868113188614, 1137.4312843578211, 1137.8748337682848, 664.5016237821634, 680.4862328941617, 681.8261343357412, 684.9691763870637, 688.5524409134002, 5322.361316642027, 5461.863453413696, 5527.889442211133, 6027.758889644453, 6075.544334174954, 811.3893175369628, 811.8213992921548, 813.7274013205524, 833.8624127018974, 855.8074433252468, 3476.808775517371, 3524.1718196223865, 3614.0603443104983, 3642.1440961374055, 3674.669279764834, 11655.59205427806, 12178.208416452902, 12305.753884922431, 12310.446029928675, 12348.567800126952, 12063.457144428521, 12594.937025314815, 12698.873011269769, 12725.61823145307, 12740.872591273968, 9077.954610226907, 9150.267978561698, 9261.30540209487, 9282.48946307946, 9320.580573874191, 5347.117725575236, 5376.865578360415, 5462.945370546244, 5571.637843540157, 5662.207290979349, 11326.603568875049, 11372.943135284271, 11422.771544568895, 11482.655520334403, 11640.719520852868, 11626.128040397174, 11655.88344116548, 11708.180427370002, 11711.76576468483, 11948.880511194777, 7713.92286077132, 7914.485558438683, 7991.440599158003, 8009.439339246197, 8017.478863873829, 8983.550822458721, 9004.459732416077, 9005.63977440895, 9098.317626178195, 9138.817223655356, 7062.1878483975, 7115.395191408788, 7127.073480447509, 7146.785596432115, 7228.132624084933, 6839.555428897106, 6964.965175174294, 7005.929940700527, 7014.473914456335, 7039.753608623673, 6619.933800661932, 6736.7642132525125, 6742.9662851685425, 6744.224414192235, 6754.425873800782, 4945.653804233669, 4966.205407134841, 5043.5460808527405, 5046.1673823818655, 5246.108161612458, 5080.0799559548195, 5104.923426148536, 5137.023965446553, 5175.068487672255, 5254.632175747814], "value": 4601.9541574753275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.9038895081754, 397.1877510491046, 397.4257198348388, 397.50709120690345, 397.60636969400264, 5296.655717378368, 5488.807891723791, 5609.439056094394, 5634.887302253957, 5675.404082571333, 5360.892782144365, 5464.398916119224, 5473.31583552056, 5502.80740174094, 5554.944450555493, 4426.136903303857, 4431.512533621315, 4436.201483732927, 4444.888877778058, 4453.265211240157, 4351.804168812395, 4414.050979039506, 4420.292753159495, 4433.534478879719, 4434.312681534368, 4385.013371991308, 4390.056137930344, 4400.405945197389, 4420.1380730757355, 4420.58004489573, 4400.09797991411, 4406.294992801152, 4413.646908247332, 4420.1159768046355, 4421.0, 3495.5106285120105, 3996.160806230695, 4001.099752555681, 4007.83968641254, 4012.578679238696, 5348.331458567679, 5375.43557926416, 5381.112116500773, 5382.58206039164, 5410.972945135289, 4833.1058754130245, 4848.248521479177, 4853.320535125085, 4853.805847766085, 4858.17411040124, 5605.243292155588, 5776.768929242825, 5803.303603567599, 5833.300003999547, 5834.5624078194305, 4008.178323443716, 4010.7192496525254, 4010.859619913318, 4011.8194681239415, 4012.0, 5975.163477113208, 6070.2412198475195, 6118.908216376803, 6159.599626024294, 6189.597676151081, 4155.0, 4161.10536234705, 4169.3120635094865, 4179.310413781692, 4182.477190351206, 4230.725002874803, 4247.4265974093105, 4256.127493863751, 4257.127288905767, 4271.337942618899, 4793.32893394925, 4854.611631069506, 4886.828960986349, 4890.168671325883, 4892.535209155125, 5828.125781132864, 5829.941700583025, 5830.0, 5835.183074369592, 5837.445442682938, 1460.4085345435117, 1462.5228519195477, 1463.5463006468028, 1463.9048461849945, 1468.4273133477952, 939.5255396024963, 992.3301771304341, 993.8409854423257, 1000.1198944928561, 1010.711947095089, 5028.949710502921, 5056.6713163644245, 5061.670991385548, 5062.240663900371, 5070.239464080418, 6010.819675409744, 6039.48664363534, 6060.939390606038, 6072.726727297282, 6083.300420451705, 4873.390826146731, 5028.622853286018, 5057.570106540912, 5070.239464080418, 5086.338775959176, 3210.502372132323, 3224.613046434443, 3237.1583388318736, 3243.1567792374153, 3247.6427592964756, 5528.668279903219, 5636.943630563724, 5670.177824215466, 5672.234248376416, 5687.829365119053, 6144.324124346318, 6171.876562468726, 6197.411245931629, 6198.3120043574, 6257.812265631947, 5632.239647647597, 5637.43625637433, 5643.726638549631, 5660.150977353348, 5661.745221465127, 5870.121561100407, 5933.380637810846, 5999.52003839683, 6001.639901605918, 6077.756889724362, 5885.058390657646, 5910.940890591039, 5949.910751338647, 5957.165996760544, 5978.4021597839865, 5420.457954204706, 5597.860053498695, 5639.407862174577, 5645.633033852787, 5657.632253903483, 4124.546299906948, 4128.422020917134, 4134.462519872397, 4135.56576559469, 4142.5857414258335, 5956.910646340221, 6027.484851360956, 6071.666058366833, 6081.6351018939, 6083.726232319645, 5801.912971305518, 5847.649141051551, 5860.882782344414, 5864.120381942743, 5880.206172166874, 5775.7400916957995, 5802.187693722798, 5837.795677151279, 5851.736671849862, 5864.765409383747, 5601.159826026129, 5746.069504552792, 5764.279465066867, 5772.826815195551, 5792.94207057924, 5906.911396329145, 5916.763329466773, 5925.348211696796, 5926.044405346139, 5927.8221653350465, 5605.635633683797, 5695.515881150149, 5720.256366672306, 5725.914111288251, 5792.5655575832825], "value": 4405.402436706887, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.5431573267787, 588.4086493074459, 597.2444857255575, 599.8710277290381, 613.5551724999378, 12200.328981905992, 12627.0, 12838.486460541579, 12914.225146491217, 13261.204327740343, 13074.280914549696, 13213.339333033358, 13377.197368157893, 13484.865151348484, 13584.660383490418, 656.1142457682139, 874.2525141004445, 905.0587389115312, 918.9035151309116, 923.8706581078654, 917.7338571814166, 917.9357444978854, 918.0498184379169, 919.7700574856286, 930.1442672741094, 971.208465100944, 971.4899677669209, 974.3861367338567, 976.599594166392, 977.3988996766973, 942.6700654770843, 955.8805149356331, 957.0572985609205, 960.0735290444754, 963.9228861691083, 702.1258533126239, 702.5538782872861, 707.409313223459, 711.1430725975239, 711.3277952335045, 13564.59306220805, 13695.589132326046, 13870.722585548334, 14296.642583935381, 14394.640133996629, 12281.201721888048, 12316.692082697915, 12646.141272388424, 12781.146733723557, 12876.80684789729, 8435.114312997108, 8451.36614753896, 8506.829863402698, 8527.530985795778, 8558.101399353041, 2287.4853158039473, 2287.5081857400573, 2326.430024643956, 2360.7403185649564, 2362.055177928825, 58974.705126474095, 59575.40424595742, 59623.70188149075, 59858.102128467675, 60487.0, 7960.601969901481, 7975.641096150571, 8061.919380806235, 8062.153473885275, 8324.458910170819, 19454.0, 20508.384748457273, 20519.17923283082, 20554.28060017892, 20570.0, 5824.854378640568, 5845.152452894306, 5873.2658417697785, 5880.617759845682, 5943.524518038546, 2910.4324656692165, 3004.7596192304563, 3009.7291243788163, 3038.0, 3068.365243735903, 3609.5668519777796, 3652.579953305351, 3671.2840996006057, 3690.7785532868115, 3703.037210325335, 650.9967450162719, 652.5921299188008, 658.3909883357875, 667.956582822115, 673.020754801772, 466.3052052441837, 466.8179410030091, 475.9524047595282, 498.29988865643105, 502.3394236578887, 7531.58576278299, 7637.465377423527, 7690.769276921701, 7699.11460182075, 8003.0, 450.4751963962013, 478.5071376482229, 482.9975850120727, 501.49599652349156, 508.9007941635458, 3271.8036917785007, 3305.471124620049, 3337.5661164049, 3378.2567835076243, 3412.9658703412647, 60546.0, 62742.68628656828, 62749.0, 65925.0, 66078.33921660818, 53331.46668533343, 53469.0, 53507.46492535103, 53838.0, 56753.0, 49188.7540562302, 49283.26075108876, 49776.75111624419, 49791.50208497941, 50350.24474632883, 6765.560238584476, 6954.860902782018, 6955.0, 7109.466789990668, 7117.501774875916, 25537.106201282866, 25702.871485642452, 26161.215163545123, 26661.866690666422, 26686.466270674868, 31723.0, 32133.1966700825, 32203.35593288168, 32914.17714557155, 32919.34161316808, 8848.380613357003, 8915.699707803884, 8969.192772650493, 8992.875890513686, 9325.207357374698, 9314.6274149036, 9463.148316651534, 9558.56986435626, 9766.267529935425, 9822.21422286201, 8494.32045436376, 8605.784855378666, 8689.913100868911, 8827.646894124164, 9048.728538143867, 8031.196880311922, 8157.510549367057, 8316.501009939422, 8551.828963420821, 8561.058283588925, 8128.634211460617, 8279.586020699062, 8496.660133594834, 8612.0, 8612.956935215283, 6678.265390807104, 6905.585664860125, 6974.337437943387, 7143.82140446493, 7219.205887352492, 6719.664016799238, 6894.24163342042, 6934.653267336714, 7036.964815175891, 7247.202807691256], "value": 5652.469442812702, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 112.0, 111.0, 114.0, 112.0, 111.0, 111.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 132.0, 166.0, 124.0, 128.0, 125.0, 125.0, 148.0, 131.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 175.0, 168.0, 168.0, 171.0, 166.0, 171.0, 164.0, 165.0, 165.0], "value": 168.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 75.0, 75.0, 112.0, 76.0, 76.0, 77.0, 76.0, 93.0, 80.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 107.0, 85.0, 84.0, 84.0, 107.0, 86.0, 84.0, 116.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 115.0, 114.0, 120.0, 115.0, 116.0, 115.0, 114.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 151.0, 151.0, 151.0, 151.0, 150.0, 162.0, 152.0, 150.0], "value": 152.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 104.0, 104.0, 94.0, 95.0, 95.0, 95.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 69.0, 69.0, 69.0, 67.0, 71.0, 68.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 83.0, 84.0, 85.0, 83.0, 83.0, 83.0, 86.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 140.0, 154.0, 135.0, 134.0, 150.0, 145.0, 194.0, 141.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 196.0, 195.0, 198.0, 189.0, 206.0, 199.0, 192.0, 195.0, 198.0], "value": 196.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 70.0, 70.0, 74.0, 71.0, 76.0, 79.0, 69.0, 74.0], "value": 72.7, "unit": "score"}]}]} 08:53:00 INFO - Return code: 0 08:53:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:00 ERROR - # TBPL SUCCESS # 08:53:00 INFO - Running post-action listener: _resource_record_post_action 08:53:00 INFO - [mozharness: 2017-03-30 15:53:00.508833Z] Finished run-tests step (success) 08:53:00 INFO - Running post-run listener: _resource_record_post_run 08:53:00 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 204800; Write bytes: 366354432; Read time: 76; Write time: 393748 08:53:00 INFO - TinderboxPrint: CPU usage
13.3% 08:53:00 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 76 08:53:00 INFO - TinderboxPrint: I/O write bytes / time
366,354,432 / 393,748 08:53:00 INFO - TinderboxPrint: CPU idle
8,311.4 (86.6%) 08:53:00 INFO - TinderboxPrint: CPU user
1,219.1 (12.7%) 08:53:00 INFO - TinderboxPrint: Swap in / out
0 / 0 08:53:00 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 106319872; Read time: 0; Write time: 178348 08:53:00 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 204800; Write bytes: 259497984; Read time: 76; Write time: 215400 08:53:01 INFO - Running post-run listener: _upload_blobber_files 08:53:01 INFO - Blob upload gear active. 08:53:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53:01 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 08:53:01 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:53:01 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:53:01 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:53:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:53:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:02 INFO - (blobuploader) - INFO - Done attempting. 08:53:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:53:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:53:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:03 INFO - (blobuploader) - INFO - Done attempting. 08:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:06 INFO - (blobuploader) - INFO - Done attempting. 08:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:07 INFO - (blobuploader) - INFO - Done attempting. 08:53:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:07 INFO - Return code: 0 08:53:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d572df34ecf6edaa5b11102ffbb33944d964950a5b6e1b9ef009e1f69a6a6e4fbf5d9ef1fcca87d2c4ad8b1087802922a8ab893074ba698e1d0bd54a57ca0a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aed3f1a0956a6efb9acc3890673dbfb346c2f9e5970967eac04085df36f1d1a412ed168eebb6f0935084b8bef459ff28ce1b16c6376a8425e798cf8d4b52416b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b77d1cd25e4d4b93ebe07ac6e0471092f5878b5746e4073523dee7be3b8a147bf9446f46f8aa722b9587a45fa502bd69bb78d896852658c52ef6e910f475334", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/297837041f008c78dd89431af76859707505eb8ae8e9748f0275228fc2a5d60a036bbc1904098e9d959b7f666be96483ec71de8ff708e7cd59f2fef71ea3c6a2"} 08:53:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:07 INFO - Contents: 08:53:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d572df34ecf6edaa5b11102ffbb33944d964950a5b6e1b9ef009e1f69a6a6e4fbf5d9ef1fcca87d2c4ad8b1087802922a8ab893074ba698e1d0bd54a57ca0a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aed3f1a0956a6efb9acc3890673dbfb346c2f9e5970967eac04085df36f1d1a412ed168eebb6f0935084b8bef459ff28ce1b16c6376a8425e798cf8d4b52416b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b77d1cd25e4d4b93ebe07ac6e0471092f5878b5746e4073523dee7be3b8a147bf9446f46f8aa722b9587a45fa502bd69bb78d896852658c52ef6e910f475334", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/297837041f008c78dd89431af76859707505eb8ae8e9748f0275228fc2a5d60a036bbc1904098e9d959b7f666be96483ec71de8ff708e7cd59f2fef71ea3c6a2"} 08:53:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:07 INFO - Copying logs to upload dir... 08:53:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.877476 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-30 08:53:07.679489) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 08:53:07.682987) ========= 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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d572df34ecf6edaa5b11102ffbb33944d964950a5b6e1b9ef009e1f69a6a6e4fbf5d9ef1fcca87d2c4ad8b1087802922a8ab893074ba698e1d0bd54a57ca0a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aed3f1a0956a6efb9acc3890673dbfb346c2f9e5970967eac04085df36f1d1a412ed168eebb6f0935084b8bef459ff28ce1b16c6376a8425e798cf8d4b52416b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b77d1cd25e4d4b93ebe07ac6e0471092f5878b5746e4073523dee7be3b8a147bf9446f46f8aa722b9587a45fa502bd69bb78d896852658c52ef6e910f475334", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/297837041f008c78dd89431af76859707505eb8ae8e9748f0275228fc2a5d60a036bbc1904098e9d959b7f666be96483ec71de8ff708e7cd59f2fef71ea3c6a2"} build_url:https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/WPrw1PBMSS-vUjG5HGKOMw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d572df34ecf6edaa5b11102ffbb33944d964950a5b6e1b9ef009e1f69a6a6e4fbf5d9ef1fcca87d2c4ad8b1087802922a8ab893074ba698e1d0bd54a57ca0a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aed3f1a0956a6efb9acc3890673dbfb346c2f9e5970967eac04085df36f1d1a412ed168eebb6f0935084b8bef459ff28ce1b16c6376a8425e798cf8d4b52416b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b77d1cd25e4d4b93ebe07ac6e0471092f5878b5746e4073523dee7be3b8a147bf9446f46f8aa722b9587a45fa502bd69bb78d896852658c52ef6e910f475334", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/297837041f008c78dd89431af76859707505eb8ae8e9748f0275228fc2a5d60a036bbc1904098e9d959b7f666be96483ec71de8ff708e7cd59f2fef71ea3c6a2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 08:53:07.709472) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:53:07.709890) ========= 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=5cf47e208febbcd81da23b2e0000026f-1490887391.213751-388583102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 08:53:07.794840) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 08:53:07.795197) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 08:53:07.795524) ========= ========= Total master_lag: 0.18 =========