builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1479335352.93 results: success (0) buildid: 20161116124710 builduid: ef9f6765b6624dc5842f8f97ad547878 revision: fbbcd82b29623828d64038b7272da2906f4bd8f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:12.930550) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:12.931034) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:12.931319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:12.946024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:12.946385) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-16 14:29:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.3M=0s 2016-11-16 14:29:13 (51.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082170 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:13.042460) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:13.042812) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014256 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:13.092656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 14:29:13.093018) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev fbbcd82b29623828d64038b7272da2906f4bd8f5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev fbbcd82b29623828d64038b7272da2906f4bd8f5 --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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-16 14:29:13,337 truncating revision to first 12 chars 2016-11-16 14:29:13,337 Setting DEBUG logging. 2016-11-16 14:29:13,337 attempt 1/10 2016-11-16 14:29:13,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/fbbcd82b2962?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 14:29:14,713 unpacking tar archive at: larch-fbbcd82b2962/testing/mozharness/ program finished with exit code 0 elapsedTime=1.715426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 14:29:14.823653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:14.824005) ========= script_repo_revision: fbbcd82b29623828d64038b7272da2906f4bd8f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:14.824450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:14.824820) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 14:29:14.835346) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-11-16 14:29:14.835893) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:29:15 INFO - MultiFileLogger online at 20161116 14:29:15 in /builds/slave/test 14:29:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 14:29:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:29:15 INFO - {'append_to_log': False, 14:29:15 INFO - 'base_work_dir': '/builds/slave/test', 14:29:15 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 14:29:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:29:15 INFO - 'branch': 'Larch-Non-PGO', 14:29:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:29:15 INFO - 'config_files': ('talos/linux_config.py',), 14:29:15 INFO - 'default_actions': ('clobber', 14:29:15 INFO - 'read-buildbot-config', 14:29:15 INFO - 'download-and-extract', 14:29:15 INFO - 'populate-webroot', 14:29:15 INFO - 'create-virtualenv', 14:29:15 INFO - 'install', 14:29:15 INFO - 'run-tests'), 14:29:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:29:15 INFO - 'download_minidump_stackwalk': True, 14:29:15 INFO - 'download_symbols': 'ondemand', 14:29:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:29:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:29:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:29:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:29:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:29:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:29:15 INFO - 'installer_path': 'installer.exe', 14:29:15 INFO - 'log_level': 'info', 14:29:15 INFO - 'log_name': 'talos', 14:29:15 INFO - 'log_to_console': True, 14:29:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:29:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:29:15 INFO - 'opt_config_files': (), 14:29:15 INFO - 'pip_index': False, 14:29:15 INFO - 'sps_profile': False, 14:29:15 INFO - 'sps_profile_interval': 0, 14:29:15 INFO - 'suite': 'dromaeojs', 14:29:15 INFO - 'system_bits': '32', 14:29:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:29:15 INFO - 'title': 'talos-linux64-ix-062', 14:29:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:29:15 INFO - 'use_talos_json': True, 14:29:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:29:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:29:15 INFO - 'work_dir': 'build'} 14:29:15 INFO - [mozharness: 2016-11-16 22:29:15.189053Z] Running clobber step. 14:29:15 INFO - Running pre-action listener: _resource_record_pre_action 14:29:15 INFO - Running main action method: clobber 14:29:15 INFO - rmtree: /builds/slave/test/build 14:29:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:15 INFO - Running post-action listener: _resource_record_post_action 14:29:15 INFO - [mozharness: 2016-11-16 22:29:15.646434Z] Finished clobber step (success) 14:29:15 INFO - [mozharness: 2016-11-16 22:29:15.646534Z] Running read-buildbot-config step. 14:29:15 INFO - Running pre-action listener: _resource_record_pre_action 14:29:15 INFO - Running main action method: read_buildbot_config 14:29:15 INFO - Using buildbot properties: 14:29:15 INFO - { 14:29:15 INFO - "project": "", 14:29:15 INFO - "product": "firefox", 14:29:15 INFO - "script_repo_revision": "production", 14:29:15 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 14:29:15 INFO - "repository": "", 14:29:15 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 14:29:15 INFO - "buildid": "20161116124710", 14:29:15 INFO - "pgo_build": "False", 14:29:15 INFO - "basedir": "/builds/slave/test", 14:29:15 INFO - "buildnumber": 8, 14:29:15 INFO - "slavename": "talos-linux64-ix-062", 14:29:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:29:15 INFO - "platform": "ubuntu64_hw", 14:29:15 INFO - "scheduler": "tests-larch-linux64-talos", 14:29:15 INFO - "branch": "larch", 14:29:15 INFO - "repo_path": "projects/larch", 14:29:15 INFO - "revision": "fbbcd82b29623828d64038b7272da2906f4bd8f5", 14:29:15 INFO - "stage_platform": "linux64", 14:29:15 INFO - "builduid": "ef9f6765b6624dc5842f8f97ad547878", 14:29:15 INFO - "slavebuilddir": "test" 14:29:15 INFO - } 14:29:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:29:15 INFO - Running post-action listener: _resource_record_post_action 14:29:15 INFO - [mozharness: 2016-11-16 22:29:15.648939Z] Finished read-buildbot-config step (success) 14:29:15 INFO - [mozharness: 2016-11-16 22:29:15.649026Z] Running download-and-extract step. 14:29:15 INFO - Running pre-action listener: _resource_record_pre_action 14:29:15 INFO - Running main action method: download_and_extract 14:29:15 INFO - mkdir: /builds/slave/test/build/tests 14:29:15 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')]} 14:29:15 INFO - trying https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:29:15 INFO - Downloading https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:29:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:29:16 INFO - Downloaded 1361 bytes. 14:29:16 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:29:16 INFO - Using the following test package requirements: 14:29:16 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:29:16 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:29:16 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:29:16 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'jsshell-linux-x86_64.zip'], 14:29:16 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:29:16 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:29:16 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:29:16 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:29:16 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:29:16 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:29:16 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:29:16 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:29:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:29:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:29:16 INFO - Fetch https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:29:16 INFO - Http code: 200 14:29:16 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 14:29:16 INFO - x-amz-cf-id: 5i2Z-7Et1VnHjVdRAYBFajoveYUEZtueOWSteDPPVZJDa7GpgrLXaQ== 14:29:16 INFO - x-amz-version-id: pqT2xOqI_yu5TMlbpafKkr3wp.SbzXSZ 14:29:17 INFO - Expected file size: 20353974 14:29:17 INFO - Obtained file size: 20353974 14:29:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:29:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:29:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:29:21 INFO - Fetch https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:29:21 INFO - Http code: 200 14:29:21 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 14:29:21 INFO - x-amz-cf-id: 7tIeAThXaK9Dm2EjI69RmhOT-EnzkZYAKkCkGIOEUHA38OuOCBJDSQ== 14:29:21 INFO - x-amz-version-id: pqT2xOqI_yu5TMlbpafKkr3wp.SbzXSZ 14:29:21 INFO - Expected file size: 20353974 14:29:21 INFO - Obtained file size: 20353974 14:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:29:26 INFO - Http code: 200 14:29:26 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 14:29:26 INFO - x-amz-cf-id: k_pdCmA4QS9Gby0URcNzzeL7v4PArPvPKkEV91OX_Pf1mWwmXmezfw== 14:29:26 INFO - x-amz-version-id: M6UuKwJkmrU08HapfIPk0a0r74bNrYfK 14:29:26 INFO - Expected file size: 14409366 14:29:26 INFO - Obtained file size: 14409366 14:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:27 INFO - trying https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:29:27 INFO - Downloading https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:29:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:29:28 INFO - Downloaded 55743532 bytes. 14:29:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:29:28 INFO - mkdir: /builds/slave/test/properties 14:29:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:28 INFO - Contents: 14:29:28 INFO - build_url:https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:29:29 INFO - Running post-action listener: _resource_record_post_action 14:29:29 INFO - Running post-action listener: set_extra_try_arguments 14:29:29 INFO - [mozharness: 2016-11-16 22:29:29.047982Z] Finished download-and-extract step (success) 14:29:29 INFO - [mozharness: 2016-11-16 22:29:29.048208Z] Running populate-webroot step. 14:29:29 INFO - Running pre-action listener: _resource_record_pre_action 14:29:29 INFO - Running main action method: populate_webroot 14:29:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:29:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:29:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:29:29 INFO - '--tppagecycles', 14:29:29 INFO - '7'], 14:29:29 INFO - 'tests': ['tsvgm']}}, 14:29:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:29:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:29:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:29:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:29:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['damp', 'tps']}, 14:29:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'tests': ['damp', 'tps']}, 14:29:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['dromaeo_dom']}, 14:29:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:29:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['basic_compositor_video']}, 14:29:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:29:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['a11yr', 14:29:29 INFO - 'ts_paint', 14:29:29 INFO - 'tpaint', 14:29:29 INFO - 'sessionrestore', 14:29:29 INFO - 'sessionrestore_no_auto_restore', 14:29:29 INFO - 'tabpaint']}, 14:29:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['tsvgx', 14:29:29 INFO - 'tsvgr_opacity', 14:29:29 INFO - 'tart', 14:29:29 INFO - 'tscrollx', 14:29:29 INFO - 'cart']}, 14:29:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:29:29 INFO - 'tsvgr_opacity', 14:29:29 INFO - 'tart', 14:29:29 INFO - 'tscrollx', 14:29:29 INFO - 'cart']}, 14:29:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'talos_options': ['--disable-e10s'], 14:29:29 INFO - 'tests': ['tp5o']}, 14:29:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:29:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'talos_options': ['--disable-e10s', 14:29:29 INFO - '--xperf_path', 14:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:29 INFO - 'tests': ['tp5n']}, 14:29:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:29:29 INFO - 'talos_options': ['--xperf_path', 14:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:29 INFO - 'tests': ['tp5n']}}, 14:29:29 INFO - 'talos.zip': {'path': '', 14:29:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:29:29 INFO - Running post-action listener: _resource_record_post_action 14:29:29 INFO - [mozharness: 2016-11-16 22:29:29.065305Z] Finished populate-webroot step (success) 14:29:29 INFO - [mozharness: 2016-11-16 22:29:29.065391Z] Running create-virtualenv step. 14:29:29 INFO - Running pre-action listener: _resource_record_pre_action 14:29:29 INFO - Running main action method: create_virtualenv 14:29:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:29:29 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 14:29:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:29:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:29:29 INFO - Using real prefix '/usr' 14:29:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:29:29 INFO - Installing distribute.............................................................................................................................................................................................done. 14:29:31 INFO - Installing pip.................done. 14:29:31 INFO - Return code: 0 14:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:29:31 INFO - Reading from file tmpfile_stdout 14:29:31 INFO - Output received: 14:29:31 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) 14:29:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:31 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:31 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 14:29:31 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 14:29:31 INFO - Using env: {'DISPLAY': ':0', 14:29:31 INFO - 'HOME': '/home/cltbld', 14:29:31 INFO - 'LANG': 'en_US.UTF-8', 14:29:31 INFO - 'LANGUAGE': 'en_US:en', 14:29:31 INFO - 'LOGNAME': 'cltbld', 14:29:31 INFO - 'MAIL': '/var/mail/cltbld', 14:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:31 INFO - 'MOZ_NO_REMOTE': '1', 14:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:31 INFO - 'NO_EM_RESTART': '1', 14:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:31 INFO - 'PWD': '/builds/slave/test', 14:29:31 INFO - 'SHELL': '/bin/bash', 14:29:31 INFO - 'SHLVL': '1', 14:29:31 INFO - 'TERM': 'linux', 14:29:31 INFO - 'TMOUT': '86400', 14:29:31 INFO - 'USER': 'cltbld', 14:29:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:31 INFO - '_': '/tools/buildbot/bin/python'} 14:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:32 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 14:29:32 INFO - Cleaning up... 14:29:32 INFO - Return code: 0 14:29:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:32 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:32 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 14:29:32 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 14:29:32 INFO - Using env: {'DISPLAY': ':0', 14:29:32 INFO - 'HOME': '/home/cltbld', 14:29:32 INFO - 'LANG': 'en_US.UTF-8', 14:29:32 INFO - 'LANGUAGE': 'en_US:en', 14:29:32 INFO - 'LOGNAME': 'cltbld', 14:29:32 INFO - 'MAIL': '/var/mail/cltbld', 14:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:32 INFO - 'MOZ_NO_REMOTE': '1', 14:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:32 INFO - 'NO_EM_RESTART': '1', 14:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:32 INFO - 'PWD': '/builds/slave/test', 14:29:32 INFO - 'SHELL': '/bin/bash', 14:29:32 INFO - 'SHLVL': '1', 14:29:32 INFO - 'TERM': 'linux', 14:29:32 INFO - 'TMOUT': '86400', 14:29:32 INFO - 'USER': 'cltbld', 14:29:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:32 INFO - '_': '/tools/buildbot/bin/python'} 14:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:32 INFO - Downloading/unpacking psutil>=3.1.1 14:29:32 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 14:29:32 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 14:29:32 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 14:29:32 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 14:29:32 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 14:29:32 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 14:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:34 INFO - Installing collected packages: psutil 14:29:34 INFO - Running setup.py install for psutil 14:29:34 INFO - building 'psutil._psutil_linux' extension 14:29:34 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 14:29:34 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 14:29:34 INFO - building 'psutil._psutil_posix' extension 14:29:34 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 14:29:34 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 14:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:34 INFO - Successfully installed psutil 14:29:34 INFO - Cleaning up... 14:29:34 INFO - Return code: 0 14:29:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:29:34 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')]} 14:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:34 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')]} 14:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:29:35 INFO - Using env: {'DISPLAY': ':0', 14:29:35 INFO - 'HOME': '/home/cltbld', 14:29:35 INFO - 'LANG': 'en_US.UTF-8', 14:29:35 INFO - 'LANGUAGE': 'en_US:en', 14:29:35 INFO - 'LOGNAME': 'cltbld', 14:29:35 INFO - 'MAIL': '/var/mail/cltbld', 14:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:35 INFO - 'MOZ_NO_REMOTE': '1', 14:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:35 INFO - 'NO_EM_RESTART': '1', 14:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:35 INFO - 'PWD': '/builds/slave/test', 14:29:35 INFO - 'SHELL': '/bin/bash', 14:29:35 INFO - 'SHLVL': '1', 14:29:35 INFO - 'TERM': 'linux', 14:29:35 INFO - 'TMOUT': '86400', 14:29:35 INFO - 'USER': 'cltbld', 14:29:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:35 INFO - '_': '/tools/buildbot/bin/python'} 14:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:29:37 INFO - Installing collected packages: mozsystemmonitor 14:29:37 INFO - Running setup.py install for mozsystemmonitor 14:29:37 INFO - Successfully installed mozsystemmonitor 14:29:37 INFO - Cleaning up... 14:29:37 INFO - Return code: 0 14:29:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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', '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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:29: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 jsonschema==2.5.1 14:29:37 INFO - Using env: {'DISPLAY': ':0', 14:29:37 INFO - 'HOME': '/home/cltbld', 14:29:37 INFO - 'LANG': 'en_US.UTF-8', 14:29:37 INFO - 'LANGUAGE': 'en_US:en', 14:29:37 INFO - 'LOGNAME': 'cltbld', 14:29:37 INFO - 'MAIL': '/var/mail/cltbld', 14:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:37 INFO - 'MOZ_NO_REMOTE': '1', 14:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:37 INFO - 'NO_EM_RESTART': '1', 14:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:37 INFO - 'PWD': '/builds/slave/test', 14:29:37 INFO - 'SHELL': '/bin/bash', 14:29:37 INFO - 'SHLVL': '1', 14:29:37 INFO - 'TERM': 'linux', 14:29:37 INFO - 'TMOUT': '86400', 14:29:37 INFO - 'USER': 'cltbld', 14:29:37 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:37 INFO - '_': '/tools/buildbot/bin/python'} 14:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:37 INFO - Downloading/unpacking jsonschema==2.5.1 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:29:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:29:39 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 14:29:39 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 14:29:39 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 14:29:39 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 14:29:39 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 14:29:39 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 14:29:39 INFO - Downloading functools32-3.2.3-2.tar.gz 14:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:29:39 INFO - warning: no files found matching '*.txt' 14:29:39 INFO - no previously-included directories found matching 'build' 14:29:39 INFO - no previously-included directories found matching 'dist' 14:29:39 INFO - no previously-included directories found matching '.git*' 14:29:39 INFO - Installing collected packages: jsonschema, functools32 14:29:39 INFO - Running setup.py install for functools32 14:29:39 INFO - warning: no files found matching '*.txt' 14:29:39 INFO - no previously-included directories found matching 'build' 14:29:39 INFO - no previously-included directories found matching 'dist' 14:29:39 INFO - no previously-included directories found matching '.git*' 14:29:39 INFO - Successfully installed jsonschema functools32 14:29:39 INFO - Cleaning up... 14:29:39 INFO - Return code: 0 14:29:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:39 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:39 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 14:29:39 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 14:29:39 INFO - Using env: {'DISPLAY': ':0', 14:29:39 INFO - 'HOME': '/home/cltbld', 14:29:39 INFO - 'LANG': 'en_US.UTF-8', 14:29:39 INFO - 'LANGUAGE': 'en_US:en', 14:29:39 INFO - 'LOGNAME': 'cltbld', 14:29:39 INFO - 'MAIL': '/var/mail/cltbld', 14:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:39 INFO - 'MOZ_NO_REMOTE': '1', 14:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:39 INFO - 'NO_EM_RESTART': '1', 14:29:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:39 INFO - 'PWD': '/builds/slave/test', 14:29:39 INFO - 'SHELL': '/bin/bash', 14:29:39 INFO - 'SHLVL': '1', 14:29:39 INFO - 'TERM': 'linux', 14:29:39 INFO - 'TMOUT': '86400', 14:29:39 INFO - 'USER': 'cltbld', 14:29:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:39 INFO - '_': '/tools/buildbot/bin/python'} 14:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:29:40 INFO - Cleaning up... 14:29:40 INFO - Return code: 0 14:29:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:29:40 INFO - Using env: {'DISPLAY': ':0', 14:29:40 INFO - 'HOME': '/home/cltbld', 14:29:40 INFO - 'LANG': 'en_US.UTF-8', 14:29:40 INFO - 'LANGUAGE': 'en_US:en', 14:29:40 INFO - 'LOGNAME': 'cltbld', 14:29:40 INFO - 'MAIL': '/var/mail/cltbld', 14:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:40 INFO - 'MOZ_NO_REMOTE': '1', 14:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:40 INFO - 'NO_EM_RESTART': '1', 14:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:40 INFO - 'PWD': '/builds/slave/test', 14:29:40 INFO - 'SHELL': '/bin/bash', 14:29:40 INFO - 'SHLVL': '1', 14:29:40 INFO - 'TERM': 'linux', 14:29:40 INFO - 'TMOUT': '86400', 14:29:40 INFO - 'USER': 'cltbld', 14:29:40 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:40 INFO - '_': '/tools/buildbot/bin/python'} 14:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:40 INFO - Downloading/unpacking blobuploader==1.2.4 14:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:42 INFO - Downloading blobuploader-1.2.4.tar.gz 14:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:29:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:29:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:43 INFO - Downloading docopt-0.6.1.tar.gz 14:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:29:43 INFO - Installing collected packages: blobuploader, requests, docopt 14:29:43 INFO - Running setup.py install for blobuploader 14:29:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:29:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:29:43 INFO - Running setup.py install for requests 14:29:43 INFO - Running setup.py install for docopt 14:29:43 INFO - Successfully installed blobuploader requests docopt 14:29:43 INFO - Cleaning up... 14:29:43 INFO - Return code: 0 14:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:43 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 14:29:43 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 14:29:43 INFO - Using env: {'DISPLAY': ':0', 14:29:43 INFO - 'HOME': '/home/cltbld', 14:29:43 INFO - 'LANG': 'en_US.UTF-8', 14:29:43 INFO - 'LANGUAGE': 'en_US:en', 14:29:43 INFO - 'LOGNAME': 'cltbld', 14:29:43 INFO - 'MAIL': '/var/mail/cltbld', 14:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:43 INFO - 'MOZ_NO_REMOTE': '1', 14:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:43 INFO - 'NO_EM_RESTART': '1', 14:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:43 INFO - 'PWD': '/builds/slave/test', 14:29:43 INFO - 'SHELL': '/bin/bash', 14:29:43 INFO - 'SHLVL': '1', 14:29:43 INFO - 'TERM': 'linux', 14:29:43 INFO - 'TMOUT': '86400', 14:29:43 INFO - 'USER': 'cltbld', 14:29:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:43 INFO - '_': '/tools/buildbot/bin/python'} 14:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:43 INFO - Running setup.py (path:/tmp/pip-S3wx9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:44 INFO - Running setup.py (path:/tmp/pip-g_C045-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:44 INFO - Running setup.py (path:/tmp/pip-X5oxeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:44 INFO - Running setup.py (path:/tmp/pip-e3jiWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:44 INFO - Running setup.py (path:/tmp/pip-m3C4UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:44 INFO - Running setup.py (path:/tmp/pip-CHTKXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:44 INFO - Running setup.py (path:/tmp/pip-0Nws5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:44 INFO - Running setup.py (path:/tmp/pip-n9vfxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:44 INFO - Running setup.py (path:/tmp/pip-D6t211-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:44 INFO - Running setup.py (path:/tmp/pip-yzPD6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:44 INFO - Running setup.py (path:/tmp/pip-mgpdea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:44 INFO - Running setup.py (path:/tmp/pip-gPn1yF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:44 INFO - Running setup.py (path:/tmp/pip-OJyug0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:44 INFO - Running setup.py (path:/tmp/pip-EXwR0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:45 INFO - Running setup.py (path:/tmp/pip-ePmAbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:45 INFO - Running setup.py (path:/tmp/pip-K4oW3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:45 INFO - Running setup.py (path:/tmp/pip-WR85DG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:29:45 INFO - Running setup.py install for manifestparser 14:29:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozcrash 14:29:45 INFO - Running setup.py install for mozdebug 14:29:45 INFO - Running setup.py install for mozdevice 14:29:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozfile 14:29:45 INFO - Running setup.py install for mozhttpd 14:29:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozinfo 14:29:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozInstall 14:29:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozleak 14:29:46 INFO - Running setup.py install for mozlog 14:29:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for moznetwork 14:29:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozprocess 14:29:46 INFO - Running setup.py install for mozprofile 14:29:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozrunner 14:29:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozscreenshot 14:29:46 INFO - Running setup.py install for moztest 14:29:46 INFO - Running setup.py install for mozversion 14:29:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:29:47 INFO - Cleaning up... 14:29:47 INFO - Return code: 0 14:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:47 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:47 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 14:29:47 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 14:29:47 INFO - Using env: {'DISPLAY': ':0', 14:29:47 INFO - 'HOME': '/home/cltbld', 14:29:47 INFO - 'LANG': 'en_US.UTF-8', 14:29:47 INFO - 'LANGUAGE': 'en_US:en', 14:29:47 INFO - 'LOGNAME': 'cltbld', 14:29:47 INFO - 'MAIL': '/var/mail/cltbld', 14:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:47 INFO - 'MOZ_NO_REMOTE': '1', 14:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:47 INFO - 'NO_EM_RESTART': '1', 14:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:47 INFO - 'PWD': '/builds/slave/test', 14:29:47 INFO - 'SHELL': '/bin/bash', 14:29:47 INFO - 'SHLVL': '1', 14:29:47 INFO - 'TERM': 'linux', 14:29:47 INFO - 'TMOUT': '86400', 14:29:47 INFO - 'USER': 'cltbld', 14:29:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:47 INFO - '_': '/tools/buildbot/bin/python'} 14:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:47 INFO - Running setup.py (path:/tmp/pip-FRsxcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:47 INFO - Running setup.py (path:/tmp/pip-9sytK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:47 INFO - Running setup.py (path:/tmp/pip-pUvyW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:47 INFO - Running setup.py (path:/tmp/pip-LK7Eg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:47 INFO - Running setup.py (path:/tmp/pip-LHGFtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:47 INFO - Running setup.py (path:/tmp/pip-10FVoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:47 INFO - Running setup.py (path:/tmp/pip-N8EDQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:47 INFO - Running setup.py (path:/tmp/pip-WWk4sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:47 INFO - Running setup.py (path:/tmp/pip-E3BNfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:47 INFO - Running setup.py (path:/tmp/pip-haJPYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:47 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)) 14:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:47 INFO - Running setup.py (path:/tmp/pip-eSX_Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:48 INFO - Running setup.py (path:/tmp/pip-d8rawe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:48 INFO - Running setup.py (path:/tmp/pip-OM8vWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:48 INFO - Running setup.py (path:/tmp/pip-NaQbeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:48 INFO - Running setup.py (path:/tmp/pip-yOdgHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:48 INFO - Running setup.py (path:/tmp/pip-ex64gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:48 INFO - Running setup.py (path:/tmp/pip-XduRy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:48 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)) 14:29:48 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)) 14:29:48 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)) 14:29:48 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)) 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:50 INFO - Downloading blessings-1.6.tar.gz 14:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:29:50 INFO - Installing collected packages: blessings 14:29:50 INFO - Running setup.py install for blessings 14:29:50 INFO - Successfully installed blessings 14:29:50 INFO - Cleaning up... 14:29:50 INFO - Return code: 0 14:29:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:50 INFO - Reading from file tmpfile_stdout 14:29:50 INFO - Current package versions: 14:29:50 INFO - argparse == 1.2.1 14:29:50 INFO - blessings == 1.6 14:29:50 INFO - blobuploader == 1.2.4 14:29:50 INFO - docopt == 0.6.1 14:29:50 INFO - functools32 == 3.2.3-2 14:29:50 INFO - jsonschema == 2.5.1 14:29:50 INFO - manifestparser == 1.1 14:29:50 INFO - mozInstall == 1.12 14:29:50 INFO - mozcrash == 1.0 14:29:50 INFO - mozdebug == 0.1 14:29:50 INFO - mozdevice == 0.48 14:29:50 INFO - mozfile == 1.2 14:29:50 INFO - mozhttpd == 0.7 14:29:50 INFO - mozinfo == 0.9 14:29:50 INFO - mozleak == 0.1 14:29:50 INFO - mozlog == 3.4 14:29:50 INFO - moznetwork == 0.27 14:29:50 INFO - mozprocess == 0.23 14:29:50 INFO - mozprofile == 0.28 14:29:50 INFO - mozrunner == 6.13 14:29:50 INFO - mozscreenshot == 0.1 14:29:50 INFO - mozsystemmonitor == 0.3 14:29:50 INFO - moztest == 0.7 14:29:50 INFO - mozversion == 1.4 14:29:50 INFO - psutil == 3.1.1 14:29:50 INFO - requests == 1.2.3 14:29:50 INFO - wsgiref == 0.1.2 14:29:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:50 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 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:50 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 14:29:50 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 14:29:50 INFO - Using env: {'DISPLAY': ':0', 14:29:50 INFO - 'HOME': '/home/cltbld', 14:29:50 INFO - 'LANG': 'en_US.UTF-8', 14:29:50 INFO - 'LANGUAGE': 'en_US:en', 14:29:50 INFO - 'LOGNAME': 'cltbld', 14:29:50 INFO - 'MAIL': '/var/mail/cltbld', 14:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:50 INFO - 'MOZ_NO_REMOTE': '1', 14:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:50 INFO - 'NO_EM_RESTART': '1', 14:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:50 INFO - 'PWD': '/builds/slave/test', 14:29:50 INFO - 'SHELL': '/bin/bash', 14:29:50 INFO - 'SHLVL': '1', 14:29:50 INFO - 'TERM': 'linux', 14:29:50 INFO - 'TMOUT': '86400', 14:29:50 INFO - 'USER': 'cltbld', 14:29:50 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:50 INFO - '_': '/tools/buildbot/bin/python'} 14:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 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)) 14:29:51 INFO - Cleaning up... 14:29:51 INFO - Return code: 0 14:29:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb17b7ffe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', '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 14:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:29:51 INFO - Using env: {'DISPLAY': ':0', 14:29:51 INFO - 'HOME': '/home/cltbld', 14:29:51 INFO - 'LANG': 'en_US.UTF-8', 14:29:51 INFO - 'LANGUAGE': 'en_US:en', 14:29:51 INFO - 'LOGNAME': 'cltbld', 14:29:51 INFO - 'MAIL': '/var/mail/cltbld', 14:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:51 INFO - 'MOZ_NO_REMOTE': '1', 14:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:51 INFO - 'NO_EM_RESTART': '1', 14:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:51 INFO - 'PWD': '/builds/slave/test', 14:29:51 INFO - 'SHELL': '/bin/bash', 14:29:51 INFO - 'SHLVL': '1', 14:29:51 INFO - 'TERM': 'linux', 14:29:51 INFO - 'TMOUT': '86400', 14:29:51 INFO - 'USER': 'cltbld', 14:29:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:51 INFO - '_': '/tools/buildbot/bin/python'} 14:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:29:51 INFO - Cleaning up... 14:29:51 INFO - Return code: 0 14:29:51 INFO - Running post-action listener: _resource_record_post_action 14:29:51 INFO - Running post-action listener: _start_resource_monitoring 14:29:51 INFO - Starting resource monitoring. 14:29:51 INFO - [mozharness: 2016-11-16 22:29:51.438837Z] Finished create-virtualenv step (success) 14:29:51 INFO - [mozharness: 2016-11-16 22:29:51.439563Z] Running install step. 14:29:51 INFO - Running pre-action listener: _resource_record_pre_action 14:29:51 INFO - Running main action method: install 14:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:51 INFO - Reading from file tmpfile_stdout 14:29:51 INFO - Detecting whether we're running mozinstall >=1.0... 14:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:29:51 INFO - Reading from file tmpfile_stdout 14:29:51 INFO - Output received: 14:29:51 INFO - Usage: mozinstall [options] installer 14:29:51 INFO - Options: 14:29:51 INFO - -h, --help show this help message and exit 14:29:51 INFO - -d DEST, --destination=DEST 14:29:51 INFO - Directory to install application into. [default: 14:29:51 INFO - "/builds/slave/test"] 14:29:51 INFO - --app=APP Application being installed. [default: firefox] 14:29:51 INFO - mkdir: /builds/slave/test/build/application 14:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:30:08 INFO - Reading from file tmpfile_stdout 14:30:08 INFO - Output received: 14:30:08 INFO - /builds/slave/test/build/application/firefox/firefox 14:30:08 INFO - Running post-action listener: _resource_record_post_action 14:30:08 INFO - [mozharness: 2016-11-16 22:30:08.325063Z] Finished install step (success) 14:30:08 INFO - [mozharness: 2016-11-16 22:30:08.325319Z] Running run-tests step. 14:30:08 INFO - Running pre-action listener: _resource_record_pre_action 14:30:08 INFO - Running main action method: run_tests 14:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:08 INFO - Python 2.7.3 14:30:08 INFO - Return code: 0 14:30:08 INFO - grabbing minidump binary from tooltool 14:30:08 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')]} 14:30:08 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 0x1739fc0>, '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 0x173aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:08 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 14:30:08 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 14:30:08 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 14:30:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:08 INFO - Return code: 0 14:30:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:30:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:30:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:30:08 INFO - Using env: {'DISPLAY': ':0', 14:30:08 INFO - 'HOME': '/home/cltbld', 14:30:08 INFO - 'LANG': 'en_US.UTF-8', 14:30:08 INFO - 'LANGUAGE': 'en_US:en', 14:30:08 INFO - 'LOGNAME': 'cltbld', 14:30:08 INFO - 'MAIL': '/var/mail/cltbld', 14:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:08 INFO - 'MOZ_NO_REMOTE': '1', 14:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:08 INFO - 'NO_EM_RESTART': '1', 14:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:08 INFO - 'PWD': '/builds/slave/test', 14:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:08 INFO - 'SHELL': '/bin/bash', 14:30:08 INFO - 'SHLVL': '1', 14:30:08 INFO - 'TERM': 'linux', 14:30:08 INFO - 'TMOUT': '86400', 14:30:08 INFO - 'USER': 'cltbld', 14:30:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655', 14:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:08 INFO - '_': '/tools/buildbot/bin/python'} 14:30:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:30:08 INFO - mozversion application_buildid: 20161116124710 14:30:08 INFO - mozversion application_changeset: fbbcd82b29623828d64038b7272da2906f4bd8f5 14:30:08 INFO - mozversion application_display_name: Nightly 14:30:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:08 INFO - mozversion application_name: Firefox 14:30:08 INFO - mozversion application_remotingname: firefox 14:30:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:30:08 INFO - mozversion application_vendor: Mozilla 14:30:08 INFO - mozversion application_version: 53.0a1 14:30:08 INFO - mozversion platform_buildid: 20161116124710 14:30:08 INFO - mozversion platform_changeset: fbbcd82b29623828d64038b7272da2906f4bd8f5 14:30:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:30:08 INFO - mozversion platform_version: 53.0a1 14:30:08 INFO - using testdate: 1479335408 14:30:08 INFO - actual date: 1479335408 14:30:08 INFO - starting webserver on 'localhost:46360' 14:30:08 INFO - SUITE-START | Running 2 tests 14:30:08 INFO - TEST-START | dromaeo_css 14:30:08 INFO - operating with platform_type : linux_ 14:30:08 INFO - Initialising browser for dromaeo_css test... 14:30:08 INFO - TEST-INFO | started process 24764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFbFLHo/profile http://localhost:46360/getInfo.html) 14:30:12 INFO - PROCESS | 24764 | __metrics Screen width/height:1600/1200 14:30:12 INFO - PROCESS | 24764 | colorDepth:24 14:30:12 INFO - PROCESS | 24764 | Browser inner width/height: 1024/697 14:30:12 INFO - PROCESS | 24764 | __metrics 14:30:13 INFO - PROCESS | 24764 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:30:13 INFO - PROCESS | 24764 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:30:13 INFO - PROCESS | 24764 | console.error: PushService: 14:30:13 INFO - PROCESS | 24764 | stateChangeProcessEnqueue: Error transitioning state 14:30:13 INFO - PROCESS | 24764 | UnknownError 14:30:14 INFO - TEST-INFO | 24764: exit 0 14:30:14 INFO - Browser initialized. 14:30:14 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:14 INFO - TEST-INFO | started process 24844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFbFLHo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:26 INFO - PROCESS | 24844 | RSS: Main: 176480256 14:30:26 INFO - PROCESS | 24844 | 14:31:17 INFO - PROCESS | 24844 | 1479335477499 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:33:30 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46360/tests/dromaeo/cssquery-ext.html) 14:33:32 INFO - PROCESS | 24844 | RSS: Main: 280485888 14:33:32 INFO - PROCESS | 24844 | 14:36:35 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-ext.html (next: http://localhost:46360/tests/dromaeo/cssquery-jquery.html) 14:36:36 INFO - PROCESS | 24844 | RSS: Main: 278835200 14:36:36 INFO - PROCESS | 24844 | 14:39:38 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46360/tests/dromaeo/cssquery-mootools.html) 14:39:39 INFO - PROCESS | 24844 | RSS: Main: 257650688 14:39:39 INFO - PROCESS | 24844 | 14:42:41 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46360/tests/dromaeo/cssquery-prototype.html) 14:42:42 INFO - PROCESS | 24844 | RSS: Main: 238092288 14:42:42 INFO - PROCESS | 24844 | 14:45:45 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46360/tests/dromaeo/cssquery-yui.html) 14:45:46 INFO - PROCESS | 24844 | RSS: Main: 222793728 14:45:46 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | Cycle 1(1): loaded http://localhost:46360/tests/dromaeo/cssquery-yui.html (next: http://localhost:46360/tests/dromaeo/cssquery-dojo.html) 14:48:49 INFO - PROCESS | 24844 | RSS: Main: 317816832 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | __start_tp_report 14:48:49 INFO - PROCESS | 24844 | _x_x_mozilla_page_load 14:48:49 INFO - PROCESS | 24844 | _x_x_mozilla_page_load_details 14:48:49 INFO - PROCESS | 24844 | |i|pagename|runs| 14:48:49 INFO - PROCESS | 24844 | |0;dojo.html;2568.2937192272125;2590.378309205789;2607.9478410431784;2630.947381052379;2638.1294172922926;4365.607095361417;4442.11157768446;4621.930671039937;4622.8844278893;4631.397918270624;4491.820327186909;4641.419822522184;4681.929771053428;4713.693609915362;4723.244280915045;4217.072244106307;4219.767912764797;4240.363945408198;4281.828726850922;4291.334843099325;4274.679399045069;4333.761643109628;4338.045629961403;4354.1074079813725;4398.450193725785;4364.258076127066;4370.994671225604;4406.471223453206;4419.823207071713;4442.555744425547;4119.970007498126;4135.0902801383945;4137.668986481074;4215.683823713233;4245.12974840157;4248.171606536726;4255.957440425587;4278.465191851019;4286.828526858922;4324.870253892388;4607.746573938432;4612.677112602119;4625.236835922069;4647.163510568096;4687.531246875319;4794.49657785931;4841.15279826029;4853.320535125085;4959.380077490314;4972.701637901738;4904.681195722266;4924.630652701062;4936.333594964705;4936.975315123437;4937.975310123462;4628.028114096044;4707.905841883143;4717.551832575899;4720.150372932836;4720.95279047212;5068.898622027539;5086.364204474441;5089.185730283211;5101.362329708786;5106.974465127725;4879.2925025871045;4889.804407823718;4894.902101957941;4895.657303988758;4896.4858689837765;4790.928136077961;4857.708537487762;4858.149823780825;4930.383702037246;4979.825706100269;4960.454350021495;4964.453910069889;4987.650864439527;5010.323606313101;5013.874653133701;4894.608431325485;4942.555170034714;4944.332515110414;4951.183054795918;5006.64953453262;4068.8575899843363;4159.521655009652;4162.188373267244;4286.014216730169;4288.421063156434;3689.2805902849223;3743.3823419136083;3743.831527581211;3745.6441638044344;3786.091338078841;4371.715838470489;4375.803088861009;4377.978110109493;4412.007298357844;4423.181711383404;4784.473707892129;5051.368578927634;5057.8229761958155;5063.620228482879;5065.721385323769;4212.662986961036;4262.808173632132;4272.252355837716;4327.7186982846015;4340.565943405697;4523.457185137804;4523.570260825217;4541.364209010675;4558.293464513005;4566.223741963874;4723.83466578675;4727.810887564459;4729.10147072069;4736.384270044872;4775.832845850378;4846.903061938812;4905.77923993414;4909.1654418748185;4965.0566392385335;5012.248162775612;4635.976820116013;4676.018036212434;4680.204365257846;4692.812287508462;4697.530246975275;4884.5603895649565;4889.9022019560125;4893.09477746618;4895;4897.534734200245;4956.479569645136;4997.475265097258;5010.248462730619;5081.034603425337;5084.745762711924;4859.4654587996;4881.29221262922;4899.412070551557;4906.288588154769;4953.925691114564;4892.877678057935;4902.289168070681;4903.926441103315;4914;4955.231939049453;4645.233536466445;4653.976730116327;4655.511171327097;4765.046990601824;4827.444843843003;4876.536729010738;4930.802767889244;4942.085714142895;4944.456109827988;4968.503149685002;4784.449788274393;4793.448753393265;4800.399950006249;4846.975765121152;4848.781804818862 14:48:49 INFO - PROCESS | 24844 | |1;ext.html;562.2465895699763;585.0200913469937;590.4213870407001;596.6449962272449;597.0715537339441;38228.426573601384;39510;39885;43568;43689.34465983013;40239.39640905389;40608.796956015256;40680.796596017055;40718;40803;7616.50492717973;7759.107702614215;7771.533707977511;7771.533707977511;7771.844563108734;7603.733869314575;7680.539167649931;7683.1548529661695;7687.461877668537;7687.461877668566;7578.052743407075;7636.732714354998;7684.807879803022;7697.692092316301;7721.073471183466;7617.2001939796355;7657.731979380695;7672.309492145678;7677.155512893577;7681.078270607507;7539.886901696479;7610.8097297568;7621.618919054025;7624.885626715603;7625.656845441913;37080.44379334312;37416.625833741586;37466.43800342997;37507.624923750685;37517.81241093777;2892.783041271913;3027.409655117253;3230.224746060952;3442.7245820334315;3579.194681196736;27858.442831143264;28032.439351212863;28120.578191327146;28158.15525534237;29665.25836854096;3421.4354631485776;3481.669241422061;3498.737594680409;3500;3538.5930617978747;229091;229472;229657;229732.99999999834;229964.8501757497;52252.47747522552;52699.209511856585;52771.47228527666;52786.73606632019;52801.471985279655;32390;33023.50464743031;33135.668643313744;34945.30109397798;35018.12454688653;14609.196494192922;14665.120092794466;14736.410543578137;14764;14796.70406591862;10915.454227288605;10958.671239862657;10974.835377469344;10974.945125274482;11001.889981100247;17549.649007020045;17685.734713979302;17753.201105950262;17754.37859674905;17796.911015445097;6632.900506492407;6822.829429264209;6863.142107236596;6921.961705744178;7019.403350715246;767.7850201943353;780.6487080813544;883.1918794303269;933.0948979489884;966.9129778319985;25336.493270134495;26088.217353479427;27177.86411067932;27179.72820271772;27204;765.3150430364792;923.7228831350702;927.5037854747677;943.4905151218265;958.6836344006462;13715.314234288246;14291.42834286617;14367.78448323276;14535.85464145345;14644.707105857824;346852;347682;348614;349835;352490;47923;47953;48136.277955830694;48330.27504587434;48439.27341089816;1068.6206396729206;1113.8217885138502;1163.3717792392013;1179.3867189061639;1214.1258294028285;25158.874205628854;28031.29921751891;28108;28889.42221155523;29292.707072929;41131.177376451706;41796.16407671769;41859.372109417774;41875.16249674928;41922;279211;281724;281908;281935;282407;24133;25298.36754081163;25316.367090822878;26303.21090367292;26477.602835957092;40665;40805.59194408137;41235.793821031904;41512.79243603763;42204.78897605492;34520.309593807484;34907.127321816144;34930.65069349274;35120.47319290264;35450.64549354473;34621.48067778935;34733.65266347304;34756;35213.119672008404;35255.64744352524;33954.15114622055;34520.13699657529;35096.47355289622;35122.82438587791;35425.29149417049;30162.24594385158;31951.520727189574;32157.839210803795;32267.51598726068;32399.676003239667;23953.76046239585;24540.263792086265;24658.753412465645;24992.50014999726;25088.229707167087 14:48:49 INFO - PROCESS | 24844 | |2;jquery.html;331.77605116546323;343.9466882633193;344.2186237241462;346.91500582357327;371.9293334266489;12439.626811195667;12556.121071524996;13228.735425291488;13587.93206033971;16195.352185912549;16211.83788162118;16382.83617163831;16621;17064.402745903895;17188.914055429737;4920.335754673118;4937.111319962405;5060.949390506103;5087.745612719368;5142.999999999981;5009.273655319977;5019.347484826967;5040;5102.617303702219;5115.53960143587;6168.722407491651;6509.93490065098;6524.314946930575;6542.084108224853;6654.168228971379;6496.805095847132;6523.543351965364;6550.967245163743;6853.931460685429;6992.2658120897595;6792.388685018323;6806.319368063154;6811.284815094394;6818.045473633695;6818.4886133540185;22027.66958495624;22939.623622582698;22944.77055229443;23047.65808957692;23086.538269234523;1972.8816271023784;2001.599680063993;2081.9791802081936;2121.904514296845;2485.0556788420467;13987.720245595134;13989.580312590537;14079.50721724745;14139.64650883736;15175.62060948474;1769.938037177683;1784.7055235886064;1794.883332583378;1842.4288470574165;1883.0772921268617;75819.62090189513;79784;80401.59799200967;80708.99999999942;81976.59011704904;15637.3745050199;15915;15975.760363594556;16183.67632647364;16334.673306533803;27849.721502784712;27883.860580696968;27961.16116516508;27963.860180699372;28170.85914570414;8744.91255087441;8800.955995219983;8826.735197944072;9040.321975851837;9148.222401095849;7114.324139206767;7181;7352.63236838156;7377.077865266841;7401.777946661608;7921.207879212033;8179.509229446252;8193.83612327762;8215.383846211558;8378.355415394322;3051.862666180027;3145.653978062411;3245.707886290198;3299.9340013199603;3404.14896275931;762.9198934111838;830.5141492226994;868.5483548554716;902.5216635183364;919.5678031325266;15971;16278.43025494102;16600.750988735177;17283.13584320779;17491.12544372802;774.2644487736673;798.8482188384188;821.9671213151527;861.3539845116163;864.0236532718114;9144.177024067865;9194.95402522983;9427.622895084258;9504.239660827117;9732.318737688294;360948;362838;368114;368512;368874;71024.673149514;72146.63926680334;72763.63618181876;73686.63156684182;74933.62533187299;863.2964134230582;880.823835232956;887.2857349833317;892.4065496444791;897.537768049455;14394.20831854258;14811.925940370229;15158;15186.848131518544;15242.923785381003;35451.82274088613;35512.822435887654;36538.451923221706;36636.81681591575;36761.26477470489;47016;47314;47571.76214118907;47813;48087.75956120197;7089.326513981164;7246.565206087652;7270.127584689871;7316.268373162641;7370.926290737024;8188.549629770419;10389.272750907365;10416.843747343948;11216.83174752363;11656.475458604553;9557.522123893916;9673.015018785907;10040.397576145455;10203.642872499428;10647.574097036031;9700.75748106303;9748.122668959828;9902.25733070037;10015.298929074896;10150.289479736348;9649.951750241204;9675.612975481183;10139.898601013896;10299.176065914859;10720.410377429316;6594.483268848096;6922.757703480354;6930.8613827724175;7443.330100290783;7561.584112873625;9650.372725772802;9668.75828104303;10027.04743049409;10476.423796691255;10512.737181570523 14:48:49 INFO - PROCESS | 24844 | |3;mootools.html;1211.1824518450055;1286.8455785305764;1323.1862404621158;1329.9534516291926;1633.599768056827;6023.728932198051;6562.573432726869;6683.966580167093;6702.597844129349;6871.347222013913;6783.219929708085;6821;6833.965830170842;6835.658217089138;6837.726490940357;2027.1688607670858;2049.2929939170963;2050.7231523744326;2051.5896820635858;2054.3734161080865;1938.5153711572107;1998.5503261766132;2004.8195662390383;2019.5758890632985;2021.3026505855457;1936.390037138297;1945.8735182213181;1968.28157722431;1968.8621796474254;1972.7829938706802;1920.5198700324918;1922.0677971183964;1926.1524929031182;1930.1217945834612;1938.6898096304524;1887.5941672540332;1891.8581106416937;1907.4945139538033;1907.7806052304024;1935.2839449403812;6768.187817461936;6836.213835408942;6836.4530837533375;6843.418309443703;6867.416269617089;4022.7184097113213;4078.5513593504693;4211;4216.177845320164;4216.262154123016;2674.0640775728534;2743.533599288126;2757.0625987164462;2757.986210068937;2762.378464845414;823.6499487717696;1650.5873531617096;1654.454030170041;1706.6671998960207;1723.500184946364;8588.398812083156;8745;8762.780930476725;8860.202581767608;8871.379003469825;3546.556680414948;3671.81640917953;3674.834632441493;3726.4782930389765;3727.906802329909;7694.384449244047;7700.499467534592;7704.383649308042;7712.614369281513;7743.264389882952;4120.526139493936;4138.565450627701;4185.24665560196;4186.372044193335;4189.2668782962855;3428.3143371325837;3534.6465353464964;3684.4473329000866;3791.905202369908;3879.903002424962;3062.402831447891;3099.4885843835514;3190.8723651053697;3230.7738458308163;3246.717546569092;995.3629677006721;1011.0234491568965;1016.3444578246989;1017.9032991865761;1020.882598501167;823.555280148713;853.2235665544325;854.1885209051426;877.1272583779181;882.633707011583;4697.248440249544;4794.113089078462;4942.851714448572;4977.352944117241;5010.423801262901;372.43575982386704;394.0523042083793;399.8980260033673;402.4064504855315;406.8006676728441;3358.916027099342;3493.161641206092;3505.6844883960566;3524.3656141894703;3546.7339949503375;7701.614919254015;8078.676852925934;8110.188981101961;8146.429749917567;8226.259636632733;8004.51972881629;8042.276195142465;8052.919470805334;8062.717804876801;8068.959655201804;6127.325994140641;6204.317525072239;6311.905321420182;6317.30509643948;6330.588511746721;4703.31801888731;4742.905141897212;4805.231163013901;4826.0589185108565;4828.95171048285;7484.176740558425;7521.4734968551675;7529.929269450945;7550.7734767957045;7608.429367797326;7057.8707406814665;7500.662470188964;7514.173440921603;7517.624118794148;7535.73624923125;5935.465808077293;6063;6066.575339726353;6067.666278354733;6072.574919755575;5962.403759624003;6188.535859810442;6220.72006759706;6374.840628984312;6475.740970361321;5994.790182343597;6062.666553339609;6064.666443345658;6093.725782339894;6095.75616975316;5918.467337939606;5967.194428752063;5990.161377407079;6032.969835150796;6143.139960405636;5384.407715151252;5431.9728401359325;5433.402325744244;5448.891022179455;5492.533134683436;4188.602082802129;4299.204647140289;4311.784410779385;4405.207062728739;4564.566366195206;4158.91682166361;4191.3293872980175;4209.906307871247;4304.504981926993;4372.825086996484 14:48:49 INFO - PROCESS | 24844 | |4;prototype.html;272.4482922082782;279.25578333740583;279.7871228225642;284.1291441731094;285.5417055625722;4553.5901768840795;4668.369770081038;4684.437867455901;4716.750061233772;4722.551357621025;4516.932246016312;4591.471980722218;4630.166570017395;4634;4677.111348843718;3679.632036796312;3694.131879008431;3702.962970370302;3706.110533471961;3712.9418115836993;3499.8425070871745;3678.282734866702;3695.316366472198;3699.075231192202;3704.2776658551456;3372.342635551558;3646.74472786905;3646.981765091184;3666.4866918631415;3673.5591728992426;3641.726870484698;3655.6527129922615;3659.14010207601;3666.3583872822283;3672.1186915140306;3180.220845892748;3196.264859082424;3198.152489590237;3203.6956489133495;3203.951940720891;4349.347597860315;4520.841770538048;4549.909001819945;4593.173228818811;4593.356930029799;4081.8775436736946;4092.529359123709;4097.323941549641;4108.26051310764;4111.157212771374;4877.3903262092235;4888.975555122237;4894.339264199323;4914.9111627558505;4919.7540122993705;3435.3301106284284;3438.280859570215;3439.6560343965652;3445.0526105321237;3467.4105402081705;4946.530079642428;5101.515356041207;5123.2315152727015;5126.846194614168;5131.307273518065;3486.5816102067915;3490.47642853574;3491.9127021824656;3493.7554371194283;3495.2485215678753;3529.6823285904393;3537.663921927413;3549.9112522186633;3565.286942611436;3567.2865426914723;4002.8799136025964;4125.401816736557;4132.090939993197;4135.17296540693;4148.481439820022;4896.804127834918;4906.828261010919;4917.459079501252;4922.163232250526;4929.408470983433;1125.8311253311904;1188.607759439383;1192.3680449361852;1196.4077781498213;1202.1885227471596;763.8357753083113;788.787027502571;797.7447216890655;806.5443024691148;812.6546217857505;4260.659147268273;4296.613304802583;4298.634616057608;4302.741835489881;4324;4878.512053823618;4893.926591101136;4919.114559379346;4951.257311403318;4973.875653108629;4094.7952602369764;4251.914961700749;4252.55348188442;4274.4443222381515;4284.186004659105;2598.7006496751624;2605.1142611511796;2607.4133320003225;2613.568761154388;2632.684077910663;4550.544945505423;4593.219152744042;4621.56731413263;4630.86107416778;4631.58315751584;4911.586447419081;4966.180580204298;5003.449620541737;5017.523335283142;5035.571976381975;4608.050847457563;4621.607163391082;4622.168009758208;4629.421322334708;4663.976680116645;4847.684900481458;4855.635827312895;4872.9269060964825;4952.331435256195;4963.791112954845;4729.43246810385;4815.662903596714;4848.908995475989;4849.4908034655855;5021.949780502148;4806.567408933212;4821.734804585782;4822.95177048225;4829.6860704054125;4842.201036829025;3613.4941108245393;3654.812186039601;3665.780053196817;3671.1372827386076;3674.2651469706702;4986.950130498649;5015.5987520997505;5130.820421285386;5133.459962011456;5141.897162056813;4846.321514987834;4850.902981940412;4851.878703032311;4858.562729354375;4859.125357435554;4840.34655321527;4845.733484658236;4876.390451193601;4896.853094407174;4913.877153071202;4650.953490465052;4819.783109760139;4821.902472424506;4832.057748738962;4839.274108883696;4950.925736113889;4964.602831773522;4966.61602430828;4969.130402179677;4969.900601988013;4840.709557426566;4853.883606770392;4873.46391896884;4876.4392094909535;4891.217405215149 14:48:49 INFO - PROCESS | 24844 | |5;yui.html;643.6910283064128;662.8409181796367;679.147669674559;723.75754122369;733.640516147088;11486.827697584544;12237.449314780833;12257.938710306458;12275.263484190955;12306.077044221674;12108.212966157194;12232.143749937508;12261.080418968591;12294.938525307383;12354.320512371816;617.2407938235973;633.1800318587441;748.4124961904906;753.3408267765718;773.350385676032;770.7186876789959;773.8104164479682;774.4075782026754;776.0067114093969;776.0299625468165;827.9793005174859;828.353883970504;829.7801082713086;830.0827585518006;830.2154464031493;650.9381608747161;667.8897981832991;823.1398188892622;823.197382552013;823.5182418285281;580.1095318685839;592.9169916211733;599.4964230046781;600.1208229943154;600.7206648908278;12028.45871935765;12087.576934807328;12112.939435302855;12118.636440906786;12120.81818772719;10550.947245263802;10576.36541807494;10983.61557345489;11063.557457701681;11517.539298428053;10039.096481316716;10084.94957525215;10123.1901447884;10312.484375781256;10388.688339349832;3843.26977874203;3915.138669492707;3920.2355540669582;3946.1515774108416;3995.260876737813;44690;45738.313925290815;46032.079358412644;46177.53822461766;46774.29838552391;8973.237274831712;8986.640534378552;8993.865092023625;9019.143181397756;9055.094490551024;15502.5349239523;15601.297941592655;15695.293711782997;16043.759343609856;16190.352385904433;6009.278886533644;6014.0377539593455;6059.121427393004;6104.328523862371;6133.355997620275;2808.9297767555568;2974.7768917331287;3239.093053944899;3351.8324083795715;3398.575178102737;2412.2642594008644;2455.693038370204;2476.702795664532;2492.4641202141624;2496.975030249674;417.25103439326483;421.9746815191098;422.3284976886703;433.23317727621935;433.7505934087912;428.38954489851835;494.0021157262382;502.6732623794563;528.1559543649506;528.7303475227585;7004;7159.964200178966;7164.319389657974;7166.928330716626;7192.4246060315045;523.4608353395932;529.5445916511796;531.2031952071892;543.1906459375502;548.0737553534514;3284.556584861013;3292.193412613925;3359.7480188985924;3392.118049307198;3478.3391155680847;38790.22419551649;39819.40270895939;48553.27170092452;67923;71076.60310572073;37482.43776343357;38072;39160.80419597884;48317.516824832004;63904.360956390774;44433.115416142304;45169;46105;52817;53784.326858673936;7718.575478348745;7956.164602716632;8225;8272;8344.249017588447;34634;36351.81824090863;36870.63129368672;37334.25331493409;37911;43139.784301078296;43839;49585;49820;50655;10065;10319;10453.738656533647;10454.32046916948;10498.055175034284;11290.056944368407;11301.943490282496;11399.259048161843;11695.415229238673;11813.173077884465;11186.32882027081;11242;11524.59663911759;11833.52665893389;11933.403329833647;11461.197716160123;11678.54295519003;11798.11514136426;11853.966796114539;12199.51201951912;10041.598336066767;10284.125849302893;10287.074163325336;10343.172546196436;10682.03861652424;8525.701600444203;8559.186877246651;8810.559472026245;8968.641254349754;9292.25661947029;8436.493810371398;8981.550922453982;8999.325050621099;9079.68221112258;9273.211776999031 14:48:49 INFO - PROCESS | 24844 | __end_tp_report 14:48:49 INFO - PROCESS | 24844 | __start_cc_report 14:48:49 INFO - PROCESS | 24844 | _x_x_mozilla_cycle_collect,2033 14:48:49 INFO - PROCESS | 24844 | __end_cc_report 14:48:49 INFO - PROCESS | 24844 | __startTimestamp1479336529398__endTimestamp 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | ------- Summary: start ------- 14:48:49 INFO - PROCESS | 24844 | Number of tests: 6 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#0] dojo.html Cycles:165 Average:4602.15 Median:4723.24 stddev:475.60 (10.1%) stddev-sans-first:449.50 14:48:49 INFO - PROCESS | 24844 | Values: 2568.3 2590.4 2607.9 2630.9 2638.1 4365.6 4442.1 4621.9 4622.9 4631.4 4491.8 4641.4 4681.9 4713.7 4723.2 4217.1 4219.8 4240.4 4281.8 4291.3 4274.7 4333.8 4338.0 4354.1 4398.5 4364.3 4371.0 4406.5 4419.8 4442.6 4120.0 4135.1 4137.7 4215.7 4245.1 4248.2 4256.0 4278.5 4286.8 4324.9 4607.7 4612.7 4625.2 4647.2 4687.5 4794.5 4841.2 4853.3 4959.4 4972.7 4904.7 4924.6 4936.3 4937.0 4938.0 4628.0 4707.9 4717.6 4720.2 4721.0 5068.9 5086.4 5089.2 5101.4 5107.0 4879.3 4889.8 4894.9 4895.7 4896.5 4790.9 4857.7 4858.1 4930.4 4979.8 4960.5 4964.5 4987.7 5010.3 5013.9 4894.6 4942.6 4944.3 4951.2 5006.6 4068.9 4159.5 4162.2 4286.0 4288.4 3689.3 3743.4 3743.8 3745.6 3786.1 4371.7 4375.8 4378.0 4412.0 4423.2 4784.5 5051.4 5057.8 5063.6 5065.7 4212.7 4262.8 4272.3 4327.7 4340.6 4523.5 4523.6 4541.4 4558.3 4566.2 4723.8 4727.8 4729.1 4736.4 4775.8 4846.9 4905.8 4909.2 4965.1 5012.2 4636.0 4676.0 4680.2 4692.8 4697.5 4884.6 4889.9 4893.1 4895.0 4897.5 4956.5 4997.5 5010.2 5081.0 5084.7 4859.5 4881.3 4899.4 4906.3 4953.9 4892.9 4902.3 4903.9 4914.0 4955.2 4645.2 4654.0 4655.5 4765.0 4827.4 4876.5 4930.8 4942.1 4944.5 4968.5 4784.4 4793.4 4800.4 4847.0 4848.8 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#1] ext.html Cycles:180 Average:43868.36 Median:35108.47 stddev:76288.98 (217.3%) stddev-sans-first:76433.70 14:48:49 INFO - PROCESS | 24844 | Values: 562.2 585.0 590.4 596.6 597.1 38228.4 39510.0 39885.0 43568.0 43689.3 40239.4 40608.8 40680.8 40718.0 40803.0 7616.5 7759.1 7771.5 7771.5 7771.8 7603.7 7680.5 7683.2 7687.5 7687.5 7578.1 7636.7 7684.8 7697.7 7721.1 7617.2 7657.7 7672.3 7677.2 7681.1 7539.9 7610.8 7621.6 7624.9 7625.7 37080.4 37416.6 37466.4 37507.6 37517.8 2892.8 3027.4 3230.2 3442.7 3579.2 27858.4 28032.4 28120.6 28158.2 29665.3 3421.4 3481.7 3498.7 3500.0 3538.6 229091.0 229472.0 229657.0 229733.0 229964.9 52252.5 52699.2 52771.5 52786.7 52801.5 32390.0 33023.5 33135.7 34945.3 35018.1 14609.2 14665.1 14736.4 14764.0 14796.7 10915.5 10958.7 10974.8 10974.9 11001.9 17549.6 17685.7 17753.2 17754.4 17796.9 6632.9 6822.8 6863.1 6922.0 7019.4 767.8 780.6 883.2 933.1 966.9 25336.5 26088.2 27177.9 27179.7 27204.0 765.3 923.7 927.5 943.5 958.7 13715.3 14291.4 14367.8 14535.9 14644.7 346852.0 347682.0 348614.0 349835.0 352490.0 47923.0 47953.0 48136.3 48330.3 48439.3 1068.6 1113.8 1163.4 1179.4 1214.1 25158.9 28031.3 28108.0 28889.4 29292.7 41131.2 41796.2 41859.4 41875.2 41922.0 279211.0 281724.0 281908.0 281935.0 282407.0 24133.0 25298.4 25316.4 26303.2 26477.6 40665.0 40805.6 41235.8 41512.8 42204.8 34520.3 34907.1 34930.7 35120.5 35450.6 34621.5 34733.7 34756.0 35213.1 35255.6 33954.2 34520.1 35096.5 35122.8 35425.3 30162.2 31951.5 32157.8 32267.5 32399.7 23953.8 24540.3 24658.8 24992.5 25088.2 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#2] jquery.html Cycles:180 Average:24556.27 Median:4928.72 stddev:60548.06 (1228.5%) stddev-sans-first:60690.60 14:48:49 INFO - PROCESS | 24844 | Values: 331.8 343.9 344.2 346.9 371.9 12439.6 12556.1 13228.7 13587.9 16195.4 16211.8 16382.8 16621.0 17064.4 17188.9 4920.3 4937.1 5060.9 5087.7 5143.0 5009.3 5019.3 5040.0 5102.6 5115.5 6168.7 6509.9 6524.3 6542.1 6654.2 6496.8 6523.5 6551.0 6853.9 6992.3 6792.4 6806.3 6811.3 6818.0 6818.5 22027.7 22939.6 22944.8 23047.7 23086.5 1972.9 2001.6 2082.0 2121.9 2485.1 13987.7 13989.6 14079.5 14139.6 15175.6 1769.9 1784.7 1794.9 1842.4 1883.1 75819.6 79784.0 80401.6 80709.0 81976.6 15637.4 15915.0 15975.8 16183.7 16334.7 27849.7 27883.9 27961.2 27963.9 28170.9 8744.9 8801.0 8826.7 9040.3 9148.2 7114.3 7181.0 7352.6 7377.1 7401.8 7921.2 8179.5 8193.8 8215.4 8378.4 3051.9 3145.7 3245.7 3299.9 3404.1 762.9 830.5 868.5 902.5 919.6 15971.0 16278.4 16600.8 17283.1 17491.1 774.3 798.8 822.0 861.4 864.0 9144.2 9195.0 9427.6 9504.2 9732.3 360948.0 362838.0 368114.0 368512.0 368874.0 71024.7 72146.6 72763.6 73686.6 74933.6 863.3 880.8 887.3 892.4 897.5 14394.2 14811.9 15158.0 15186.8 15242.9 35451.8 35512.8 36538.5 36636.8 36761.3 47016.0 47314.0 47571.8 47813.0 48087.8 7089.3 7246.6 7270.1 7316.3 7370.9 8188.5 10389.3 10416.8 11216.8 11656.5 9557.5 9673.0 10040.4 10203.6 10647.6 9700.8 9748.1 9902.3 10015.3 10150.3 9650.0 9675.6 10139.9 10299.2 10720.4 6594.5 6922.8 6930.9 7443.3 7561.6 9650.4 9668.8 10027.0 10476.4 10512.7 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#3] mootools.html Cycles:180 Average:4502.49 Median:4768.51 stddev:2376.44 (49.8%) stddev-sans-first:2370.23 14:48:49 INFO - PROCESS | 24844 | Values: 1211.2 1286.8 1323.2 1330.0 1633.6 6023.7 6562.6 6684.0 6702.6 6871.3 6783.2 6821.0 6834.0 6835.7 6837.7 2027.2 2049.3 2050.7 2051.6 2054.4 1938.5 1998.6 2004.8 2019.6 2021.3 1936.4 1945.9 1968.3 1968.9 1972.8 1920.5 1922.1 1926.2 1930.1 1938.7 1887.6 1891.9 1907.5 1907.8 1935.3 6768.2 6836.2 6836.5 6843.4 6867.4 4022.7 4078.6 4211.0 4216.2 4216.3 2674.1 2743.5 2757.1 2758.0 2762.4 823.6 1650.6 1654.5 1706.7 1723.5 8588.4 8745.0 8762.8 8860.2 8871.4 3546.6 3671.8 3674.8 3726.5 3727.9 7694.4 7700.5 7704.4 7712.6 7743.3 4120.5 4138.6 4185.2 4186.4 4189.3 3428.3 3534.6 3684.4 3791.9 3879.9 3062.4 3099.5 3190.9 3230.8 3246.7 995.4 1011.0 1016.3 1017.9 1020.9 823.6 853.2 854.2 877.1 882.6 4697.2 4794.1 4942.9 4977.4 5010.4 372.4 394.1 399.9 402.4 406.8 3358.9 3493.2 3505.7 3524.4 3546.7 7701.6 8078.7 8110.2 8146.4 8226.3 8004.5 8042.3 8052.9 8062.7 8069.0 6127.3 6204.3 6311.9 6317.3 6330.6 4703.3 4742.9 4805.2 4826.1 4829.0 7484.2 7521.5 7529.9 7550.8 7608.4 7057.9 7500.7 7514.2 7517.6 7535.7 5935.5 6063.0 6066.6 6067.7 6072.6 5962.4 6188.5 6220.7 6374.8 6475.7 5994.8 6062.7 6064.7 6093.7 6095.8 5918.5 5967.2 5990.2 6033.0 6143.1 5384.4 5432.0 5433.4 5448.9 5492.5 4188.6 4299.2 4311.8 4405.2 4564.6 4158.9 4191.3 4209.9 4304.5 4372.8 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#4] prototype.html Cycles:180 Average:4039.18 Median:4614.81 stddev:1180.44 (25.6%) stddev-sans-first:1149.40 14:48:49 INFO - PROCESS | 24844 | Values: 272.4 279.3 279.8 284.1 285.5 4553.6 4668.4 4684.4 4716.8 4722.6 4516.9 4591.5 4630.2 4634.0 4677.1 3679.6 3694.1 3703.0 3706.1 3712.9 3499.8 3678.3 3695.3 3699.1 3704.3 3372.3 3646.7 3647.0 3666.5 3673.6 3641.7 3655.7 3659.1 3666.4 3672.1 3180.2 3196.3 3198.2 3203.7 3204.0 4349.3 4520.8 4549.9 4593.2 4593.4 4081.9 4092.5 4097.3 4108.3 4111.2 4877.4 4889.0 4894.3 4914.9 4919.8 3435.3 3438.3 3439.7 3445.1 3467.4 4946.5 5101.5 5123.2 5126.8 5131.3 3486.6 3490.5 3491.9 3493.8 3495.2 3529.7 3537.7 3549.9 3565.3 3567.3 4002.9 4125.4 4132.1 4135.2 4148.5 4896.8 4906.8 4917.5 4922.2 4929.4 1125.8 1188.6 1192.4 1196.4 1202.2 763.8 788.8 797.7 806.5 812.7 4260.7 4296.6 4298.6 4302.7 4324.0 4878.5 4893.9 4919.1 4951.3 4973.9 4094.8 4251.9 4252.6 4274.4 4284.2 2598.7 2605.1 2607.4 2613.6 2632.7 4550.5 4593.2 4621.6 4630.9 4631.6 4911.6 4966.2 5003.4 5017.5 5035.6 4608.1 4621.6 4622.2 4629.4 4664.0 4847.7 4855.6 4872.9 4952.3 4963.8 4729.4 4815.7 4848.9 4849.5 5021.9 4806.6 4821.7 4823.0 4829.7 4842.2 3613.5 3654.8 3665.8 3671.1 3674.3 4987.0 5015.6 5130.8 5133.5 5141.9 4846.3 4850.9 4851.9 4858.6 4859.1 4840.3 4845.7 4876.4 4896.9 4913.9 4651.0 4819.8 4821.9 4832.1 4839.3 4950.9 4964.6 4966.6 4969.1 4969.9 4840.7 4853.9 4873.5 4876.4 4891.2 14:48:49 INFO - PROCESS | 24844 | 14:48:49 INFO - PROCESS | 24844 | [#5] yui.html Cycles:180 Average:13131.13 Median:433.49 stddev:15874.96 (3662.1%) stddev-sans-first:15891.80 14:48:49 INFO - PROCESS | 24844 | Values: 643.7 662.8 679.1 723.8 733.6 11486.8 12237.4 12257.9 12275.3 12306.1 12108.2 12232.1 12261.1 12294.9 12354.3 617.2 633.2 748.4 753.3 773.4 770.7 773.8 774.4 776.0 776.0 828.0 828.4 829.8 830.1 830.2 650.9 667.9 823.1 823.2 823.5 580.1 592.9 599.5 600.1 600.7 12028.5 12087.6 12112.9 12118.6 12120.8 10550.9 10576.4 10983.6 11063.6 11517.5 10039.1 10084.9 10123.2 10312.5 10388.7 3843.3 3915.1 3920.2 3946.2 3995.3 44690.0 45738.3 46032.1 46177.5 46774.3 8973.2 8986.6 8993.9 9019.1 9055.1 15502.5 15601.3 15695.3 16043.8 16190.4 6009.3 6014.0 6059.1 6104.3 6133.4 2808.9 2974.8 3239.1 3351.8 3398.6 2412.3 2455.7 2476.7 2492.5 2497.0 417.3 422.0 422.3 433.2 433.8 428.4 494.0 502.7 528.2 528.7 7004.0 7160.0 7164.3 7166.9 7192.4 523.5 529.5 531.2 543.2 548.1 3284.6 3292.2 3359.7 3392.1 3478.3 38790.2 39819.4 48553.3 67923.0 71076.6 37482.4 38072.0 39160.8 48317.5 63904.4 44433.1 45169.0 46105.0 52817.0 53784.3 7718.6 7956.2 8225.0 8272.0 8344.2 34634.0 36351.8 36870.6 37334.3 37911.0 43139.8 43839.0 49585.0 49820.0 50655.0 10065.0 10319.0 10453.7 10454.3 10498.1 11290.1 11301.9 11399.3 11695.4 11813.2 11186.3 11242.0 11524.6 11833.5 11933.4 11461.2 11678.5 11798.1 11854.0 12199.5 10041.6 10284.1 10287.1 10343.2 10682.0 8525.7 8559.2 8810.6 8968.6 9292.3 8436.5 8981.6 8999.3 9079.7 9273.2 14:48:49 INFO - PROCESS | 24844 | -------- Summary: end -------- 14:48:49 INFO - PROCESS | 24844 | 14:48:50 INFO - TEST-INFO | 24844: exit 0 14:48:50 INFO - TEST-OK | dromaeo_css | took 1121824ms 14:48:50 INFO - TEST-START | kraken 14:48:50 INFO - operating with platform_type : linux_ 14:48:50 INFO - Initialising browser for kraken test... 14:48:50 INFO - TEST-INFO | started process 25149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEhYbN8/profile http://localhost:46360/getInfo.html) 14:48:54 INFO - PROCESS | 25149 | __metrics Screen width/height:1600/1200 14:48:54 INFO - PROCESS | 25149 | colorDepth:24 14:48:54 INFO - PROCESS | 25149 | Browser inner width/height: 1024/697 14:48:54 INFO - PROCESS | 25149 | __metrics 14:48:55 INFO - PROCESS | 25149 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:48:55 INFO - PROCESS | 25149 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:48:55 INFO - PROCESS | 25149 | console.error: PushService: 14:48:55 INFO - PROCESS | 25149 | stateChangeProcessEnqueue: Error transitioning state 14:48:55 INFO - PROCESS | 25149 | UnknownError 14:48:56 INFO - TEST-INFO | 25149: exit 0 14:48:56 INFO - Browser initialized. 14:48:56 INFO - Running cycle 1/1 for kraken test... 14:48:56 INFO - TEST-INFO | started process 25231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEhYbN8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:08 INFO - PROCESS | 25231 | RSS: Main: 176644096 14:49:08 INFO - PROCESS | 25231 | 14:49:11 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46360/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:12 INFO - PROCESS | 25231 | RSS: Main: 242298880 14:49:12 INFO - PROCESS | 25231 | 14:49:15 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46360/tests/kraken/driver.html?testName=audio-dft) 14:49:15 INFO - PROCESS | 25231 | RSS: Main: 220098560 14:49:15 INFO - PROCESS | 25231 | 14:49:19 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46360/tests/kraken/driver.html?testName=audio-fft) 14:49:19 INFO - PROCESS | 25231 | RSS: Main: 301924352 14:49:19 INFO - PROCESS | 25231 | 14:49:21 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46360/tests/kraken/driver.html?testName=audio-oscillator) 14:49:22 INFO - PROCESS | 25231 | RSS: Main: 212426752 14:49:22 INFO - PROCESS | 25231 | 14:49:24 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46360/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:25 INFO - PROCESS | 25231 | RSS: Main: 215724032 14:49:25 INFO - PROCESS | 25231 | 14:49:28 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46360/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:29 INFO - PROCESS | 25231 | RSS: Main: 254865408 14:49:29 INFO - PROCESS | 25231 | 14:49:32 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46360/tests/kraken/driver.html?testName=imaging-desaturate) 14:49:33 INFO - PROCESS | 25231 | RSS: Main: 249507840 14:49:33 INFO - PROCESS | 25231 | 14:49:36 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46360/tests/kraken/driver.html?testName=json-parse-financial) 14:49:36 INFO - PROCESS | 25231 | RSS: Main: 251645952 14:49:36 INFO - PROCESS | 25231 | 14:49:38 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46360/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:39 INFO - PROCESS | 25231 | RSS: Main: 215044096 14:49:39 INFO - PROCESS | 25231 | 14:49:41 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:49:41 INFO - PROCESS | 25231 | RSS: Main: 222322688 14:49:41 INFO - PROCESS | 25231 | 14:49:43 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:49:44 INFO - PROCESS | 25231 | RSS: Main: 231481344 14:49:44 INFO - PROCESS | 25231 | 14:49:47 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:49:48 INFO - PROCESS | 25231 | RSS: Main: 239579136 14:49:48 INFO - PROCESS | 25231 | 14:49:51 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:49:51 INFO - PROCESS | 25231 | RSS: Main: 236617728 14:49:51 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | Cycle 1(1): loaded http://localhost:46360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46360/tests/kraken/driver.html?testName=ai-astar) 14:49:53 INFO - PROCESS | 25231 | RSS: Main: 288714752 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | __start_tp_report 14:49:53 INFO - PROCESS | 25231 | _x_x_mozilla_page_load 14:49:53 INFO - PROCESS | 25231 | _x_x_mozilla_page_load_details 14:49:53 INFO - PROCESS | 25231 | |i|pagename|runs| 14:49:53 INFO - PROCESS | 25231 | |0;ai-astar;110;116;118;119;117;118;118;117;118;119 14:49:53 INFO - PROCESS | 25231 | |1;audio-beat-detection;155;138;136;183;136;136;138;130;165;131 14:49:53 INFO - PROCESS | 25231 | |2;audio-dft;163;163;164;161;162;160;164;164;163;162 14:49:53 INFO - PROCESS | 25231 | |3;audio-fft;77;76;76;108;78;79;78;78;99;77 14:49:53 INFO - PROCESS | 25231 | |4;audio-oscillator;85;85;121;84;84;84;105;85;83;116 14:49:53 INFO - PROCESS | 25231 | |5;imaging-gaussian-blur;119;119;119;120;118;119;117;117;117;117 14:49:53 INFO - PROCESS | 25231 | |6;imaging-darkroom;148;147;147;147;147;148;146;147;146;147 14:49:53 INFO - PROCESS | 25231 | |7;imaging-desaturate;96;95;95;95;96;96;95;95;96;95 14:49:53 INFO - PROCESS | 25231 | |8;json-parse-financial;66;64;65;65;65;65;66;65;65;64 14:49:53 INFO - PROCESS | 25231 | |9;json-stringify-tinderbox;66;66;66;66;66;68;66;67;71;66 14:49:53 INFO - PROCESS | 25231 | |10;stanford-crypto-aes;84;84;84;85;85;85;84;85;84;89 14:49:53 INFO - PROCESS | 25231 | |11;stanford-crypto-ccm;145;143;145;154;151;143;141;143;204;158 14:49:53 INFO - PROCESS | 25231 | |12;stanford-crypto-pbkdf2;181;182;181;183;182;178;179;178;179;180 14:49:53 INFO - PROCESS | 25231 | |13;stanford-crypto-sha256-iterative;71;72;70;70;71;71;70;71;69;72 14:49:53 INFO - PROCESS | 25231 | __end_tp_report 14:49:53 INFO - PROCESS | 25231 | __start_cc_report 14:49:53 INFO - PROCESS | 25231 | _x_x_mozilla_cycle_collect,1949 14:49:53 INFO - PROCESS | 25231 | __end_cc_report 14:49:53 INFO - PROCESS | 25231 | __startTimestamp1479336593930__endTimestamp 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | ------- Summary: start ------- 14:49:53 INFO - PROCESS | 25231 | Number of tests: 14 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#0] ai-astar Cycles:10 Average:117.00 Median:118.00 stddev:2.62 (2.2%) stddev-sans-first:0.97 14:49:53 INFO - PROCESS | 25231 | Values: 110.0 116.0 118.0 119.0 117.0 118.0 118.0 117.0 118.0 119.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:138.00 stddev:17.34 (12.6%) stddev-sans-first:17.99 14:49:53 INFO - PROCESS | 25231 | Values: 155.0 138.0 136.0 183.0 136.0 136.0 138.0 130.0 165.0 131.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 14:49:53 INFO - PROCESS | 25231 | Values: 163.0 163.0 164.0 161.0 162.0 160.0 164.0 164.0 163.0 162.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#3] audio-fft Cycles:10 Average:82.60 Median:78.00 stddev:11.26 (14.4%) stddev-sans-first:11.76 14:49:53 INFO - PROCESS | 25231 | Values: 77.0 76.0 76.0 108.0 78.0 79.0 78.0 78.0 99.0 77.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:84.00 stddev:14.88 (17.7%) stddev-sans-first:15.48 14:49:53 INFO - PROCESS | 25231 | Values: 85.0 85.0 121.0 84.0 84.0 84.0 105.0 85.0 83.0 116.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:119.00 stddev:1.14 (1.0%) stddev-sans-first:1.17 14:49:53 INFO - PROCESS | 25231 | Values: 119.0 119.0 119.0 120.0 118.0 119.0 117.0 117.0 117.0 117.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 14:49:53 INFO - PROCESS | 25231 | Values: 148.0 147.0 147.0 147.0 147.0 148.0 146.0 147.0 146.0 147.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 14:49:53 INFO - PROCESS | 25231 | Values: 96.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 14:49:53 INFO - PROCESS | 25231 | Values: 66.0 64.0 65.0 65.0 65.0 65.0 66.0 65.0 65.0 64.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.00 stddev:1.62 (2.5%) stddev-sans-first:1.69 14:49:53 INFO - PROCESS | 25231 | Values: 66.0 66.0 66.0 66.0 66.0 68.0 66.0 67.0 71.0 66.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 14:49:53 INFO - PROCESS | 25231 | Values: 84.0 84.0 84.0 85.0 85.0 85.0 84.0 85.0 84.0 89.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:148.00 stddev:18.86 (12.7%) stddev-sans-first:19.80 14:49:53 INFO - PROCESS | 25231 | Values: 145.0 143.0 145.0 154.0 151.0 143.0 141.0 143.0 204.0 158.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:181.00 stddev:1.77 (1.0%) stddev-sans-first:1.86 14:49:53 INFO - PROCESS | 25231 | Values: 181.0 182.0 181.0 183.0 182.0 178.0 179.0 178.0 179.0 180.0 14:49:53 INFO - PROCESS | 25231 | 14:49:53 INFO - PROCESS | 25231 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:0.95 (1.3%) stddev-sans-first:1.00 14:49:53 INFO - PROCESS | 25231 | Values: 71.0 72.0 70.0 70.0 71.0 71.0 70.0 71.0 69.0 72.0 14:49:53 INFO - PROCESS | 25231 | -------- Summary: end -------- 14:49:53 INFO - PROCESS | 25231 | 14:49:55 INFO - TEST-INFO | 25231: exit 0 14:49:55 INFO - TEST-OK | kraken | took 64917ms 14:49:55 INFO - SUITE-END | took 1186s 14:49:55 INFO - Completed test suite (00:19:46) 14:49:55 INFO - javascript benchmark 14:49:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2568.2937192272125, 2590.378309205789, 2607.9478410431784, 2630.947381052379, 2638.1294172922926, 4365.607095361417, 4442.11157768446, 4621.930671039937, 4622.8844278893, 4631.397918270624, 4491.820327186909, 4641.419822522184, 4681.929771053428, 4713.693609915362, 4723.244280915045, 4217.072244106307, 4219.767912764797, 4240.363945408198, 4281.828726850922, 4291.334843099325, 4274.679399045069, 4333.761643109628, 4338.045629961403, 4354.1074079813725, 4398.450193725785, 4364.258076127066, 4370.994671225604, 4406.471223453206, 4419.823207071713, 4442.555744425547, 4119.970007498126, 4135.0902801383945, 4137.668986481074, 4215.683823713233, 4245.12974840157, 4248.171606536726, 4255.957440425587, 4278.465191851019, 4286.828526858922, 4324.870253892388, 4607.746573938432, 4612.677112602119, 4625.236835922069, 4647.163510568096, 4687.531246875319, 4794.49657785931, 4841.15279826029, 4853.320535125085, 4959.380077490314, 4972.701637901738, 4904.681195722266, 4924.630652701062, 4936.333594964705, 4936.975315123437, 4937.975310123462, 4628.028114096044, 4707.905841883143, 4717.551832575899, 4720.150372932836, 4720.95279047212, 5068.898622027539, 5086.364204474441, 5089.185730283211, 5101.362329708786, 5106.974465127725, 4879.2925025871045, 4889.804407823718, 4894.902101957941, 4895.657303988758, 4896.4858689837765, 4790.928136077961, 4857.708537487762, 4858.149823780825, 4930.383702037246, 4979.825706100269, 4960.454350021495, 4964.453910069889, 4987.650864439527, 5010.323606313101, 5013.874653133701, 4894.608431325485, 4942.555170034714, 4944.332515110414, 4951.183054795918, 5006.64953453262, 4068.8575899843363, 4159.521655009652, 4162.188373267244, 4286.014216730169, 4288.421063156434, 3689.2805902849223, 3743.3823419136083, 3743.831527581211, 3745.6441638044344, 3786.091338078841, 4371.715838470489, 4375.803088861009, 4377.978110109493, 4412.007298357844, 4423.181711383404, 4784.473707892129, 5051.368578927634, 5057.8229761958155, 5063.620228482879, 5065.721385323769, 4212.662986961036, 4262.808173632132, 4272.252355837716, 4327.7186982846015, 4340.565943405697, 4523.457185137804, 4523.570260825217, 4541.364209010675, 4558.293464513005, 4566.223741963874, 4723.83466578675, 4727.810887564459, 4729.10147072069, 4736.384270044872, 4775.832845850378, 4846.903061938812, 4905.77923993414, 4909.1654418748185, 4965.0566392385335, 5012.248162775612, 4635.976820116013, 4676.018036212434, 4680.204365257846, 4692.812287508462, 4697.530246975275, 4884.5603895649565, 4889.9022019560125, 4893.09477746618, 4895.0, 4897.534734200245, 4956.479569645136, 4997.475265097258, 5010.248462730619, 5081.034603425337, 5084.745762711924, 4859.4654587996, 4881.29221262922, 4899.412070551557, 4906.288588154769, 4953.925691114564, 4892.877678057935, 4902.289168070681, 4903.926441103315, 4914.0, 4955.231939049453, 4645.233536466445, 4653.976730116327, 4655.511171327097, 4765.046990601824, 4827.444843843003, 4876.536729010738, 4930.802767889244, 4942.085714142895, 4944.456109827988, 4968.503149685002, 4784.449788274393, 4793.448753393265, 4800.399950006249, 4846.975765121152, 4848.781804818862], "value": 4572.173860039709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.2465895699763, 585.0200913469937, 590.4213870407001, 596.6449962272449, 597.0715537339441, 38228.426573601384, 39510.0, 39885.0, 43568.0, 43689.34465983013, 40239.39640905389, 40608.796956015256, 40680.796596017055, 40718.0, 40803.0, 7616.50492717973, 7759.107702614215, 7771.533707977511, 7771.533707977511, 7771.844563108734, 7603.733869314575, 7680.539167649931, 7683.1548529661695, 7687.461877668537, 7687.461877668566, 7578.052743407075, 7636.732714354998, 7684.807879803022, 7697.692092316301, 7721.073471183466, 7617.2001939796355, 7657.731979380695, 7672.309492145678, 7677.155512893577, 7681.078270607507, 7539.886901696479, 7610.8097297568, 7621.618919054025, 7624.885626715603, 7625.656845441913, 37080.44379334312, 37416.625833741586, 37466.43800342997, 37507.624923750685, 37517.81241093777, 2892.783041271913, 3027.409655117253, 3230.224746060952, 3442.7245820334315, 3579.194681196736, 27858.442831143264, 28032.439351212863, 28120.578191327146, 28158.15525534237, 29665.25836854096, 3421.4354631485776, 3481.669241422061, 3498.737594680409, 3500.0, 3538.5930617978747, 229091.0, 229472.0, 229657.0, 229732.99999999834, 229964.8501757497, 52252.47747522552, 52699.209511856585, 52771.47228527666, 52786.73606632019, 52801.471985279655, 32390.0, 33023.50464743031, 33135.668643313744, 34945.30109397798, 35018.12454688653, 14609.196494192922, 14665.120092794466, 14736.410543578137, 14764.0, 14796.70406591862, 10915.454227288605, 10958.671239862657, 10974.835377469344, 10974.945125274482, 11001.889981100247, 17549.649007020045, 17685.734713979302, 17753.201105950262, 17754.37859674905, 17796.911015445097, 6632.900506492407, 6822.829429264209, 6863.142107236596, 6921.961705744178, 7019.403350715246, 767.7850201943353, 780.6487080813544, 883.1918794303269, 933.0948979489884, 966.9129778319985, 25336.493270134495, 26088.217353479427, 27177.86411067932, 27179.72820271772, 27204.0, 765.3150430364792, 923.7228831350702, 927.5037854747677, 943.4905151218265, 958.6836344006462, 13715.314234288246, 14291.42834286617, 14367.78448323276, 14535.85464145345, 14644.707105857824, 346852.0, 347682.0, 348614.0, 349835.0, 352490.0, 47923.0, 47953.0, 48136.277955830694, 48330.27504587434, 48439.27341089816, 1068.6206396729206, 1113.8217885138502, 1163.3717792392013, 1179.3867189061639, 1214.1258294028285, 25158.874205628854, 28031.29921751891, 28108.0, 28889.42221155523, 29292.707072929, 41131.177376451706, 41796.16407671769, 41859.372109417774, 41875.16249674928, 41922.0, 279211.0, 281724.0, 281908.0, 281935.0, 282407.0, 24133.0, 25298.36754081163, 25316.367090822878, 26303.21090367292, 26477.602835957092, 40665.0, 40805.59194408137, 41235.793821031904, 41512.79243603763, 42204.78897605492, 34520.309593807484, 34907.127321816144, 34930.65069349274, 35120.47319290264, 35450.64549354473, 34621.48067778935, 34733.65266347304, 34756.0, 35213.119672008404, 35255.64744352524, 33954.15114622055, 34520.13699657529, 35096.47355289622, 35122.82438587791, 35425.29149417049, 30162.24594385158, 31951.520727189574, 32157.839210803795, 32267.51598726068, 32399.676003239667, 23953.76046239585, 24540.263792086265, 24658.753412465645, 24992.50014999726, 25088.229707167087], "value": 16911.58187629995, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.77605116546323, 343.9466882633193, 344.2186237241462, 346.91500582357327, 371.9293334266489, 12439.626811195667, 12556.121071524996, 13228.735425291488, 13587.93206033971, 16195.352185912549, 16211.83788162118, 16382.83617163831, 16621.0, 17064.402745903895, 17188.914055429737, 4920.335754673118, 4937.111319962405, 5060.949390506103, 5087.745612719368, 5142.999999999981, 5009.273655319977, 5019.347484826967, 5040.0, 5102.617303702219, 5115.53960143587, 6168.722407491651, 6509.93490065098, 6524.314946930575, 6542.084108224853, 6654.168228971379, 6496.805095847132, 6523.543351965364, 6550.967245163743, 6853.931460685429, 6992.2658120897595, 6792.388685018323, 6806.319368063154, 6811.284815094394, 6818.045473633695, 6818.4886133540185, 22027.66958495624, 22939.623622582698, 22944.77055229443, 23047.65808957692, 23086.538269234523, 1972.8816271023784, 2001.599680063993, 2081.9791802081936, 2121.904514296845, 2485.0556788420467, 13987.720245595134, 13989.580312590537, 14079.50721724745, 14139.64650883736, 15175.62060948474, 1769.938037177683, 1784.7055235886064, 1794.883332583378, 1842.4288470574165, 1883.0772921268617, 75819.62090189513, 79784.0, 80401.59799200967, 80708.99999999942, 81976.59011704904, 15637.3745050199, 15915.0, 15975.760363594556, 16183.67632647364, 16334.673306533803, 27849.721502784712, 27883.860580696968, 27961.16116516508, 27963.860180699372, 28170.85914570414, 8744.91255087441, 8800.955995219983, 8826.735197944072, 9040.321975851837, 9148.222401095849, 7114.324139206767, 7181.0, 7352.63236838156, 7377.077865266841, 7401.777946661608, 7921.207879212033, 8179.509229446252, 8193.83612327762, 8215.383846211558, 8378.355415394322, 3051.862666180027, 3145.653978062411, 3245.707886290198, 3299.9340013199603, 3404.14896275931, 762.9198934111838, 830.5141492226994, 868.5483548554716, 902.5216635183364, 919.5678031325266, 15971.0, 16278.43025494102, 16600.750988735177, 17283.13584320779, 17491.12544372802, 774.2644487736673, 798.8482188384188, 821.9671213151527, 861.3539845116163, 864.0236532718114, 9144.177024067865, 9194.95402522983, 9427.622895084258, 9504.239660827117, 9732.318737688294, 360948.0, 362838.0, 368114.0, 368512.0, 368874.0, 71024.673149514, 72146.63926680334, 72763.63618181876, 73686.63156684182, 74933.62533187299, 863.2964134230582, 880.823835232956, 887.2857349833317, 892.4065496444791, 897.537768049455, 14394.20831854258, 14811.925940370229, 15158.0, 15186.848131518544, 15242.923785381003, 35451.82274088613, 35512.822435887654, 36538.451923221706, 36636.81681591575, 36761.26477470489, 47016.0, 47314.0, 47571.76214118907, 47813.0, 48087.75956120197, 7089.326513981164, 7246.565206087652, 7270.127584689871, 7316.268373162641, 7370.926290737024, 8188.549629770419, 10389.272750907365, 10416.843747343948, 11216.83174752363, 11656.475458604553, 9557.522123893916, 9673.015018785907, 10040.397576145455, 10203.642872499428, 10647.574097036031, 9700.75748106303, 9748.122668959828, 9902.25733070037, 10015.298929074896, 10150.289479736348, 9649.951750241204, 9675.612975481183, 10139.898601013896, 10299.176065914859, 10720.410377429316, 6594.483268848096, 6922.757703480354, 6930.8613827724175, 7443.330100290783, 7561.584112873625, 9650.372725772802, 9668.75828104303, 10027.04743049409, 10476.423796691255, 10512.737181570523], "value": 8925.485376257935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1211.1824518450055, 1286.8455785305764, 1323.1862404621158, 1329.9534516291926, 1633.599768056827, 6023.728932198051, 6562.573432726869, 6683.966580167093, 6702.597844129349, 6871.347222013913, 6783.219929708085, 6821.0, 6833.965830170842, 6835.658217089138, 6837.726490940357, 2027.1688607670858, 2049.2929939170963, 2050.7231523744326, 2051.5896820635858, 2054.3734161080865, 1938.5153711572107, 1998.5503261766132, 2004.8195662390383, 2019.5758890632985, 2021.3026505855457, 1936.390037138297, 1945.8735182213181, 1968.28157722431, 1968.8621796474254, 1972.7829938706802, 1920.5198700324918, 1922.0677971183964, 1926.1524929031182, 1930.1217945834612, 1938.6898096304524, 1887.5941672540332, 1891.8581106416937, 1907.4945139538033, 1907.7806052304024, 1935.2839449403812, 6768.187817461936, 6836.213835408942, 6836.4530837533375, 6843.418309443703, 6867.416269617089, 4022.7184097113213, 4078.5513593504693, 4211.0, 4216.177845320164, 4216.262154123016, 2674.0640775728534, 2743.533599288126, 2757.0625987164462, 2757.986210068937, 2762.378464845414, 823.6499487717696, 1650.5873531617096, 1654.454030170041, 1706.6671998960207, 1723.500184946364, 8588.398812083156, 8745.0, 8762.780930476725, 8860.202581767608, 8871.379003469825, 3546.556680414948, 3671.81640917953, 3674.834632441493, 3726.4782930389765, 3727.906802329909, 7694.384449244047, 7700.499467534592, 7704.383649308042, 7712.614369281513, 7743.264389882952, 4120.526139493936, 4138.565450627701, 4185.24665560196, 4186.372044193335, 4189.2668782962855, 3428.3143371325837, 3534.6465353464964, 3684.4473329000866, 3791.905202369908, 3879.903002424962, 3062.402831447891, 3099.4885843835514, 3190.8723651053697, 3230.7738458308163, 3246.717546569092, 995.3629677006721, 1011.0234491568965, 1016.3444578246989, 1017.9032991865761, 1020.882598501167, 823.555280148713, 853.2235665544325, 854.1885209051426, 877.1272583779181, 882.633707011583, 4697.248440249544, 4794.113089078462, 4942.851714448572, 4977.352944117241, 5010.423801262901, 372.43575982386704, 394.0523042083793, 399.8980260033673, 402.4064504855315, 406.8006676728441, 3358.916027099342, 3493.161641206092, 3505.6844883960566, 3524.3656141894703, 3546.7339949503375, 7701.614919254015, 8078.676852925934, 8110.188981101961, 8146.429749917567, 8226.259636632733, 8004.51972881629, 8042.276195142465, 8052.919470805334, 8062.717804876801, 8068.959655201804, 6127.325994140641, 6204.317525072239, 6311.905321420182, 6317.30509643948, 6330.588511746721, 4703.31801888731, 4742.905141897212, 4805.231163013901, 4826.0589185108565, 4828.95171048285, 7484.176740558425, 7521.4734968551675, 7529.929269450945, 7550.7734767957045, 7608.429367797326, 7057.8707406814665, 7500.662470188964, 7514.173440921603, 7517.624118794148, 7535.73624923125, 5935.465808077293, 6063.0, 6066.575339726353, 6067.666278354733, 6072.574919755575, 5962.403759624003, 6188.535859810442, 6220.72006759706, 6374.840628984312, 6475.740970361321, 5994.790182343597, 6062.666553339609, 6064.666443345658, 6093.725782339894, 6095.75616975316, 5918.467337939606, 5967.194428752063, 5990.161377407079, 6032.969835150796, 6143.139960405636, 5384.407715151252, 5431.9728401359325, 5433.402325744244, 5448.891022179455, 5492.533134683436, 4188.602082802129, 4299.204647140289, 4311.784410779385, 4405.207062728739, 4564.566366195206, 4158.91682166361, 4191.3293872980175, 4209.906307871247, 4304.504981926993, 4372.825086996484], "value": 3673.9586015616005, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.4482922082782, 279.25578333740583, 279.7871228225642, 284.1291441731094, 285.5417055625722, 4553.5901768840795, 4668.369770081038, 4684.437867455901, 4716.750061233772, 4722.551357621025, 4516.932246016312, 4591.471980722218, 4630.166570017395, 4634.0, 4677.111348843718, 3679.632036796312, 3694.131879008431, 3702.962970370302, 3706.110533471961, 3712.9418115836993, 3499.8425070871745, 3678.282734866702, 3695.316366472198, 3699.075231192202, 3704.2776658551456, 3372.342635551558, 3646.74472786905, 3646.981765091184, 3666.4866918631415, 3673.5591728992426, 3641.726870484698, 3655.6527129922615, 3659.14010207601, 3666.3583872822283, 3672.1186915140306, 3180.220845892748, 3196.264859082424, 3198.152489590237, 3203.6956489133495, 3203.951940720891, 4349.347597860315, 4520.841770538048, 4549.909001819945, 4593.173228818811, 4593.356930029799, 4081.8775436736946, 4092.529359123709, 4097.323941549641, 4108.26051310764, 4111.157212771374, 4877.3903262092235, 4888.975555122237, 4894.339264199323, 4914.9111627558505, 4919.7540122993705, 3435.3301106284284, 3438.280859570215, 3439.6560343965652, 3445.0526105321237, 3467.4105402081705, 4946.530079642428, 5101.515356041207, 5123.2315152727015, 5126.846194614168, 5131.307273518065, 3486.5816102067915, 3490.47642853574, 3491.9127021824656, 3493.7554371194283, 3495.2485215678753, 3529.6823285904393, 3537.663921927413, 3549.9112522186633, 3565.286942611436, 3567.2865426914723, 4002.8799136025964, 4125.401816736557, 4132.090939993197, 4135.17296540693, 4148.481439820022, 4896.804127834918, 4906.828261010919, 4917.459079501252, 4922.163232250526, 4929.408470983433, 1125.8311253311904, 1188.607759439383, 1192.3680449361852, 1196.4077781498213, 1202.1885227471596, 763.8357753083113, 788.787027502571, 797.7447216890655, 806.5443024691148, 812.6546217857505, 4260.659147268273, 4296.613304802583, 4298.634616057608, 4302.741835489881, 4324.0, 4878.512053823618, 4893.926591101136, 4919.114559379346, 4951.257311403318, 4973.875653108629, 4094.7952602369764, 4251.914961700749, 4252.55348188442, 4274.4443222381515, 4284.186004659105, 2598.7006496751624, 2605.1142611511796, 2607.4133320003225, 2613.568761154388, 2632.684077910663, 4550.544945505423, 4593.219152744042, 4621.56731413263, 4630.86107416778, 4631.58315751584, 4911.586447419081, 4966.180580204298, 5003.449620541737, 5017.523335283142, 5035.571976381975, 4608.050847457563, 4621.607163391082, 4622.168009758208, 4629.421322334708, 4663.976680116645, 4847.684900481458, 4855.635827312895, 4872.9269060964825, 4952.331435256195, 4963.791112954845, 4729.43246810385, 4815.662903596714, 4848.908995475989, 4849.4908034655855, 5021.949780502148, 4806.567408933212, 4821.734804585782, 4822.95177048225, 4829.6860704054125, 4842.201036829025, 3613.4941108245393, 3654.812186039601, 3665.780053196817, 3671.1372827386076, 3674.2651469706702, 4986.950130498649, 5015.5987520997505, 5130.820421285386, 5133.459962011456, 5141.897162056813, 4846.321514987834, 4850.902981940412, 4851.878703032311, 4858.562729354375, 4859.125357435554, 4840.34655321527, 4845.733484658236, 4876.390451193601, 4896.853094407174, 4913.877153071202, 4650.953490465052, 4819.783109760139, 4821.902472424506, 4832.057748738962, 4839.274108883696, 4950.925736113889, 4964.602831773522, 4966.61602430828, 4969.130402179677, 4969.900601988013, 4840.709557426566, 4853.883606770392, 4873.46391896884, 4876.4392094909535, 4891.217405215149], "value": 3656.699069080922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.6910283064128, 662.8409181796367, 679.147669674559, 723.75754122369, 733.640516147088, 11486.827697584544, 12237.449314780833, 12257.938710306458, 12275.263484190955, 12306.077044221674, 12108.212966157194, 12232.143749937508, 12261.080418968591, 12294.938525307383, 12354.320512371816, 617.2407938235973, 633.1800318587441, 748.4124961904906, 753.3408267765718, 773.350385676032, 770.7186876789959, 773.8104164479682, 774.4075782026754, 776.0067114093969, 776.0299625468165, 827.9793005174859, 828.353883970504, 829.7801082713086, 830.0827585518006, 830.2154464031493, 650.9381608747161, 667.8897981832991, 823.1398188892622, 823.197382552013, 823.5182418285281, 580.1095318685839, 592.9169916211733, 599.4964230046781, 600.1208229943154, 600.7206648908278, 12028.45871935765, 12087.576934807328, 12112.939435302855, 12118.636440906786, 12120.81818772719, 10550.947245263802, 10576.36541807494, 10983.61557345489, 11063.557457701681, 11517.539298428053, 10039.096481316716, 10084.94957525215, 10123.1901447884, 10312.484375781256, 10388.688339349832, 3843.26977874203, 3915.138669492707, 3920.2355540669582, 3946.1515774108416, 3995.260876737813, 44690.0, 45738.313925290815, 46032.079358412644, 46177.53822461766, 46774.29838552391, 8973.237274831712, 8986.640534378552, 8993.865092023625, 9019.143181397756, 9055.094490551024, 15502.5349239523, 15601.297941592655, 15695.293711782997, 16043.759343609856, 16190.352385904433, 6009.278886533644, 6014.0377539593455, 6059.121427393004, 6104.328523862371, 6133.355997620275, 2808.9297767555568, 2974.7768917331287, 3239.093053944899, 3351.8324083795715, 3398.575178102737, 2412.2642594008644, 2455.693038370204, 2476.702795664532, 2492.4641202141624, 2496.975030249674, 417.25103439326483, 421.9746815191098, 422.3284976886703, 433.23317727621935, 433.7505934087912, 428.38954489851835, 494.0021157262382, 502.6732623794563, 528.1559543649506, 528.7303475227585, 7004.0, 7159.964200178966, 7164.319389657974, 7166.928330716626, 7192.4246060315045, 523.4608353395932, 529.5445916511796, 531.2031952071892, 543.1906459375502, 548.0737553534514, 3284.556584861013, 3292.193412613925, 3359.7480188985924, 3392.118049307198, 3478.3391155680847, 38790.22419551649, 39819.40270895939, 48553.27170092452, 67923.0, 71076.60310572073, 37482.43776343357, 38072.0, 39160.80419597884, 48317.516824832004, 63904.360956390774, 44433.115416142304, 45169.0, 46105.0, 52817.0, 53784.326858673936, 7718.575478348745, 7956.164602716632, 8225.0, 8272.0, 8344.249017588447, 34634.0, 36351.81824090863, 36870.63129368672, 37334.25331493409, 37911.0, 43139.784301078296, 43839.0, 49585.0, 49820.0, 50655.0, 10065.0, 10319.0, 10453.738656533647, 10454.32046916948, 10498.055175034284, 11290.056944368407, 11301.943490282496, 11399.259048161843, 11695.415229238673, 11813.173077884465, 11186.32882027081, 11242.0, 11524.59663911759, 11833.52665893389, 11933.403329833647, 11461.197716160123, 11678.54295519003, 11798.11514136426, 11853.966796114539, 12199.51201951912, 10041.598336066767, 10284.125849302893, 10287.074163325336, 10343.172546196436, 10682.03861652424, 8525.701600444203, 8559.186877246651, 8810.559472026245, 8968.641254349754, 9292.25661947029, 8436.493810371398, 8981.550922453982, 8999.325050621099, 9079.68221112258, 9273.211776999031], "value": 5735.490120550209, "unit": "score"}], "name": "dromaeo_css", "value": 6132.416622631434, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 118.0, 119.0, 117.0, 118.0, 118.0, 117.0, 118.0, 119.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 138.0, 136.0, 183.0, 136.0, 136.0, 138.0, 130.0, 165.0, 131.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 161.0, 162.0, 160.0, 164.0, 164.0, 163.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 108.0, 78.0, 79.0, 78.0, 78.0, 99.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 121.0, 84.0, 84.0, 84.0, 105.0, 85.0, 83.0, 116.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 120.0, 118.0, 119.0, 117.0, 117.0, 117.0, 117.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 146.0, 147.0, 146.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 68.0, 66.0, 67.0, 71.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 89.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 143.0, 145.0, 154.0, 151.0, 143.0, 141.0, 143.0, 204.0, 158.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 181.0, 183.0, 182.0, 178.0, 179.0, 178.0, 179.0, 180.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 70.0, 70.0, 71.0, 71.0, 70.0, 71.0, 69.0, 72.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1581.2000000000003, "alertThreshold": 2.0}]} 14:49:55 INFO - Return code: 0 14:49:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:49:55 INFO - # TBPL SUCCESS # 14:49:55 INFO - Running post-action listener: _resource_record_post_action 14:49:55 INFO - [mozharness: 2016-11-16 22:49:55.738575Z] Finished run-tests step (success) 14:49:55 INFO - Running post-run listener: _resource_record_post_run 14:49:55 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 394072064; Read time: 20; Write time: 296820 14:49:55 INFO - TinderboxPrint: CPU usage
13.1% 14:49:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 14:49:55 INFO - TinderboxPrint: I/O write bytes / time
394,072,064 / 296,820 14:49:55 INFO - TinderboxPrint: CPU idle
8,346.5 (86.9%) 14:49:55 INFO - TinderboxPrint: CPU user
1,210.7 (12.6%) 14:49:55 INFO - TinderboxPrint: Swap in / out
0 / 0 14:49:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99176448; Read time: 0; Write time: 81952 14:49:55 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294895616; Read time: 20; Write time: 214868 14:49:56 INFO - Running post-run listener: _upload_blobber_files 14:49:56 INFO - Blob upload gear active. 14:49:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:49:56 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 14:49:56 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:49:56 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:49:56 INFO - (blobuploader) - INFO - Open directory for files ... 14:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:00 INFO - (blobuploader) - INFO - Done attempting. 14:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:01 INFO - (blobuploader) - INFO - Done attempting. 14:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:02 INFO - (blobuploader) - INFO - Done attempting. 14:50:02 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:02 INFO - Return code: 0 14:50:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/77e5e6717bf46240b961094519d6b47f0f3532fdcf07bcb2ac6f33b3ecb7ce943187ff09eddda80fca28d437df3c2897d1580a0960ca2eddd83fed931d908287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c7895f683a422e809fad03a0d95c711464a79f3b625dd745f7fdb808629c53b352c7ac7769353075579ef8eb2efd940cf6d5ff2ace117e2bbea7501f018cb548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0c6c57db3cfe12c585f36a18666b2e606ca6784e4847c3afce6d11449f921990eeae7578664d3eaf5a809e315eb1af3f610bd9fbc7635ea9d281853d4f289a00"} 14:50:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:02 INFO - Contents: 14:50:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/77e5e6717bf46240b961094519d6b47f0f3532fdcf07bcb2ac6f33b3ecb7ce943187ff09eddda80fca28d437df3c2897d1580a0960ca2eddd83fed931d908287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c7895f683a422e809fad03a0d95c711464a79f3b625dd745f7fdb808629c53b352c7ac7769353075579ef8eb2efd940cf6d5ff2ace117e2bbea7501f018cb548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0c6c57db3cfe12c585f36a18666b2e606ca6784e4847c3afce6d11449f921990eeae7578664d3eaf5a809e315eb1af3f610bd9fbc7635ea9d281853d4f289a00"} 14:50:02 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:02 INFO - Copying logs to upload dir... 14:50:02 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.075779 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-11-16 14:50:01.938363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 14:50:01.942435) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/77e5e6717bf46240b961094519d6b47f0f3532fdcf07bcb2ac6f33b3ecb7ce943187ff09eddda80fca28d437df3c2897d1580a0960ca2eddd83fed931d908287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c7895f683a422e809fad03a0d95c711464a79f3b625dd745f7fdb808629c53b352c7ac7769353075579ef8eb2efd940cf6d5ff2ace117e2bbea7501f018cb548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0c6c57db3cfe12c585f36a18666b2e606ca6784e4847c3afce6d11449f921990eeae7578664d3eaf5a809e315eb1af3f610bd9fbc7635ea9d281853d4f289a00"} build_url:https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011678 build_url: 'https://queue.taskcluster.net/v1/task/KKFRmmG5SICx_eYszQtDvA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/77e5e6717bf46240b961094519d6b47f0f3532fdcf07bcb2ac6f33b3ecb7ce943187ff09eddda80fca28d437df3c2897d1580a0960ca2eddd83fed931d908287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c7895f683a422e809fad03a0d95c711464a79f3b625dd745f7fdb808629c53b352c7ac7769353075579ef8eb2efd940cf6d5ff2ace117e2bbea7501f018cb548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0c6c57db3cfe12c585f36a18666b2e606ca6784e4847c3afce6d11449f921990eeae7578664d3eaf5a809e315eb1af3f610bd9fbc7635ea9d281853d4f289a00"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 14:50:01.972107) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:50:01.972446) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1479335352.438298-2087100655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 14:50:02.055408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 14:50:02.055735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 14:50:02.056051) ========= ========= Total master_lag: 0.19 =========